-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2023-05-25 19:53:05 UTC. -- May 25 19:52:00 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:00 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 574. May 25 19:52:00 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:00 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:00 volumio sudo[17675]: pam_unix(sudo:session): session closed for user root May 25 19:52:00 volumio sudo[17710]: pam_unix(sudo:session): session closed for user root May 25 19:52:00 volumio volumio[17712]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:00 volumio volumio[17712]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:00 volumio volumio[17712]: Malformed config key: missing field `Authentication` May 25 19:52:00 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:00 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:00 volumio volumio-remote-updater[460]: [2023-05-25 19:52:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 19:52:00 volumio volumio-remote-updater[460]: [2023-05-25 19:52:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 19:52:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 19:52:00 volumio systemd[1]: Started dynamicswap service. May 25 19:52:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 19:52:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 85. May 25 19:52:00 volumio systemd[1]: Started dynamicswap service. May 25 19:52:00 volumio systemd[1]: Stopped Volumio Backend Module. May 25 19:52:00 volumio systemd[1]: Started Volumio Backend Module. May 25 19:52:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:01 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:02 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:02 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 575. May 25 19:52:02 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:02 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:02 volumio volumio[17746]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:02 volumio volumio[17746]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:02 volumio volumio[17746]: Malformed config key: missing field `Authentication` May 25 19:52:02 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:02 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:02 volumio volumio[17726]: info: ------------------------------------------- May 25 19:52:02 volumio volumio[17726]: info: ----- Volumio3 ---- May 25 19:52:02 volumio volumio[17726]: info: ------------------------------------------- May 25 19:52:02 volumio volumio[17726]: info: ----- System startup ---- May 25 19:52:02 volumio volumio[17726]: info: ------------------------------------------- May 25 19:52:03 volumio volumio[17726]: info: MYVOLUMIO Environment detected May 25 19:52:03 volumio volumio[17726]: info: Plugin folders cleanup May 25 19:52:03 volumio volumio[17726]: info: Scanning into folder /volumio/app/plugins/ May 25 19:52:03 volumio volumio[17726]: info: Scanning category audio_interface May 25 19:52:03 volumio volumio[17726]: info: Scanning category miscellanea May 25 19:52:03 volumio volumio[17726]: info: Scanning category music_service May 25 19:52:03 volumio volumio[17726]: info: Scanning category plugins.json May 25 19:52:03 volumio volumio[17726]: info: Scanning category system_controller May 25 19:52:03 volumio volumio[17726]: info: Scanning category user_interface May 25 19:52:03 volumio volumio[17726]: info: Scanning into folder /data/plugins/ May 25 19:52:03 volumio volumio[17726]: info: Scanning category music_service May 25 19:52:03 volumio volumio[17726]: info: Scanning category user_interface May 25 19:52:03 volumio volumio[17726]: info: Plugin folders cleanup completed May 25 19:52:03 volumio volumio[17726]: info: ------------------------------------------- May 25 19:52:03 volumio volumio[17726]: info: ----- Core plugins startup ---- May 25 19:52:03 volumio volumio[17726]: info: ------------------------------------------- May 25 19:52:03 volumio volumio[17726]: info: Loading plugins from folder /volumio/app/plugins/ May 25 19:52:03 volumio volumio[17726]: info: Adding plugin upnp to MyMusic Plugins May 25 19:52:03 volumio volumio[17726]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 19:52:03 volumio volumio[17726]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 19:52:03 volumio volumio[17726]: info: Loading plugins from folder /data/plugins/ May 25 19:52:03 volumio volumio[17726]: info: Loading plugin "system"... May 25 19:52:03 volumio volumio[17726]: info: Loading plugin "appearance"... May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "network"... May 25 19:52:04 volumio volumio[17726]: info: Refreshing Cached IP Addresses May 25 19:52:04 volumio sudo[17751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:04 volumio sudo[17751]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:04 volumio sudo[17751]: pam_unix(sudo:session): session closed for user root May 25 19:52:04 volumio sudo[17753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "services"... May 25 19:52:04 volumio sudo[17753]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "alsa_controller"... May 25 19:52:04 volumio sudo[17753]: pam_unix(sudo:session): session closed for user root May 25 19:52:04 volumio sudo[17756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 19:52:04 volumio sudo[17756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "wizard"... May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "volumio_command_line_client"... May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "upnp"... May 25 19:52:04 volumio volumio[17726]: info: [1685044324578] Starting Upmpd Daemon May 25 19:52:04 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "my_music"... May 25 19:52:04 volumio volumio[17726]: info: Loading plugin "mpd"... May 25 19:52:04 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:04 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 576. May 25 19:52:04 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:04 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:04 volumio volumio[17761]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:04 volumio volumio[17761]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:04 volumio volumio[17761]: Malformed config key: missing field `Authentication` May 25 19:52:04 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:04 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:04 volumio volumio[17726]: info: Creating MPD Configuration file May 25 19:52:05 volumio sudo[17764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:05 volumio volumio[17726]: info: Loading plugin "upnp_browser"... May 25 19:52:05 volumio sudo[17764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:05 volumio sudo[17764]: pam_unix(sudo:session): session closed for user root May 25 19:52:05 volumio sudo[17766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:05 volumio sudo[17766]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:05 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:05 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:05 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:05 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:05 volumio volumio-remote-updater[460]: [2023-05-25 19:52:05] [connect] Successful connection May 25 19:52:05 volumio volumio[17726]: info: Loading plugin "networkfs"... May 25 19:52:05 volumio volumio[17726]: info: Starting Udev Watcher for removable devices May 25 19:52:05 volumio volumio[17726]: info: Ignoring mount for partition: boot May 25 19:52:05 volumio volumio[17726]: info: Ignoring mount for partition: volumio May 25 19:52:05 volumio volumio[17726]: info: Ignoring mount for partition: volumio_data May 25 19:52:05 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:05 volumio volumio[17726]: info: Loading plugin "alarm-clock"... May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "airplay_emulation"... May 25 19:52:06 volumio volumio[17726]: info: Starting Shairport Sync May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "last_100"... May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "webradio"... May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "i2s_dacs"... May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "volumiodiscovery"... May 25 19:52:06 volumio volumio[17726]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:06 volumio volumio[17726]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:06 volumio volumio[17726]: *** WARNING *** For more information see May 25 19:52:06 volumio node[17726]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:06 volumio volumio[17726]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:06 volumio volumio[17726]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:06 volumio volumio[17726]: *** WARNING *** For more information see May 25 19:52:06 volumio node[17726]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:06 volumio node[17726]: *** WARNING *** For more information see May 25 19:52:06 volumio node[17726]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:06 volumio node[17726]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:06 volumio node[17726]: *** WARNING *** For more information see May 25 19:52:06 volumio volumio[17726]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 19:52:06 volumio volumio[17726]: info: Discovery: Started advertising with name: Volumio May 25 19:52:06 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:06 volumio volumio[17726]: info: Loading plugin "spop"... May 25 19:52:06 volumio mpd[17771]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:06 volumio mpd[17771]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:06 volumio mpd[17771]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:06 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:06 volumio sudo[17766]: pam_unix(sudo:session): session closed for user root May 25 19:52:06 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:06 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 577. May 25 19:52:06 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:06 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:06 volumio volumio[17777]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:06 volumio volumio[17777]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:06 volumio volumio[17777]: Malformed config key: missing field `Authentication` May 25 19:52:06 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:06 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:07 volumio volumio[17726]: info: Loading plugin "youtube2"... May 25 19:52:09 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:09 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 578. May 25 19:52:09 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:09 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:09 volumio volumio[17779]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:09 volumio volumio[17779]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:09 volumio volumio[17779]: Malformed config key: missing field `Authentication` May 25 19:52:09 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:09 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:10 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:10 volumio sudo[17756]: pam_unix(sudo:session): session closed for user root May 25 19:52:11 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:11 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 579. May 25 19:52:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:11 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:11 volumio volumio[17781]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:11 volumio volumio[17781]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:11 volumio volumio[17781]: Malformed config key: missing field `Authentication` May 25 19:52:11 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:11 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:11 volumio volumio[17726]: info: Loading plugin "ytmusic"... May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "outputs"... May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "albumart"... May 25 19:52:12 volumio volumio[17726]: info: Plugin example_plugin is not enabled May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "inputs"... May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "updater_comm"... May 25 19:52:12 volumio volumio[17726]: info: Plugin mpdemulation is not enabled May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "rest_api"... May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "websocket"... May 25 19:52:12 volumio volumio[17726]: info: Loading plugin "touch_display"... May 25 19:52:13 volumio volumio[17726]: Forking 3 albumart workers May 25 19:52:13 volumio volumio[17726]: info: Applying required configuration parameters for plugin touch_display May 25 19:52:13 volumio volumio[17726]: info: Loading i18n strings for locale en May 25 19:52:13 volumio volumio[17726]: Updating browse sources language May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::initPlayerControls May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:13 volumio volumio[17726]: Express server listening on port 3000 May 25 19:52:13 volumio volumio[17726]: [Metrics] WebUI: 11s 552.29ms May 25 19:52:13 volumio volumio[17726]: info: CoreStateMachine::resetVolumioState May 25 19:52:13 volumio volumio[17726]: info: CoreStateMachine::getcurrentVolume May 25 19:52:13 volumio volumio[17726]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:13 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:13 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 580. May 25 19:52:13 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:13 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:13 volumio volumio[17815]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:13 volumio volumio[17815]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:13 volumio volumio[17815]: Malformed config key: missing field `Authentication` May 25 19:52:13 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:13 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:13 volumio volumio[17726]: info: MPD Permissions set May 25 19:52:13 volumio volumio[17726]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:14 volumio volumio[17726]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 25 19:52:14 volumio volumio[17726]: info: MPD running with PID17771 May 25 19:52:14 volumio volumio[17726]: ,establishing connection May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: Reloading queue from file May 25 19:52:14 volumio volumio[17726]: error: updateQueue error: null May 25 19:52:14 volumio volumio[17726]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 19:52:14 volumio volumio[17726]: Starting albumart workers May 25 19:52:14 volumio volumio[17726]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::pushState May 25 19:52:14 volumio volumio[17726]: info: CorePlayQueue::getTrack 0 May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioPushState May 25 19:52:14 volumio volumio[17726]: Starting albumart workers May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::updateTrackBlock May 25 19:52:14 volumio volumio[17726]: info: CorePlayQueue::getTrackBlock May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::setRepeat true single undefined May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::pushState May 25 19:52:14 volumio volumio[17726]: info: CorePlayQueue::getTrack 0 May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioPushState May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::setRandom false May 25 19:52:14 volumio volumio[17726]: Starting albumart workers May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::pushState May 25 19:52:14 volumio volumio[17726]: info: CorePlayQueue::getTrack 0 May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioPushState May 25 19:52:14 volumio volumio[17726]: info: Setting Device type: Raspberry PI May 25 19:52:14 volumio volumio[17726]: info: Completed loading Core Plugins May 25 19:52:14 volumio volumio[17726]: info: Preparing to generate the ALSA configuration file May 25 19:52:14 volumio volumio[17726]: error: updateQueue error: null May 25 19:52:14 volumio volumio[17726]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:14 volumio volumio[17726]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:14 volumio volumio[17726]: info: CoreStateMachine::pushState May 25 19:52:14 volumio volumio[17726]: info: CorePlayQueue::getTrack 0 May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioPushState May 25 19:52:14 volumio volumio[17726]: info: Asound.conf file unchanged, so no further update is needed May 25 19:52:14 volumio volumio[17726]: info: Output device has changed, restarting MPD May 25 19:52:14 volumio volumio[17726]: info: Output device has changed, restarting Shairport Sync May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:14 volumio sudo[17832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:14 volumio sudo[17832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:14 volumio sudo[17832]: pam_unix(sudo:session): session closed for user root May 25 19:52:14 volumio sudo[17833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:14 volumio sudo[17833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:14 volumio volumio[17726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:14 volumio volumio[17726]: info: ___________ START PLUGINS ___________ May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:14 volumio volumio[17726]: info: [1685044334728] CoreMusicLibrary::Adding element Media Servers May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:14 volumio volumio[17726]: Cannot find translation for sourceMedia Servers May 25 19:52:14 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:14 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:14 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:14 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:14 volumio volumio[17726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:14 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:15 volumio volumio[17726]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:15 volumio volumio[17726]: info: [1685044335088] CoreMusicLibrary::Adding element Last_100 May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:15 volumio volumio[17726]: Cannot find translation for sourceMedia Servers May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:15 volumio volumio[17726]: info: [1685044335096] CoreMusicLibrary::Adding element Webradio May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:15 volumio volumio[17726]: Cannot find translation for sourceMedia Servers May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:15 volumio volumio[17726]: info: Checking for old spotify connect plugin installed May 25 19:52:15 volumio volumio[17726]: info: [youtube2-auth] Starting auth flow... May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:15 volumio volumio[17726]: info: [1685044335224] CoreMusicLibrary::Adding element YouTube2 May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:15 volumio volumio[17726]: Cannot find translation for sourceMedia Servers May 25 19:52:15 volumio volumio[17726]: Cannot find translation for sourceYouTube2 May 25 19:52:15 volumio volumio[17726]: info: Loading i18n strings for locale en May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:15 volumio volumio[17726]: info: Volumio Calling Home May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:15 volumio volumio[17726]: [SpotifyConnect] Creating VLS config file May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:15 volumio volumio[17726]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:15 volumio volumio[17726]: [SpotifyConnect] Starting metadata listener May 25 19:52:15 volumio sudo[17876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 25 19:52:15 volumio sudo[17876]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:15 volumio sudo[17879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 25 19:52:15 volumio sudo[17879]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:15 volumio systemd[1]: Reloading. May 25 19:52:15 volumio sudo[17882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:15 volumio sudo[17882]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:15 volumio volumio[17726]: Unhandled rejection Error: No sockets available, cannot start. May 25 19:52:15 volumio volumio[17726]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 25 19:52:15 volumio volumio[17726]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 25 19:52:15 volumio volumio[17726]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 25 19:52:15 volumio volumio[17726]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 25 19:52:15 volumio volumio[17726]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 25 19:52:15 volumio volumio[17726]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 25 19:52:15 volumio volumio[17726]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 25 19:52:15 volumio volumio[17726]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 25 19:52:15 volumio volumio[17726]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 25 19:52:15 volumio volumio[17726]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 25 19:52:15 volumio volumio[17726]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 25 19:52:15 volumio volumio[17726]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 25 19:52:15 volumio volumio[17726]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 25 19:52:15 volumio volumio[17726]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 25 19:52:15 volumio volumio[17726]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 25 19:52:15 volumio volumio[17726]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 25 19:52:15 volumio sudo[17882]: pam_unix(sudo:session): session closed for user root May 25 19:52:15 volumio volumio[17726]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:15 volumio volumio[17726]: ReferenceError: deferred is not defined May 25 19:52:15 volumio volumio[17726]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) May 25 19:52:15 volumio volumio[17726]: at ClientRequest.emit (events.js:315:20) May 25 19:52:15 volumio volumio[17726]: at TLSSocket.socketErrorListener (_http_client.js:469:9) May 25 19:52:15 volumio volumio[17726]: at TLSSocket.emit (events.js:315:20) May 25 19:52:15 volumio volumio[17726]: at emitErrorNT (internal/streams/destroy.js:106:8) May 25 19:52:15 volumio volumio[17726]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 25 19:52:15 volumio volumio[17726]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 25 19:52:15 volumio volumio[17726]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:15 volumio sudo[17885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:15 volumio sudo[17885]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:15 volumio sudo[17885]: pam_unix(sudo:session): session closed for user root May 25 19:52:16 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 25 19:52:16 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:16 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 581. May 25 19:52:16 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:16 volumio sudo[17879]: pam_unix(sudo:session): session closed for user root May 25 19:52:16 volumio mpd[17871]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:16 volumio mpd[17871]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:16 volumio mpd[17871]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:16 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:16 volumio sudo[17876]: pam_unix(sudo:session): session closed for user root May 25 19:52:16 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:16 volumio volumio[17908]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:16 volumio volumio[17908]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:16 volumio volumio[17908]: Malformed config key: missing field `Authentication` May 25 19:52:16 volumio sudo[17833]: pam_unix(sudo:session): session closed for user root May 25 19:52:16 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:16 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:16 volumio sudo[17913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-25 19:51 May 25 19:52:16 volumio sudo[17913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:16 volumio sudo[17913]: pam_unix(sudo:session): session closed for user root May 25 19:52:16 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:16 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 19:52:16 volumio systemd[1]: Started dynamicswap service. May 25 19:52:16 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 19:52:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 86. May 25 19:52:17 volumio systemd[1]: Started dynamicswap service. May 25 19:52:17 volumio systemd[1]: Stopped Volumio Backend Module. May 25 19:52:17 volumio systemd[1]: Started Volumio Backend Module. May 25 19:52:17 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:17 volumio volumio-remote-updater[460]: [2023-05-25 19:52:17] [info] asio async_connect error: system:111 (Connection refused) May 25 19:52:17 volumio volumio-remote-updater[460]: [2023-05-25 19:52:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 19:52:17 volumio volumio-remote-updater[460]: [2023-05-25 19:52:17] [error] handle_connect error: Underlying Transport Error May 25 19:52:17 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:18 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:18 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 582. May 25 19:52:18 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:18 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:18 volumio volumio[17939]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:18 volumio volumio[17939]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:18 volumio volumio[17939]: Malformed config key: missing field `Authentication` May 25 19:52:18 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:18 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:19 volumio volumio[17927]: info: ------------------------------------------- May 25 19:52:19 volumio volumio[17927]: info: ----- Volumio3 ---- May 25 19:52:19 volumio volumio[17927]: info: ------------------------------------------- May 25 19:52:19 volumio volumio[17927]: info: ----- System startup ---- May 25 19:52:19 volumio volumio[17927]: info: ------------------------------------------- May 25 19:52:19 volumio volumio[17927]: info: MYVOLUMIO Environment detected May 25 19:52:19 volumio volumio[17927]: info: Plugin folders cleanup May 25 19:52:19 volumio volumio[17927]: info: Scanning into folder /volumio/app/plugins/ May 25 19:52:19 volumio volumio[17927]: info: Scanning category audio_interface May 25 19:52:19 volumio volumio[17927]: info: Scanning category miscellanea May 25 19:52:19 volumio volumio[17927]: info: Scanning category music_service May 25 19:52:19 volumio volumio[17927]: info: Scanning category plugins.json May 25 19:52:19 volumio volumio[17927]: info: Scanning category system_controller May 25 19:52:19 volumio volumio[17927]: info: Scanning category user_interface May 25 19:52:19 volumio volumio[17927]: info: Scanning into folder /data/plugins/ May 25 19:52:19 volumio volumio[17927]: info: Scanning category music_service May 25 19:52:19 volumio volumio[17927]: info: Scanning category user_interface May 25 19:52:19 volumio volumio[17927]: info: Plugin folders cleanup completed May 25 19:52:19 volumio volumio[17927]: info: ------------------------------------------- May 25 19:52:19 volumio volumio[17927]: info: ----- Core plugins startup ---- May 25 19:52:19 volumio volumio[17927]: info: ------------------------------------------- May 25 19:52:19 volumio volumio[17927]: info: Loading plugins from folder /volumio/app/plugins/ May 25 19:52:19 volumio volumio[17927]: info: Adding plugin upnp to MyMusic Plugins May 25 19:52:19 volumio volumio[17927]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 19:52:19 volumio volumio[17927]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 19:52:19 volumio volumio[17927]: info: Loading plugins from folder /data/plugins/ May 25 19:52:19 volumio volumio[17927]: info: Loading plugin "system"... May 25 19:52:19 volumio volumio[17927]: info: Loading plugin "appearance"... May 25 19:52:20 volumio volumio[17927]: info: Loading plugin "network"... May 25 19:52:20 volumio volumio[17927]: info: Refreshing Cached IP Addresses May 25 19:52:20 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:20 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 583. May 25 19:52:20 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:20 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:20 volumio volumio[17952]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:20 volumio volumio[17952]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:20 volumio volumio[17952]: Malformed config key: missing field `Authentication` May 25 19:52:20 volumio sudo[17953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:20 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:20 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:20 volumio sudo[17953]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:20 volumio sudo[17953]: pam_unix(sudo:session): session closed for user root May 25 19:52:20 volumio volumio[17927]: info: Loading plugin "services"... May 25 19:52:20 volumio volumio[17927]: info: Loading plugin "alsa_controller"... May 25 19:52:20 volumio sudo[17956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:20 volumio sudo[17956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:20 volumio sudo[17956]: pam_unix(sudo:session): session closed for user root May 25 19:52:20 volumio sudo[17959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 19:52:20 volumio sudo[17959]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "wizard"... May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "volumio_command_line_client"... May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "upnp"... May 25 19:52:21 volumio volumio[17927]: info: [1685044341070] Starting Upmpd Daemon May 25 19:52:21 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "my_music"... May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "mpd"... May 25 19:52:21 volumio volumio[17927]: info: Creating MPD Configuration file May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "upnp_browser"... May 25 19:52:21 volumio sudo[17965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:21 volumio sudo[17965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:21 volumio sudo[17965]: pam_unix(sudo:session): session closed for user root May 25 19:52:21 volumio sudo[17967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:21 volumio sudo[17967]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:21 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:21 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:21 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:21 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:21 volumio volumio[17927]: info: Loading plugin "networkfs"... May 25 19:52:22 volumio volumio[17927]: info: Starting Udev Watcher for removable devices May 25 19:52:22 volumio volumio[17927]: info: Ignoring mount for partition: boot May 25 19:52:22 volumio volumio[17927]: info: Ignoring mount for partition: volumio May 25 19:52:22 volumio volumio[17927]: info: Ignoring mount for partition: volumio_data May 25 19:52:22 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "alarm-clock"... May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "airplay_emulation"... May 25 19:52:22 volumio volumio[17927]: info: Starting Shairport Sync May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "last_100"... May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "webradio"... May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "i2s_dacs"... May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "volumiodiscovery"... May 25 19:52:22 volumio volumio[17927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:22 volumio volumio[17927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:22 volumio volumio[17927]: *** WARNING *** For more information see May 25 19:52:22 volumio node[17927]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:22 volumio volumio[17927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:22 volumio volumio[17927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:22 volumio volumio[17927]: *** WARNING *** For more information see May 25 19:52:22 volumio node[17927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:22 volumio node[17927]: *** WARNING *** For more information see May 25 19:52:22 volumio node[17927]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:22 volumio node[17927]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:22 volumio node[17927]: *** WARNING *** For more information see May 25 19:52:22 volumio volumio[17927]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 19:52:22 volumio volumio[17927]: info: Discovery: Started advertising with name: Volumio May 25 19:52:22 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:22 volumio volumio[17927]: info: Loading plugin "spop"... May 25 19:52:22 volumio mpd[17972]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:22 volumio mpd[17972]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:22 volumio mpd[17972]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:23 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:23 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 584. May 25 19:52:23 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:23 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:23 volumio sudo[17967]: pam_unix(sudo:session): session closed for user root May 25 19:52:23 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:23 volumio volumio[17978]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:23 volumio volumio[17978]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:23 volumio volumio[17978]: Malformed config key: missing field `Authentication` May 25 19:52:23 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:23 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:23 volumio volumio[17927]: info: Loading plugin "youtube2"... May 25 19:52:25 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:25 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 585. May 25 19:52:25 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:25 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:25 volumio volumio[17980]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:25 volumio volumio[17980]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:25 volumio volumio[17980]: Malformed config key: missing field `Authentication` May 25 19:52:25 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:25 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:26 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:26 volumio sudo[17959]: pam_unix(sudo:session): session closed for user root May 25 19:52:27 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:27 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 586. May 25 19:52:27 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:27 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:27 volumio volumio[17982]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:27 volumio volumio[17982]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:27 volumio volumio[17982]: Malformed config key: missing field `Authentication` May 25 19:52:27 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:27 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:27 volumio volumio-remote-updater[460]: [2023-05-25 19:52:27] [connect] Successful connection May 25 19:52:27 volumio volumio[17927]: info: Loading plugin "ytmusic"... May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "outputs"... May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "albumart"... May 25 19:52:29 volumio volumio[17927]: info: Plugin example_plugin is not enabled May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "inputs"... May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "updater_comm"... May 25 19:52:29 volumio volumio[17927]: info: Plugin mpdemulation is not enabled May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "rest_api"... May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "websocket"... May 25 19:52:29 volumio volumio[17927]: info: Loading plugin "touch_display"... May 25 19:52:29 volumio volumio[17927]: Forking 3 albumart workers May 25 19:52:29 volumio volumio[17927]: info: Applying required configuration parameters for plugin touch_display May 25 19:52:29 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:29 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 587. May 25 19:52:29 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:29 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:29 volumio volumio[18012]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:29 volumio volumio[18012]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:29 volumio volumio[18012]: Malformed config key: missing field `Authentication` May 25 19:52:29 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:29 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:29 volumio volumio[17927]: info: Loading i18n strings for locale en May 25 19:52:29 volumio volumio[17927]: Updating browse sources language May 25 19:52:29 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:29 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:29 volumio volumio[17927]: info: CoreCommandRouter::initPlayerControls May 25 19:52:29 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:29 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:30 volumio volumio[17927]: Express server listening on port 3000 May 25 19:52:30 volumio volumio[17927]: [Metrics] WebUI: 11s 574.82ms May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::resetVolumioState May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::getcurrentVolume May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:30 volumio volumio[17927]: info: MPD Permissions set May 25 19:52:30 volumio volumio-remote-updater[460]: [2023-05-25 19:52:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1685044347 101 May 25 19:52:30 volumio volumio[17927]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 25 19:52:30 volumio volumio[17927]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:30 volumio volumio[17927]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 25 19:52:30 volumio volumio[17927]: info: MPD running with PID17972 May 25 19:52:30 volumio volumio[17927]: ,establishing connection May 25 19:52:30 volumio volumio[17927]: Starting albumart workers May 25 19:52:30 volumio volumio[17927]: Starting albumart workers May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: Starting albumart workers May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:30 volumio volumio[17927]: info: Reloading queue from file May 25 19:52:30 volumio volumio[17927]: error: updateQueue error: null May 25 19:52:30 volumio volumio[17927]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 19:52:30 volumio volumio[17927]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::pushState May 25 19:52:30 volumio volumio[17927]: info: CorePlayQueue::getTrack 0 May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::volumioPushState May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::updateTrackBlock May 25 19:52:30 volumio volumio[17927]: info: CorePlayQueue::getTrackBlock May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::setRepeat true single undefined May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::pushState May 25 19:52:30 volumio volumio[17927]: info: CorePlayQueue::getTrack 0 May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::volumioPushState May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::setRandom false May 25 19:52:30 volumio volumio[17927]: info: CoreStateMachine::pushState May 25 19:52:30 volumio volumio[17927]: info: CorePlayQueue::getTrack 0 May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:30 volumio volumio[17927]: info: CoreCommandRouter::volumioPushState May 25 19:52:30 volumio volumio[17927]: info: Setting Device type: Raspberry PI May 25 19:52:30 volumio volumio[17927]: info: Completed loading Core Plugins May 25 19:52:30 volumio volumio[17927]: info: Preparing to generate the ALSA configuration file May 25 19:52:30 volumio volumio[17927]: error: updateQueue error: null May 25 19:52:30 volumio volumio[17927]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:31 volumio volumio[17927]: info: Asound.conf file unchanged, so no further update is needed May 25 19:52:31 volumio volumio[17927]: info: Output device has changed, restarting MPD May 25 19:52:31 volumio volumio[17927]: info: Output device has changed, restarting Shairport Sync May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:31 volumio sudo[18035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:31 volumio sudo[18035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:31 volumio sudo[18032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:31 volumio sudo[18032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:31 volumio sudo[18032]: pam_unix(sudo:session): session closed for user root May 25 19:52:31 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:31 volumio volumio[17927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:31 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:31 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:31 volumio volumio[17927]: info: ___________ START PLUGINS ___________ May 25 19:52:31 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:31 volumio volumio[17927]: info: [1685044351319] CoreMusicLibrary::Adding element Media Servers May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:31 volumio volumio[17927]: Cannot find translation for sourceMedia Servers May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:31 volumio volumio[17927]: info: [1685044351517] CoreMusicLibrary::Adding element Last_100 May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:31 volumio volumio[17927]: Cannot find translation for sourceMedia Servers May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:31 volumio volumio[17927]: info: [1685044351524] CoreMusicLibrary::Adding element Webradio May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:31 volumio volumio[17927]: Cannot find translation for sourceMedia Servers May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:31 volumio volumio[17927]: info: Checking for old spotify connect plugin installed May 25 19:52:31 volumio volumio[17927]: info: [youtube2-auth] Starting auth flow... May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:31 volumio volumio[17927]: info: [1685044351644] CoreMusicLibrary::Adding element YouTube2 May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:31 volumio volumio[17927]: Cannot find translation for sourceMedia Servers May 25 19:52:31 volumio volumio[17927]: Cannot find translation for sourceYouTube2 May 25 19:52:31 volumio volumio[17927]: info: Loading i18n strings for locale en May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:31 volumio volumio[17927]: info: Volumio Calling Home May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:31 volumio volumio[17927]: [SpotifyConnect] Creating VLS config file May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:31 volumio volumio[17927]: [SpotifyConnect] Starting metadata listener May 25 19:52:31 volumio sudo[18077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 25 19:52:31 volumio sudo[18077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:31 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:31 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 588. May 25 19:52:31 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:31 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:31 volumio volumio[18081]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:31 volumio sudo[18080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 25 19:52:31 volumio sudo[18080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:31 volumio volumio[18081]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:31 volumio volumio[18081]: Malformed config key: missing field `Authentication` May 25 19:52:31 volumio sudo[18077]: pam_unix(sudo:session): session closed for user root May 25 19:52:31 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:31 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:32 volumio systemd[1]: Reloading. May 25 19:52:32 volumio volumio[17927]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:32 volumio volumio[17927]: info: CoreStateMachine::pushState May 25 19:52:32 volumio volumio[17927]: info: CorePlayQueue::getTrack 0 May 25 19:52:32 volumio volumio[17927]: info: CoreCommandRouter::volumioPushState May 25 19:52:32 volumio volumio[17927]: Unhandled rejection Error: No sockets available, cannot start. May 25 19:52:32 volumio volumio[17927]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 25 19:52:32 volumio volumio[17927]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 25 19:52:32 volumio volumio[17927]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 25 19:52:32 volumio volumio[17927]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 25 19:52:32 volumio volumio[17927]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 25 19:52:32 volumio volumio[17927]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 25 19:52:32 volumio volumio[17927]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 25 19:52:32 volumio volumio[17927]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 25 19:52:32 volumio volumio[17927]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 25 19:52:32 volumio volumio[17927]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 25 19:52:32 volumio volumio[17927]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 25 19:52:32 volumio volumio[17927]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 25 19:52:32 volumio volumio[17927]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 25 19:52:32 volumio volumio[17927]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 25 19:52:32 volumio volumio[17927]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 25 19:52:32 volumio volumio[17927]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 25 19:52:32 volumio sudo[18085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:32 volumio sudo[18085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:32 volumio volumio[17927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:32 volumio volumio[17927]: ReferenceError: deferred is not defined May 25 19:52:32 volumio volumio[17927]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) May 25 19:52:32 volumio volumio[17927]: at ClientRequest.emit (events.js:315:20) May 25 19:52:32 volumio volumio[17927]: at TLSSocket.socketErrorListener (_http_client.js:469:9) May 25 19:52:32 volumio volumio[17927]: at TLSSocket.emit (events.js:315:20) May 25 19:52:32 volumio volumio[17927]: at emitErrorNT (internal/streams/destroy.js:106:8) May 25 19:52:32 volumio volumio[17927]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 25 19:52:32 volumio volumio[17927]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 25 19:52:32 volumio volumio[17927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:32 volumio sudo[18085]: pam_unix(sudo:session): session closed for user root May 25 19:52:32 volumio sudo[18087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:32 volumio sudo[18087]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:32 volumio sudo[18087]: pam_unix(sudo:session): session closed for user root May 25 19:52:32 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 25 19:52:32 volumio sudo[18112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-25 19:51 May 25 19:52:32 volumio sudo[18112]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:32 volumio sudo[18080]: pam_unix(sudo:session): session closed for user root May 25 19:52:33 volumio mpd[18072]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:33 volumio mpd[18072]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:33 volumio mpd[18072]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:33 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:33 volumio sudo[18035]: pam_unix(sudo:session): session closed for user root May 25 19:52:33 volumio sudo[18112]: pam_unix(sudo:session): session closed for user root May 25 19:52:33 volumio volumio-remote-updater[460]: [2023-05-25 19:52:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 25 19:52:33 volumio volumio-remote-updater[460]: [2023-05-25 19:52:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 25 19:52:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 19:52:33 volumio systemd[1]: Started dynamicswap service. May 25 19:52:33 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 19:52:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 87. May 25 19:52:33 volumio systemd[1]: Started dynamicswap service. May 25 19:52:33 volumio systemd[1]: Stopped Volumio Backend Module. May 25 19:52:33 volumio systemd[1]: Started Volumio Backend Module. May 25 19:52:33 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:34 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:34 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 589. May 25 19:52:34 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:34 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:34 volumio volumio[18140]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:34 volumio volumio[18140]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:34 volumio volumio[18140]: Malformed config key: missing field `Authentication` May 25 19:52:34 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:34 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:34 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:35 volumio volumio[18128]: info: ------------------------------------------- May 25 19:52:35 volumio volumio[18128]: info: ----- Volumio3 ---- May 25 19:52:35 volumio volumio[18128]: info: ------------------------------------------- May 25 19:52:35 volumio volumio[18128]: info: ----- System startup ---- May 25 19:52:35 volumio volumio[18128]: info: ------------------------------------------- May 25 19:52:36 volumio volumio[18128]: info: MYVOLUMIO Environment detected May 25 19:52:36 volumio volumio[18128]: info: Plugin folders cleanup May 25 19:52:36 volumio volumio[18128]: info: Scanning into folder /volumio/app/plugins/ May 25 19:52:36 volumio volumio[18128]: info: Scanning category audio_interface May 25 19:52:36 volumio volumio[18128]: info: Scanning category miscellanea May 25 19:52:36 volumio volumio[18128]: info: Scanning category music_service May 25 19:52:36 volumio volumio[18128]: info: Scanning category plugins.json May 25 19:52:36 volumio volumio[18128]: info: Scanning category system_controller May 25 19:52:36 volumio volumio[18128]: info: Scanning category user_interface May 25 19:52:36 volumio volumio[18128]: info: Scanning into folder /data/plugins/ May 25 19:52:36 volumio volumio[18128]: info: Scanning category music_service May 25 19:52:36 volumio volumio[18128]: info: Scanning category user_interface May 25 19:52:36 volumio volumio[18128]: info: Plugin folders cleanup completed May 25 19:52:36 volumio volumio[18128]: info: ------------------------------------------- May 25 19:52:36 volumio volumio[18128]: info: ----- Core plugins startup ---- May 25 19:52:36 volumio volumio[18128]: info: ------------------------------------------- May 25 19:52:36 volumio volumio[18128]: info: Loading plugins from folder /volumio/app/plugins/ May 25 19:52:36 volumio volumio[18128]: info: Adding plugin upnp to MyMusic Plugins May 25 19:52:36 volumio volumio[18128]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 19:52:36 volumio volumio[18128]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 19:52:36 volumio volumio[18128]: info: Loading plugins from folder /data/plugins/ May 25 19:52:36 volumio volumio[18128]: info: Loading plugin "system"... May 25 19:52:36 volumio volumio[18128]: info: Loading plugin "appearance"... May 25 19:52:36 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:36 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 590. May 25 19:52:36 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:36 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:36 volumio volumio[18152]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:36 volumio volumio[18152]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:36 volumio volumio[18152]: Malformed config key: missing field `Authentication` May 25 19:52:36 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:36 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "network"... May 25 19:52:37 volumio volumio[18128]: info: Refreshing Cached IP Addresses May 25 19:52:37 volumio sudo[18155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:37 volumio sudo[18155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:37 volumio sudo[18155]: pam_unix(sudo:session): session closed for user root May 25 19:52:37 volumio sudo[18157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:37 volumio sudo[18157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "services"... May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "alsa_controller"... May 25 19:52:37 volumio sudo[18157]: pam_unix(sudo:session): session closed for user root May 25 19:52:37 volumio sudo[18160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 19:52:37 volumio sudo[18160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "wizard"... May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "volumio_command_line_client"... May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "upnp"... May 25 19:52:37 volumio volumio[18128]: info: [1685044357371] Starting Upmpd Daemon May 25 19:52:37 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "my_music"... May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "mpd"... May 25 19:52:37 volumio volumio[18128]: info: Creating MPD Configuration file May 25 19:52:37 volumio volumio[18128]: info: Loading plugin "upnp_browser"... May 25 19:52:37 volumio sudo[18166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:37 volumio sudo[18166]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:37 volumio sudo[18166]: pam_unix(sudo:session): session closed for user root May 25 19:52:37 volumio sudo[18168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:37 volumio sudo[18168]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:37 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:37 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:37 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:37 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:38 volumio volumio[18128]: info: Loading plugin "networkfs"... May 25 19:52:38 volumio ntpd[633]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 25 19:52:38 volumio volumio-remote-updater[460]: [2023-05-25 19:52:38] [connect] Successful connection May 25 19:52:38 volumio volumio[18128]: info: Starting Udev Watcher for removable devices May 25 19:52:38 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:38 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 591. May 25 19:52:38 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:38 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:38 volumio volumio[18128]: info: Ignoring mount for partition: boot May 25 19:52:38 volumio volumio[18128]: info: Ignoring mount for partition: volumio May 25 19:52:38 volumio volumio[18128]: info: Ignoring mount for partition: volumio_data May 25 19:52:38 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:38 volumio volumio[18128]: info: Loading plugin "alarm-clock"... May 25 19:52:38 volumio volumio[18175]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:38 volumio volumio[18175]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:38 volumio volumio[18175]: Malformed config key: missing field `Authentication` May 25 19:52:38 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:38 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:38 volumio volumio[18128]: info: Loading plugin "airplay_emulation"... May 25 19:52:38 volumio volumio[18128]: info: Starting Shairport Sync May 25 19:52:38 volumio volumio[18128]: info: Loading plugin "last_100"... May 25 19:52:38 volumio volumio[18128]: info: Loading plugin "webradio"... May 25 19:52:39 volumio volumio[18128]: info: Loading plugin "i2s_dacs"... May 25 19:52:39 volumio volumio[18128]: info: Loading plugin "volumiodiscovery"... May 25 19:52:39 volumio volumio[18128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:39 volumio volumio[18128]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:39 volumio volumio[18128]: *** WARNING *** For more information see May 25 19:52:39 volumio node[18128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:39 volumio volumio[18128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:39 volumio volumio[18128]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:39 volumio volumio[18128]: *** WARNING *** For more information see May 25 19:52:39 volumio node[18128]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:39 volumio node[18128]: *** WARNING *** For more information see May 25 19:52:39 volumio node[18128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:39 volumio node[18128]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:39 volumio node[18128]: *** WARNING *** For more information see May 25 19:52:39 volumio volumio[18128]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 19:52:39 volumio volumio[18128]: info: Discovery: Started advertising with name: Volumio May 25 19:52:39 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:39 volumio volumio[18128]: info: Loading plugin "spop"... May 25 19:52:39 volumio ntpd[633]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 25 19:52:39 volumio ntpd[633]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 25 19:52:39 volumio mpd[18173]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:39 volumio mpd[18173]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:39 volumio mpd[18173]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:39 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:39 volumio sudo[18168]: pam_unix(sudo:session): session closed for user root May 25 19:52:40 volumio volumio[18128]: info: Loading plugin "youtube2"... May 25 19:52:40 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:40 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 592. May 25 19:52:40 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:40 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:40 volumio volumio[18181]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:40 volumio volumio[18181]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:40 volumio volumio[18181]: Malformed config key: missing field `Authentication` May 25 19:52:40 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:40 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:42 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:42 volumio sudo[18160]: pam_unix(sudo:session): session closed for user root May 25 19:52:43 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:43 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 593. May 25 19:52:43 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:43 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:43 volumio volumio[18183]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:43 volumio volumio[18183]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:43 volumio volumio[18183]: Malformed config key: missing field `Authentication` May 25 19:52:43 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:43 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:44 volumio volumio[18128]: info: Loading plugin "ytmusic"... May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "outputs"... May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "albumart"... May 25 19:52:45 volumio volumio[18128]: info: Plugin example_plugin is not enabled May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "inputs"... May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "updater_comm"... May 25 19:52:45 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:45 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 594. May 25 19:52:45 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:45 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:45 volumio volumio[18192]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:45 volumio volumio[18192]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:45 volumio volumio[18192]: Malformed config key: missing field `Authentication` May 25 19:52:45 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:45 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:45 volumio volumio[18128]: info: Plugin mpdemulation is not enabled May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "rest_api"... May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "websocket"... May 25 19:52:45 volumio volumio[18128]: info: Loading plugin "touch_display"... May 25 19:52:45 volumio volumio[18128]: Forking 3 albumart workers May 25 19:52:45 volumio volumio[18128]: info: Applying required configuration parameters for plugin touch_display May 25 19:52:45 volumio volumio[18128]: info: Loading i18n strings for locale en May 25 19:52:46 volumio volumio[18128]: Updating browse sources language May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::initPlayerControls May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:46 volumio volumio[18128]: Express server listening on port 3000 May 25 19:52:46 volumio volumio[18128]: [Metrics] WebUI: 11s 325.75ms May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::resetVolumioState May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::getcurrentVolume May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:46 volumio volumio[18128]: info: MPD Permissions set May 25 19:52:46 volumio volumio[18128]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:46 volumio volumio[18128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 25 19:52:46 volumio volumio[18128]: info: MPD running with PID18173 May 25 19:52:46 volumio volumio[18128]: ,establishing connection May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:46 volumio volumio[18128]: info: Reloading queue from file May 25 19:52:46 volumio volumio[18128]: Starting albumart workers May 25 19:52:46 volumio volumio[18128]: error: updateQueue error: null May 25 19:52:46 volumio volumio[18128]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 19:52:46 volumio volumio[18128]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::pushState May 25 19:52:46 volumio volumio[18128]: info: CorePlayQueue::getTrack 0 May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::volumioPushState May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::updateTrackBlock May 25 19:52:46 volumio volumio[18128]: info: CorePlayQueue::getTrackBlock May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:52:46 volumio volumio[18128]: Starting albumart workers May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::setRepeat true single undefined May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::pushState May 25 19:52:46 volumio volumio[18128]: info: CorePlayQueue::getTrack 0 May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::volumioPushState May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::setRandom false May 25 19:52:46 volumio volumio[18128]: info: CoreStateMachine::pushState May 25 19:52:46 volumio volumio[18128]: info: CorePlayQueue::getTrack 0 May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:52:46 volumio volumio[18128]: info: CoreCommandRouter::volumioPushState May 25 19:52:46 volumio volumio[18128]: info: Setting Device type: Raspberry PI May 25 19:52:47 volumio volumio[18128]: info: Completed loading Core Plugins May 25 19:52:47 volumio volumio[18128]: Starting albumart workers May 25 19:52:47 volumio volumio[18128]: info: Preparing to generate the ALSA configuration file May 25 19:52:47 volumio volumio[18128]: error: updateQueue error: null May 25 19:52:47 volumio volumio[18128]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:52:47 volumio volumio[18128]: info: Asound.conf file unchanged, so no further update is needed May 25 19:52:47 volumio volumio[18128]: info: Output device has changed, restarting MPD May 25 19:52:47 volumio volumio[18128]: info: Output device has changed, restarting Shairport Sync May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:47 volumio sudo[18234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:47 volumio sudo[18236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:47 volumio sudo[18236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:47 volumio sudo[18234]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:47 volumio sudo[18234]: pam_unix(sudo:session): session closed for user root May 25 19:52:47 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:47 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:47 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:47 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:47 volumio volumio[18128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:47 volumio volumio[18128]: info: ___________ START PLUGINS ___________ May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:47 volumio volumio[18128]: info: [1685044367454] CoreMusicLibrary::Adding element Media Servers May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:47 volumio volumio[18128]: Cannot find translation for sourceMedia Servers May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:47 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:47 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 595. May 25 19:52:47 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:47 volumio volumio[18128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:47 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:52:47 volumio volumio[18246]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:47 volumio volumio[18246]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:47 volumio volumio[18246]: Malformed config key: missing field `Authentication` May 25 19:52:47 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:47 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:47 volumio volumio[18128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:47 volumio volumio[18128]: info: [1685044367674] CoreMusicLibrary::Adding element Last_100 May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:47 volumio volumio[18128]: Cannot find translation for sourceMedia Servers May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:47 volumio volumio[18128]: info: [1685044367679] CoreMusicLibrary::Adding element Webradio May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:47 volumio volumio[18128]: Cannot find translation for sourceMedia Servers May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:47 volumio volumio[18128]: info: Checking for old spotify connect plugin installed May 25 19:52:47 volumio volumio[18128]: info: [youtube2-auth] Starting auth flow... May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:52:47 volumio volumio[18128]: info: [1685044367797] CoreMusicLibrary::Adding element YouTube2 May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:52:47 volumio volumio[18128]: Cannot find translation for sourceMedia Servers May 25 19:52:47 volumio volumio[18128]: Cannot find translation for sourceYouTube2 May 25 19:52:47 volumio volumio[18128]: info: Loading i18n strings for locale en May 25 19:52:47 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:52:47 volumio volumio[18128]: info: Volumio Calling Home May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:48 volumio volumio[18128]: [SpotifyConnect] Creating VLS config file May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:52:48 volumio volumio[18128]: [SpotifyConnect] Starting metadata listener May 25 19:52:48 volumio sudo[18280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 25 19:52:48 volumio sudo[18280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:48 volumio sudo[18283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 25 19:52:48 volumio sudo[18283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:48 volumio systemd[1]: Reloading. May 25 19:52:48 volumio volumio[18128]: info: VolumeController:: Volume=undefined Mute =false May 25 19:52:48 volumio volumio[18128]: info: CoreStateMachine::pushState May 25 19:52:48 volumio volumio[18128]: info: CorePlayQueue::getTrack 0 May 25 19:52:48 volumio volumio[18128]: info: CoreCommandRouter::volumioPushState May 25 19:52:48 volumio sudo[18286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:48 volumio sudo[18286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:48 volumio volumio[18128]: Unhandled rejection Error: No sockets available, cannot start. May 25 19:52:48 volumio volumio[18128]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 25 19:52:48 volumio volumio[18128]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 25 19:52:48 volumio volumio[18128]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 25 19:52:48 volumio volumio[18128]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 25 19:52:48 volumio volumio[18128]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 25 19:52:48 volumio volumio[18128]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 25 19:52:48 volumio volumio[18128]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 25 19:52:48 volumio volumio[18128]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 25 19:52:48 volumio volumio[18128]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 25 19:52:48 volumio volumio[18128]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 25 19:52:48 volumio volumio[18128]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 25 19:52:48 volumio volumio[18128]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 25 19:52:48 volumio volumio[18128]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 25 19:52:48 volumio volumio[18128]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 25 19:52:48 volumio volumio[18128]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 25 19:52:48 volumio volumio[18128]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 25 19:52:48 volumio sudo[18286]: pam_unix(sudo:session): session closed for user root May 25 19:52:48 volumio volumio[18128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:48 volumio sudo[18289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:48 volumio sudo[18289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:48 volumio volumio[18128]: ReferenceError: deferred is not defined May 25 19:52:48 volumio volumio[18128]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) May 25 19:52:48 volumio volumio[18128]: at ClientRequest.emit (events.js:315:20) May 25 19:52:48 volumio volumio[18128]: at TLSSocket.socketErrorListener (_http_client.js:469:9) May 25 19:52:48 volumio volumio[18128]: at TLSSocket.emit (events.js:315:20) May 25 19:52:48 volumio volumio[18128]: at emitErrorNT (internal/streams/destroy.js:106:8) May 25 19:52:48 volumio volumio[18128]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 25 19:52:48 volumio volumio[18128]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 25 19:52:48 volumio volumio[18128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:52:48 volumio sudo[18289]: pam_unix(sudo:session): session closed for user root May 25 19:52:48 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 25 19:52:49 volumio sudo[18283]: pam_unix(sudo:session): session closed for user root May 25 19:52:49 volumio mpd[18251]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:49 volumio mpd[18251]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:49 volumio mpd[18251]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:49 volumio ntpd[633]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 25 19:52:49 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:49 volumio sudo[18236]: pam_unix(sudo:session): session closed for user root May 25 19:52:49 volumio sudo[18313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-25 19:51 May 25 19:52:49 volumio sudo[18313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:49 volumio sudo[18313]: pam_unix(sudo:session): session closed for user root May 25 19:52:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:49 volumio sudo[18280]: pam_unix(sudo:session): session closed for user root May 25 19:52:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 25 19:52:49 volumio systemd[1]: Started dynamicswap service. May 25 19:52:49 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:49 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:49 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 596. May 25 19:52:49 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 25 19:52:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 88. May 25 19:52:49 volumio systemd[1]: Stopped Volumio Backend Module. May 25 19:52:49 volumio systemd[1]: Started Volumio Backend Module. May 25 19:52:49 volumio systemd[1]: Started dynamicswap service. May 25 19:52:49 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:49 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:49 volumio volumio[18330]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:49 volumio volumio[18330]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:49 volumio volumio[18330]: Malformed config key: missing field `Authentication` May 25 19:52:49 volumio systemd[1]: dynamicswap.service: Succeeded. May 25 19:52:49 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:49 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:50 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:50 volumio volumio-remote-updater[460]: [2023-05-25 19:52:50] [info] asio async_connect error: system:111 (Connection refused) May 25 19:52:50 volumio volumio-remote-updater[460]: [2023-05-25 19:52:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 19:52:50 volumio volumio-remote-updater[460]: [2023-05-25 19:52:50] [error] handle_connect error: Underlying Transport Error May 25 19:52:51 volumio volumio[18328]: info: ------------------------------------------- May 25 19:52:51 volumio volumio[18328]: info: ----- Volumio3 ---- May 25 19:52:51 volumio volumio[18328]: info: ------------------------------------------- May 25 19:52:51 volumio volumio[18328]: info: ----- System startup ---- May 25 19:52:51 volumio volumio[18328]: info: ------------------------------------------- May 25 19:52:51 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:51 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 597. May 25 19:52:51 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:51 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:51 volumio volumio[18351]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:51 volumio volumio[18351]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:51 volumio volumio[18351]: Malformed config key: missing field `Authentication` May 25 19:52:51 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:51 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:52 volumio volumio[18328]: info: MYVOLUMIO Environment detected May 25 19:52:52 volumio volumio[18328]: info: Plugin folders cleanup May 25 19:52:52 volumio volumio[18328]: info: Scanning into folder /volumio/app/plugins/ May 25 19:52:52 volumio volumio[18328]: info: Scanning category audio_interface May 25 19:52:52 volumio volumio[18328]: info: Scanning category miscellanea May 25 19:52:52 volumio volumio[18328]: info: Scanning category music_service May 25 19:52:52 volumio volumio[18328]: info: Scanning category plugins.json May 25 19:52:52 volumio volumio[18328]: info: Scanning category system_controller May 25 19:52:52 volumio volumio[18328]: info: Scanning category user_interface May 25 19:52:52 volumio volumio[18328]: info: Scanning into folder /data/plugins/ May 25 19:52:52 volumio volumio[18328]: info: Scanning category music_service May 25 19:52:52 volumio volumio[18328]: info: Scanning category user_interface May 25 19:52:52 volumio volumio[18328]: info: Plugin folders cleanup completed May 25 19:52:52 volumio volumio[18328]: info: ------------------------------------------- May 25 19:52:52 volumio volumio[18328]: info: ----- Core plugins startup ---- May 25 19:52:52 volumio volumio[18328]: info: ------------------------------------------- May 25 19:52:52 volumio volumio[18328]: info: Loading plugins from folder /volumio/app/plugins/ May 25 19:52:52 volumio volumio[18328]: info: Adding plugin upnp to MyMusic Plugins May 25 19:52:52 volumio volumio[18328]: info: Adding plugin airplay_emulation to MyMusic Plugins May 25 19:52:52 volumio volumio[18328]: info: Adding plugin upnp_browser to MyMusic Plugins May 25 19:52:52 volumio volumio[18328]: info: Loading plugins from folder /data/plugins/ May 25 19:52:52 volumio volumio[18328]: info: Loading plugin "system"... May 25 19:52:52 volumio volumio[18328]: info: Loading plugin "appearance"... May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "network"... May 25 19:52:53 volumio volumio[18328]: info: Refreshing Cached IP Addresses May 25 19:52:53 volumio sudo[18356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:52:53 volumio sudo[18356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:53 volumio sudo[18356]: pam_unix(sudo:session): session closed for user root May 25 19:52:53 volumio sudo[18358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "services"... May 25 19:52:53 volumio sudo[18358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "alsa_controller"... May 25 19:52:53 volumio sudo[18358]: pam_unix(sudo:session): session closed for user root May 25 19:52:53 volumio sudo[18361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 25 19:52:53 volumio sudo[18361]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:53 volumio ifplugd(eth0)[629]: Link beat detected. May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "wizard"... May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "volumio_command_line_client"... May 25 19:52:53 volumio dhcpcd[700]: eth0: carrier acquired May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "upnp"... May 25 19:52:53 volumio volumio[18328]: info: [1685044373630] Starting Upmpd Daemon May 25 19:52:53 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:53 volumio dhcpcd[700]: DUID 00:01:00:01:29:aa:89:19:b8:27:eb:fe:6a:b2 May 25 19:52:53 volumio dhcpcd[700]: eth0: IAID eb:fe:6a:b2 May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "my_music"... May 25 19:52:53 volumio dhcpcd[700]: eth0: adding address fe80::a749:9437:6e08:657b May 25 19:52:53 volumio dhcpcd[700]: ipv6_addaddr1: Permission denied May 25 19:52:53 volumio ifplugd(eth0)[629]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 25 19:52:53 volumio volumio[18328]: info: Loading plugin "mpd"... May 25 19:52:53 volumio dhcpcd[18388]: sending commands to master dhcpcd process May 25 19:52:53 volumio dhcpcd[700]: control command: /sbin/dhcpcd eth0 May 25 19:52:53 volumio ifplugd(eth0)[629]: client: sending commands to master dhcpcd process May 25 19:52:53 volumio dhcpcd[700]: eth0: rebinding lease of 192.168.1.94 May 25 19:52:53 volumio dhcpcd[700]: eth0: probing address 192.168.1.94/24 May 25 19:52:53 volumio ifplugd(eth0)[629]: Program executed successfully. May 25 19:52:54 volumio volumio[18328]: info: Creating MPD Configuration file May 25 19:52:54 volumio volumio[18328]: info: Loading plugin "upnp_browser"... May 25 19:52:54 volumio sudo[18414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:52:54 volumio sudo[18414]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:54 volumio sudo[18414]: pam_unix(sudo:session): session closed for user root May 25 19:52:54 volumio sudo[18416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:52:54 volumio sudo[18416]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:52:54 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:54 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 598. May 25 19:52:54 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:54 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:54 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:52:54 volumio volumio[18419]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:54 volumio volumio[18419]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:54 volumio volumio[18419]: Malformed config key: missing field `Authentication` May 25 19:52:54 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:54 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:54 volumio systemd[1]: mpd.service: Succeeded. May 25 19:52:54 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:52:54 volumio systemd[1]: Starting Music Player Daemon... May 25 19:52:54 volumio dhcpcd[700]: eth0: soliciting an IPv6 router May 25 19:52:54 volumio volumio[18328]: info: Loading plugin "networkfs"... May 25 19:52:54 volumio volumio[18328]: info: Starting Udev Watcher for removable devices May 25 19:52:55 volumio volumio[18328]: info: Ignoring mount for partition: boot May 25 19:52:55 volumio volumio[18328]: info: Ignoring mount for partition: volumio May 25 19:52:55 volumio volumio[18328]: info: Ignoring mount for partition: volumio_data May 25 19:52:55 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "alarm-clock"... May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "airplay_emulation"... May 25 19:52:55 volumio volumio[18328]: info: Starting Shairport Sync May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "last_100"... May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "webradio"... May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "i2s_dacs"... May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "volumiodiscovery"... May 25 19:52:55 volumio volumio[18328]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:55 volumio node[18328]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 25 19:52:55 volumio volumio[18328]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:55 volumio node[18328]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:55 volumio volumio[18328]: *** WARNING *** For more information see May 25 19:52:55 volumio node[18328]: *** WARNING *** For more information see May 25 19:52:55 volumio volumio[18328]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:55 volumio node[18328]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 25 19:52:55 volumio volumio[18328]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:55 volumio node[18328]: *** WARNING *** Please fix your application to use the native API of Avahi! May 25 19:52:55 volumio volumio[18328]: *** WARNING *** For more information see May 25 19:52:55 volumio node[18328]: *** WARNING *** For more information see May 25 19:52:55 volumio volumio[18328]: info: Applying required configuration parameters for plugin volumiodiscovery May 25 19:52:55 volumio volumio[18328]: info: Discovery: Started advertising with name: Volumio May 25 19:52:55 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:52:55 volumio volumio[18328]: info: Loading plugin "spop"... May 25 19:52:55 volumio mpd[18423]: May 25 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:52:55 volumio mpd[18423]: May 25 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:52:55 volumio mpd[18423]: May 25 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:52:55 volumio systemd[1]: Started Music Player Daemon. May 25 19:52:55 volumio sudo[18416]: pam_unix(sudo:session): session closed for user root May 25 19:52:56 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:56 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 599. May 25 19:52:56 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:56 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:56 volumio volumio[18429]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:56 volumio volumio[18429]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:56 volumio volumio[18429]: Malformed config key: missing field `Authentication` May 25 19:52:56 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:56 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:56 volumio volumio[18328]: info: Loading plugin "youtube2"... May 25 19:52:58 volumio dhcpcd[700]: eth0: leased 192.168.1.94 for 86400 seconds May 25 19:52:58 volumio avahi-daemon[478]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.94. May 25 19:52:58 volumio avahi-daemon[478]: New relevant interface eth0.IPv4 for mDNS. May 25 19:52:58 volumio dhcpcd[700]: eth0: adding route to 192.168.1.0/24 May 25 19:52:58 volumio avahi-daemon[478]: Registering new address record for 192.168.1.94 on eth0.IPv4. May 25 19:52:58 volumio dhcpcd[700]: eth0: adding default route via 192.168.1.254 May 25 19:52:58 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:52:58 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 600. May 25 19:52:58 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:52:58 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:52:58 volumio volumio[18452]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:52:58 volumio volumio[18452]: Reading Config from "/tmp/volspotify.toml" May 25 19:52:58 volumio volumio[18452]: Malformed config key: missing field `Authentication` May 25 19:52:58 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:52:58 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:52:59 volumio wpa_supplicant[688]: wlan0: Failed to initiate sched scan May 25 19:52:59 volumio sudo[18361]: pam_unix(sudo:session): session closed for user root May 25 19:53:00 volumio ntpd[633]: Listen normally on 3 eth0 192.168.1.94:123 May 25 19:53:00 volumio ntpd[633]: new interface(s) found: waking up resolver May 25 19:53:00 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:53:00 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 601. May 25 19:53:00 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:53:00 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:53:00 volumio volumio[18328]: info: Loading plugin "ytmusic"... May 25 19:53:00 volumio volumio[18454]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:53:00 volumio volumio[18454]: Reading Config from "/tmp/volspotify.toml" May 25 19:53:00 volumio volumio[18454]: Malformed config key: missing field `Authentication` May 25 19:53:00 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:53:00 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:53:00 volumio volumio-remote-updater[460]: [2023-05-25 19:53:00] [connect] Successful connection May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "outputs"... May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "albumart"... May 25 19:53:01 volumio volumio[18328]: info: Plugin example_plugin is not enabled May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "inputs"... May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "updater_comm"... May 25 19:53:01 volumio volumio[18328]: info: Plugin mpdemulation is not enabled May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "rest_api"... May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "websocket"... May 25 19:53:01 volumio volumio[18328]: info: Loading plugin "touch_display"... May 25 19:53:02 volumio volumio[18328]: Forking 3 albumart workers May 25 19:53:02 volumio volumio[18328]: info: Applying required configuration parameters for plugin touch_display May 25 19:53:02 volumio volumio[18328]: info: Loading i18n strings for locale en May 25 19:53:02 volumio volumio[18328]: Updating browse sources language May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::initPlayerControls May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:53:02 volumio volumio[18328]: Express server listening on port 3000 May 25 19:53:02 volumio volumio[18328]: [Metrics] WebUI: 11s 667.35ms May 25 19:53:02 volumio volumio[18328]: info: CoreStateMachine::resetVolumioState May 25 19:53:02 volumio volumio[18328]: info: CoreStateMachine::getcurrentVolume May 25 19:53:02 volumio volumio[18328]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:53:02 volumio volumio[18328]: info: MPD Permissions set May 25 19:53:02 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:53:02 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 602. May 25 19:53:02 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:53:02 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:53:02 volumio volumio[18490]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:53:02 volumio volumio[18490]: Reading Config from "/tmp/volspotify.toml" May 25 19:53:02 volumio volumio[18490]: Malformed config key: missing field `Authentication` May 25 19:53:02 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE May 25 19:53:02 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. May 25 19:53:03 volumio volumio-remote-updater[460]: [2023-05-25 19:53:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1685044380 101 May 25 19:53:03 volumio volumio[18328]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 25 19:53:03 volumio volumio[18328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:53:03 volumio volumio[18328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 25 19:53:03 volumio volumio[18328]: info: MPD running with PID18423 May 25 19:53:03 volumio volumio[18328]: ,establishing connection May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:53:03 volumio volumio[18328]: Starting albumart workers May 25 19:53:03 volumio volumio[18328]: info: Reloading queue from file May 25 19:53:03 volumio volumio[18328]: error: updateQueue error: null May 25 19:53:03 volumio volumio[18328]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 25 19:53:03 volumio volumio[18328]: Starting albumart workers May 25 19:53:03 volumio volumio[18328]: Starting albumart workers May 25 19:53:03 volumio volumio[18328]: info: VolumeController:: Volume=undefined Mute =false May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::pushState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioPushState May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::updateTrackBlock May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrackBlock May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioRetrievevolume May 25 19:53:03 volumio volumio[18328]: info: Setting Device type: Raspberry PI May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::setRepeat true single undefined May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::pushState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioPushState May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::setRandom false May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::pushState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioPushState May 25 19:53:03 volumio volumio[18328]: info: Completed loading Core Plugins May 25 19:53:03 volumio volumio[18328]: info: Preparing to generate the ALSA configuration file May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 19:53:03 volumio volumio[18328]: info: Discovery: Getting this device information May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioGetState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 19:53:03 volumio volumio[18328]: error: updateQueue error: null May 25 19:53:03 volumio volumio[18328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 25 19:53:03 volumio sudo[18506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 19:53:03 volumio sudo[18506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:03 volumio sudo[18506]: pam_unix(sudo:session): session closed for user root May 25 19:53:03 volumio sudo[18509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 19:53:03 volumio sudo[18509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:03 volumio sudo[18509]: pam_unix(sudo:session): session closed for user root May 25 19:53:03 volumio volumio[18328]: info: VolumeController:: Volume=undefined Mute =false May 25 19:53:03 volumio volumio[18328]: info: CoreStateMachine::pushState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioPushState May 25 19:53:03 volumio volumio[18328]: info: Discovery: adding 80bc70e0-dfb2-4daa-89f4-83adc3431fd4 May 25 19:53:03 volumio volumio[18328]: info: mDNS: Found device Volumio May 25 19:53:03 volumio volumio[18328]: info: CoreCommandRouter::volumioGetState May 25 19:53:03 volumio volumio[18328]: info: CorePlayQueue::getTrack 0 May 25 19:53:03 volumio volumio[18328]: info: Asound.conf file unchanged, so no further update is needed May 25 19:53:03 volumio volumio[18328]: info: Output device has changed, restarting MPD May 25 19:53:04 volumio sudo[18516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 19:53:04 volumio sudo[18516]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:04 volumio sudo[18516]: pam_unix(sudo:session): session closed for user root May 25 19:53:04 volumio volumio[18328]: info: Output device has changed, restarting Shairport Sync May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:53:04 volumio sudo[18520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 19:53:04 volumio sudo[18520]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:04 volumio systemd[1]: Stopping Music Player Daemon... May 25 19:53:04 volumio systemd[1]: mpd.service: Succeeded. May 25 19:53:04 volumio volumio[18328]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:53:04 volumio systemd[1]: Stopped Music Player Daemon. May 25 19:53:04 volumio volumio[18328]: info: ___________ START PLUGINS ___________ May 25 19:53:04 volumio systemd[1]: Starting Music Player Daemon... May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:53:04 volumio volumio[18328]: info: [1685044384242] CoreMusicLibrary::Adding element Media Servers May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:53:04 volumio volumio[18328]: Cannot find translation for sourceMedia Servers May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:53:04 volumio volumio[18328]: info: [1685044384423] CoreMusicLibrary::Adding element Last_100 May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:53:04 volumio volumio[18328]: Cannot find translation for sourceMedia Servers May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:53:04 volumio volumio[18328]: info: [1685044384429] CoreMusicLibrary::Adding element Webradio May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:53:04 volumio volumio[18328]: Cannot find translation for sourceMedia Servers May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:53:04 volumio volumio[18328]: info: Checking for old spotify connect plugin installed May 25 19:53:04 volumio volumio[18328]: info: [youtube2-auth] Starting auth flow... May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 25 19:53:04 volumio volumio[18328]: info: [1685044384533] CoreMusicLibrary::Adding element YouTube2 May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 19:53:04 volumio volumio[18328]: Cannot find translation for sourceMedia Servers May 25 19:53:04 volumio volumio[18328]: Cannot find translation for sourceYouTube2 May 25 19:53:04 volumio volumio[18328]: info: Loading i18n strings for locale en May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 19:53:04 volumio volumio[18328]: info: Volumio Calling Home May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 25 19:53:04 volumio volumio[18328]: [SpotifyConnect] Creating VLS config file May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 19:53:04 volumio volumio[18328]: [SpotifyConnect] Starting metadata listener May 25 19:53:04 volumio sudo[18557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service May 25 19:53:04 volumio sudo[18557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:04 volumio sudo[18560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 25 19:53:04 volumio sudo[18560]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:04 volumio systemd[1]: Reloading. May 25 19:53:04 volumio volumio[18328]: info: MPD Permissions set May 25 19:53:05 volumio volumio[18328]: verbose: New Socket.io Connection to 192.168.1.94:3000 from 192.168.1.130 UA: Dart/3.4 (dart:io) Total Clients: 3 May 25 19:53:05 volumio volumio[18328]: info: Volumio called home May 25 19:53:05 volumio volumio[18328]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:53:05 volumio volumio[18328]: ReferenceError: deferred is not defined May 25 19:53:05 volumio volumio[18328]: at ClientRequest. (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) May 25 19:53:05 volumio volumio[18328]: at ClientRequest.emit (events.js:315:20) May 25 19:53:05 volumio volumio[18328]: at TLSSocket.socketErrorListener (_http_client.js:469:9) May 25 19:53:05 volumio volumio[18328]: at TLSSocket.emit (events.js:315:20) May 25 19:53:05 volumio volumio[18328]: at emitErrorNT (internal/streams/destroy.js:106:8) May 25 19:53:05 volumio volumio[18328]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) May 25 19:53:05 volumio volumio[18328]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 25 19:53:05 volumio volumio[18328]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 19:53:05 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 25 19:53:05 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. May 25 19:53:05 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 603. May 25 19:53:05 volumio sudo[18560]: pam_unix(sudo:session): session closed for user root May 25 19:53:05 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 25 19:53:05 volumio systemd[1]: Started Volspotconnect2 Daemon. May 25 19:53:05 volumio sudo[18557]: pam_unix(sudo:session): session closed for user root May 25 19:53:05 volumio volumio[18583]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 May 25 19:53:05 volumio volumio[18583]: Reading Config from "/tmp/volspotify.toml" May 25 19:53:05 volumio volumio[18583]: [Vollibrespot] : Using Alsa backend with device: volumio May 25 19:53:05 volumio sudo[18586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-05-25 19:52 May 25 19:53:05 volumio sudo[18586]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 19:53:05 volumio mpd[18552]: May 25 19:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 19:53:05 volumio mpd[18552]: May 25 19:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 25 19:53:05 volumio mpd[18552]: May 25 19:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 25 19:53:05 volumio systemd[1]: Started Music Player Daemon. May 25 19:53:05 volumio sudo[18520]: pam_unix(sudo:session): session closed for user root May 25 19:53:06 volumio volumio[18583]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } 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="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"