-- Logs begin at Sun 2024-08-11 01:58:34 CEST, end at Sun 2024-08-11 03:37:43 CEST. -- Aug 11 03:36:01 volumio volumio-remote-updater[662]: [2024-08-11 03:36:01] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Aug 11 03:36:06 volumio volumio-remote-updater[662]: [2024-08-11 03:36:06] [connect] Successful connection Aug 11 03:36:13 volumio go-librespot[4477]: time="2024-08-11T03:36:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 11 03:36:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 11 03:36:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 11 03:36:14 volumio ntpd[6264]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 11 03:36:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 11 03:36:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Aug 11 03:36:16 volumio systemd[1]: Stopped go-librespot Daemon. Aug 11 03:36:16 volumio systemd[1]: Started go-librespot Daemon. Aug 11 03:36:16 volumio go-librespot[4484]: Librespot-go daemon starting... Aug 11 03:36:16 volumio go-librespot[4484]: time="2024-08-11T03:36:16+02:00" level=info msg="generated new device id: 3c8209855d469082c87b5c03740569f2425c786e" Aug 11 03:36:16 volumio go-librespot[4484]: time="2024-08-11T03:36:16+02:00" level=debug msg="stored credentials found for rocqqoxbqr2vt9yo7ckmuz0ji" Aug 11 03:36:18 volumio volumio-remote-updater[662]: [2024-08-11 03:36:18] [connect] Successful connection Aug 11 03:36:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 11 03:36:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 11 03:36:33 volumio systemd[1]: Started dynamicswap service. Aug 11 03:36:33 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 11 03:36:33 volumio volumio-remote-updater[662]: [2024-08-11 03:36:33] [info] asio async_connect error: system:111 (Connection refused) Aug 11 03:36:33 volumio volumio-remote-updater[662]: [2024-08-11 03:36:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 11 03:36:33 volumio volumio-remote-updater[662]: [2024-08-11 03:36:33] [error] handle_connect error: Underlying Transport Error Aug 11 03:36:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 11 03:36:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2814. Aug 11 03:36:33 volumio systemd[1]: Started dynamicswap service. Aug 11 03:36:33 volumio systemd[1]: Stopped Volumio Backend Module. Aug 11 03:36:33 volumio systemd[1]: Started Volumio Backend Module. Aug 11 03:36:33 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 11 03:36:34 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:36:34 volumio volumio[4539]: info: ----- Volumio3 ---- Aug 11 03:36:34 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:36:34 volumio volumio[4539]: info: ----- System startup ---- Aug 11 03:36:34 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:36:35 volumio volumio[4539]: info: MYVOLUMIO Environment detected Aug 11 03:36:35 volumio volumio[4539]: info: Plugin folders cleanup Aug 11 03:36:35 volumio volumio[4539]: info: Scanning into folder /volumio/app/plugins/ Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category audio_interface Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category miscellanea Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category music_service Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category plugins.json Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category system_controller Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category user_interface Aug 11 03:36:35 volumio volumio[4539]: info: Scanning into folder /data/plugins/ Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category audio_interface Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category music_service Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category system_controller Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category system_hardware Aug 11 03:36:35 volumio volumio[4539]: info: Scanning category user_interface Aug 11 03:36:35 volumio volumio[4539]: info: Plugin folders cleanup completed Aug 11 03:36:35 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:36:35 volumio volumio[4539]: info: ----- Core plugins startup ---- Aug 11 03:36:35 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugins from folder /volumio/app/plugins/ Aug 11 03:36:35 volumio volumio[4539]: info: Adding plugin upnp to MyMusic Plugins Aug 11 03:36:35 volumio volumio[4539]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 11 03:36:35 volumio volumio[4539]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugins from folder /data/plugins/ Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugin "system"... Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugin "appearance"... Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugin "network"... Aug 11 03:36:35 volumio volumio[4539]: info: Refreshing Cached IP Addresses Aug 11 03:36:35 volumio sudo[4565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 11 03:36:35 volumio sudo[4565]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:35 volumio sudo[4565]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugin "services"... Aug 11 03:36:35 volumio sudo[4567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 11 03:36:35 volumio volumio[4539]: info: Loading plugin "alsa_controller"... Aug 11 03:36:35 volumio sudo[4567]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:35 volumio sudo[4567]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:35 volumio sudo[4570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 11 03:36:35 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:36 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "wizard"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "networkfs"... Aug 11 03:36:36 volumio volumio[4539]: info: Starting Udev Watcher for removable devices Aug 11 03:36:36 volumio volumio[4539]: info: Ignoring mount for partition: boot Aug 11 03:36:36 volumio volumio[4539]: info: Ignoring mount for partition: volumio Aug 11 03:36:36 volumio volumio[4539]: info: Ignoring mount for partition: volumio_data Aug 11 03:36:36 volumio volumio[4539]: info: Mounting Device 0365-716E Aug 11 03:36:36 volumio sudo[4581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/0365-716E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 11 03:36:36 volumio sudo[4581]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:36 volumio sudo[4581]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:36 volumio volumio[4539]: mount: /media/0365-716E: /dev/sdb1 already mounted on /media/0365-716E. Aug 11 03:36:36 volumio volumio[4539]: error: Failed to mount 0365-716E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/0365-716E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 11 03:36:36 volumio volumio[4539]: mount: /media/0365-716E: /dev/sdb1 already mounted on /media/0365-716E. Aug 11 03:36:36 volumio volumio[4539]: info: Mounting Device 6928-A5A0 Aug 11 03:36:36 volumio sudo[4587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/6928-A5A0 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 11 03:36:36 volumio sudo[4587]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:36 volumio sudo[4587]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:36 volumio volumio[4539]: mount: /media/6928-A5A0: /dev/sda1 already mounted on /media/6928-A5A0. Aug 11 03:36:36 volumio volumio[4539]: error: Failed to mount 6928-A5A0: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/6928-A5A0" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 11 03:36:36 volumio volumio[4539]: mount: /media/6928-A5A0: /dev/sda1 already mounted on /media/6928-A5A0. Aug 11 03:36:36 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "volumio_command_line_client"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "upnp"... Aug 11 03:36:36 volumio volumio[4539]: info: [1723340196227] Starting Upmpd Daemon Aug 11 03:36:36 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "my_music"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "mpd"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "upnp_browser"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "alarm-clock"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "airplay_emulation"... Aug 11 03:36:36 volumio volumio[4539]: info: Starting Shairport Sync Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "last_100"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "webradio"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "i2s_dacs"... Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "volumiodiscovery"... Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 11 03:36:36 volumio node[4539]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** For more information see Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 11 03:36:36 volumio volumio[4539]: *** WARNING *** For more information see Aug 11 03:36:36 volumio node[4539]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 11 03:36:36 volumio node[4539]: *** WARNING *** For more information see Aug 11 03:36:36 volumio node[4539]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 11 03:36:36 volumio node[4539]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 11 03:36:36 volumio node[4539]: *** WARNING *** For more information see Aug 11 03:36:36 volumio volumio[4539]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 11 03:36:36 volumio volumio[4539]: info: Discovery: Started advertising with name: Volumio Aug 11 03:36:36 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 11 03:36:36 volumio volumio[4539]: info: Loading plugin "bandcamp"... Aug 11 03:36:37 volumio volumio[4539]: info: Loading plugin "calmradio"... Aug 11 03:36:37 volumio volumio[4539]: info: Loading plugin "mixcloud"... Aug 11 03:36:38 volumio volumio[4539]: info: Loading plugin "soundcloud"... Aug 11 03:36:38 volumio volumio[4539]: info: Loading plugin "spop"... Aug 11 03:36:38 volumio volumio[4539]: info: Loading plugin "youtube2"... Aug 11 03:36:39 volumio volumio[4539]: info: Loading plugin "ytcr"... Aug 11 03:36:39 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:40 volumio volumio[4539]: info: Loading plugin "ytmusic"... Aug 11 03:36:41 volumio volumio[4539]: info: Loading plugin "now_playing"... Aug 11 03:36:41 volumio volumio[4539]: info: Loading plugin "outputs"... Aug 11 03:36:41 volumio volumio[4539]: info: Loading plugin "albumart"... Aug 11 03:36:41 volumio volumio[4539]: info: Plugin example_plugin is not enabled Aug 11 03:36:41 volumio volumio[4539]: info: Loading plugin "inputs"... Aug 11 03:36:41 volumio volumio[4539]: info: Loading plugin "updater_comm"... Aug 11 03:36:42 volumio volumio[4539]: info: Plugin mpdemulation is not enabled Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "rest_api"... Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "websocket"... Aug 11 03:36:42 volumio volumio[4539]: info: Starting Socket.io Server version 2.3.0 Aug 11 03:36:42 volumio volumio[4539]: info: Plugin fusiondsp is not enabled Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "80s80s"... Aug 11 03:36:42 volumio volumio[4539]: Forking 3 albumart workers Aug 11 03:36:42 volumio volumio[4539]: info: Applying required configuration parameters for plugin 80s80s Aug 11 03:36:42 volumio volumio[4539]: info: [1723340202285] [80s80s] API delay: 30 Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "lms"... Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "minidlna"... Aug 11 03:36:42 volumio volumio[4539]: info: Applying required configuration parameters for plugin minidlna Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "motherearthradio"... Aug 11 03:36:42 volumio volumio[4539]: info: Applying required configuration parameters for plugin motherearthradio Aug 11 03:36:42 volumio volumio[4539]: info: [1723340202618] [MotherEarth] API delay: 5 Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "myb"... Aug 11 03:36:42 volumio ntpd[6264]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 11 03:36:42 volumio volumio[4539]: Starting albumart workers Aug 11 03:36:42 volumio volumio[4539]: info: Applying required configuration parameters for plugin myb Aug 11 03:36:42 volumio volumio[4539]: info: Loading plugin "plexamp"... Aug 11 03:36:43 volumio volumio[4539]: Starting albumart workers Aug 11 03:36:43 volumio volumio[4539]: Starting albumart workers Aug 11 03:36:43 volumio volumio-remote-updater[662]: [2024-08-11 03:36:43] [connect] Successful connection Aug 11 03:36:43 volumio volumio[4539]: info: Applying required configuration parameters for plugin plexamp Aug 11 03:36:43 volumio volumio[4539]: info: Loading plugin "podcast"... Aug 11 03:36:43 volumio volumio[4539]: info: ControllerPodcast::constructor Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "rad357pl"... Aug 11 03:36:44 volumio volumio[4539]: info: Applying required configuration parameters for plugin rad357pl Aug 11 03:36:44 volumio volumio[4539]: info: [1723340204170] [rad357pl] API delay: 5 Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "radio_paradise"... Aug 11 03:36:44 volumio volumio[4539]: info: Applying required configuration parameters for plugin radio_paradise Aug 11 03:36:44 volumio volumio[4539]: info: [1723340204291] [RadioParadise] API delay: 5 Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "squeezelite"... Aug 11 03:36:44 volumio volumio[4539]: info: [Squeezelite] Initiated plugin Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "swarmstation"... Aug 11 03:36:44 volumio volumio[4539]: info: Applying required configuration parameters for plugin swarmstation Aug 11 03:36:44 volumio volumio[4539]: info: [1723340204520] [Swarmstation] API delay: 5 Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "volusonic"... Aug 11 03:36:44 volumio go-librespot[4484]: time="2024-08-11T03:36:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 11 03:36:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 11 03:36:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 11 03:36:44 volumio volumio[4539]: info: Applying required configuration parameters for plugin volusonic Aug 11 03:36:44 volumio volumio[4539]: info: Loading plugin "backup_restore"... Aug 11 03:36:45 volumio volumio[4539]: info: Applying required configuration parameters for plugin backup_restore Aug 11 03:36:45 volumio volumio[4539]: info: Loading plugin "crossfader_switch"... Aug 11 03:36:45 volumio volumio[4539]: info: Applying required configuration parameters for plugin crossfader_switch Aug 11 03:36:45 volumio volumio[4539]: info: Loading plugin "music_services_shield"... Aug 11 03:36:45 volumio volumio[4539]: info: Applying required configuration parameters for plugin music_services_shield Aug 11 03:36:45 volumio volumio[4539]: info: Loading plugin "Systeminfo"... Aug 11 03:36:45 volumio volumio[4539]: info: Loading plugin "lastfm"... Aug 11 03:36:45 volumio volumio[4539]: info: Loading plugin "randomizer"... Aug 11 03:36:45 volumio volumio[4539]: info: Applying required configuration parameters for plugin randomizer Aug 11 03:36:45 volumio volumio[4539]: info: Loading i18n strings for locale de Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Aug 11 03:36:45 volumio volumio[4539]: Updating browse sources language Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::initPlayerControls Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 11 03:36:45 volumio volumio[4539]: Express server listening on port 3000 Aug 11 03:36:45 volumio volumio[4539]: [Metrics] WebUI: 11s 424.77ms Aug 11 03:36:45 volumio volumio[4539]: info: CoreStateMachine::resetVolumioState Aug 11 03:36:45 volumio volumio[4539]: info: CoreStateMachine::getcurrentVolume Aug 11 03:36:45 volumio volumio[4539]: info: CoreCommandRouter::volumioRetrievevolume Aug 11 03:36:46 volumio volumio-remote-updater[662]: [2024-08-11 03:36:46] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723340203 101 Aug 11 03:36:46 volumio volumio[4539]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 11 03:36:46 volumio sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 11 03:36:46 volumio sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio sudo[4649]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio sudo[4652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 11 03:36:46 volumio sudo[4652]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio sudo[4652]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio volumio[4539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 11 03:36:46 volumio volumio[4539]: info: VolumeController:: Volume=25 Mute =false Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::pushState Aug 11 03:36:46 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioPushState Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::updateTrackBlock Aug 11 03:36:46 volumio volumio[4539]: info: CorePlayQueue::getTrackBlock Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioRetrievevolume Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:46 volumio volumio[4539]: info: Reloading queue from file Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::setRepeat null single undefined Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::pushState Aug 11 03:36:46 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioPushState Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::setRandom false Aug 11 03:36:46 volumio volumio[4539]: info: CoreStateMachine::pushState Aug 11 03:36:46 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioPushState Aug 11 03:36:46 volumio volumio[4539]: info: Setting Device type: Raspberry PI Aug 11 03:36:46 volumio volumio[4539]: info: Completed loading Core Plugins Aug 11 03:36:46 volumio volumio[4539]: info: Preparing to generate the ALSA configuration file Aug 11 03:36:46 volumio volumio[4539]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 11 03:36:46 volumio volumio[4539]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03112 Aug 11 03:36:46 volumio volumio[4539]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 11 03:36:46 volumio volumio[4539]: info: Asound.conf file unchanged, so no further update is needed Aug 11 03:36:46 volumio volumio[4539]: info: Output device has changed, restarting MPD Aug 11 03:36:46 volumio sudo[4662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 11 03:36:46 volumio sudo[4662]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio sudo[4662]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio sudo[4665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 11 03:36:46 volumio sudo[4665]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio sudo[4665]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio volumio[4539]: info: Output device has changed, restarting Shairport Sync Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:46 volumio sudo[4668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 11 03:36:46 volumio sudo[4668]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio systemd[1]: musicservicesshield.service: Succeeded. Aug 11 03:36:46 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Aug 11 03:36:46 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Aug 11 03:36:46 volumio systemd[1]: Stopping Music Player Daemon... Aug 11 03:36:46 volumio systemd[1]: mpd.service: Succeeded. Aug 11 03:36:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 11 03:36:46 volumio volumio[4539]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 11 03:36:46 volumio volumio[4539]: info: ___________ START PLUGINS ___________ Aug 11 03:36:46 volumio volumio[4539]: info: ControllerMpd::onStart: Initializing MPD Aug 11 03:36:46 volumio volumio[4539]: info: Creating MPD Configuration file Aug 11 03:36:46 volumio sudo[4675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 11 03:36:46 volumio sudo[4675]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio systemd[1]: Starting Music Player Daemon... Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:46 volumio volumio[4539]: info: [1723340206851] CoreMusicLibrary::Adding element Medienserver Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:46 volumio sudo[4675]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio sudo[4678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 11 03:36:46 volumio sudo[4678]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 11 03:36:46 volumio systemd[1]: mpd.service: Succeeded. Aug 11 03:36:46 volumio systemd[1]: Stopped Music Player Daemon. Aug 11 03:36:46 volumio systemd[1]: Starting Music Player Daemon... Aug 11 03:36:46 volumio volumio[4539]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:46 volumio sudo[4681]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 11 03:36:46 volumio sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:46 volumio sudo[4681]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:46 volumio volumio[4539]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:46 volumio volumio[4539]: info: [1723340206982] CoreMusicLibrary::Adding element Last_100 Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:46 volumio volumio[4539]: info: [1723340206984] CoreMusicLibrary::Adding element Webradio Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:46 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 11 03:36:46 volumio volumio[4539]: info: Initializing BBC Radios Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207095] CoreMusicLibrary::Adding element Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: info: Adding Calm Radio to Browse Sources Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207099] CoreMusicLibrary::Adding element Calm Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207106] CoreMusicLibrary::Adding element Mixcloud Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207115] CoreMusicLibrary::Adding element SoundCloud Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: info: Creating Spotify config file Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207177] CoreMusicLibrary::Adding element YouTube2 Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207213] CoreMusicLibrary::Adding element YouTube Music Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: info: [now-playing] ConfigUpdater: config is up to date. Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207236] CoreMusicLibrary::Adding element 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: info: Loading i18n strings for locale de Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Aug 11 03:36:47 volumio sudo[4700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Aug 11 03:36:47 volumio sudo[4700]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207334] CoreMusicLibrary::Adding element Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio systemd[1]: Starting Logitech Media Server Daemon... Aug 11 03:36:47 volumio systemd[1]: Started Logitech Media Server Daemon. Aug 11 03:36:47 volumio systemd[4706]: logitechmediaserver.service: Failed to execute command: No such file or directory Aug 11 03:36:47 volumio systemd[4706]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Aug 11 03:36:47 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Aug 11 03:36:47 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Aug 11 03:36:47 volumio volumio[4539]: info: Loading i18n strings for locale de Aug 11 03:36:47 volumio sudo[4700]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: Updating browse sources language Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207418] CoreMusicLibrary::Adding element Podcast Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207427] CoreMusicLibrary::Adding element RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207436] CoreMusicLibrary::Adding element Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: info: [Squeezelite] Not starting squeezelite; it's not enabled. Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207449] CoreMusicLibrary::Adding element SWARM Station Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: info: Loading i18n strings for locale de Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CONFIGURATION. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_CRED_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLUGIN_OPTS_DESC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE_CREDS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT_DOC. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TIME_OUT. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SUCCESS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_OK. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_FAILED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_BAD_CREDS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key CON_SERVER_UNREACHABLE. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key NEWEST_ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key GENRES. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key PLAYLISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key START_RADIO. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TOP_SONGS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key ALBUMS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SIMILAR_ARTISTS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key TRACKS. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key SAVED. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: info: Plugin volusonic has duplicated i18n key POP_ERROR. It is ignored. Aug 11 03:36:47 volumio volumio[4539]: Updating browse sources language Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207504] CoreMusicLibrary::Adding element Volusonic Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Volusonic Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207762] CoreMusicLibrary::Adding element LastFM Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Volusonic Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source LastFM Aug 11 03:36:47 volumio volumio[4539]: info: [LastFM] scrobbler initiated! Aug 11 03:36:47 volumio volumio[4539]: info: [LastFM] extended logging: false Aug 11 03:36:47 volumio volumio[4539]: info: [LastFM] try scrobble stream/radio plays: true Aug 11 03:36:47 volumio volumio[4539]: info: [LastFM] Left init routine Aug 11 03:36:47 volumio volumio[4539]: info: [LastFM] Socket already connected: true Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207794] CoreMusicLibrary::Adding element Randomizer Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Volusonic Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source LastFM Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Randomizer Aug 11 03:36:47 volumio volumio[4539]: info: Volumio Calling Home Aug 11 03:36:47 volumio sudo[4733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Aug 11 03:36:47 volumio sudo[4733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:47 volumio mpd[4688]: Aug 11 03:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 11 03:36:47 volumio systemd[1]: Started Music Player Daemon. Aug 11 03:36:47 volumio sudo[4678]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:47 volumio sudo[4668]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:47 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Aug 11 03:36:47 volumio volumio[4539]: info: PlexAmp::Plex initialised[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 11 03:36:47 volumio volumio[4539]: info: [1723340207897] CoreMusicLibrary::Adding element Plex Aug 11 03:36:47 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Bandcamp Discover Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Calm Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mixcloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SoundCloud Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube2 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source YouTube Music Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source 80s80s Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Mother Earth Radio Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Podcast Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source RADIO 357 Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Radio Paradise Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source SWARM Station Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Volusonic Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source LastFM Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Randomizer Aug 11 03:36:47 volumio volumio[4539]: Cannot find translation for source Plex Aug 11 03:36:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 11 03:36:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Aug 11 03:36:47 volumio volumio[4539]: info: [now-playing] App is listening on port 4004. Aug 11 03:36:47 volumio systemd[1]: Stopped go-librespot Daemon. Aug 11 03:36:47 volumio systemd[1]: Started go-librespot Daemon. Aug 11 03:36:47 volumio go-librespot[4751]: Librespot-go daemon starting... Aug 11 03:36:47 volumio volumio[4539]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 11 03:36:48 volumio go-librespot[4751]: time="2024-08-11T03:36:47+02:00" level=info msg="generated new device id: 6ff6e631cf29d0be3f94fa8a8a9c9db2824f95de" Aug 11 03:36:48 volumio go-librespot[4751]: time="2024-08-11T03:36:48+02:00" level=fatal msg="unknown credentials: " Aug 11 03:36:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 11 03:36:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 11 03:36:48 volumio volumio[4539]: info: VolumeController:: Volume=25 Mute =false Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::volumioGetState Aug 11 03:36:48 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:36:48 volumio volumio[4539]: info: CoreStateMachine::pushState Aug 11 03:36:48 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::volumioPushState Aug 11 03:36:48 volumio volumio[4539]: info: minidlna: Found miniDLNA version 1.2.1 Aug 11 03:36:48 volumio volumio[4539]: info: minidlna: Starting minidlna.service Aug 11 03:36:48 volumio volumio[4539]: info: logitechmediaserver started Aug 11 03:36:48 volumio sudo[4770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 11 03:36:48 volumio sudo[4770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:48 volumio volumio[4539]: info: MPD Permissions set Aug 11 03:36:48 volumio volumio[4539]: info: Upmpdcli Daemon Started Aug 11 03:36:48 volumio volumio[4539]: info: Spotify config file written Aug 11 03:36:48 volumio sudo[4770]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:48 volumio sudo[4779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 11 03:36:48 volumio sudo[4779]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:48 volumio systemd[1]: Stopped go-librespot Daemon. Aug 11 03:36:48 volumio systemd[1]: Started go-librespot Daemon. Aug 11 03:36:48 volumio go-librespot[4784]: Librespot-go daemon starting... Aug 11 03:36:48 volumio volumio[4539]: error: MPD error: The expression evaluated to a falsy value: Aug 11 03:36:48 volumio volumio[4539]: assert.ok(self.idling) Aug 11 03:36:48 volumio volumio[4539]: error: The expression evaluated to a falsy value: Aug 11 03:36:48 volumio volumio[4539]: assert.ok(self.idling) Aug 11 03:36:48 volumio sudo[4779]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:48 volumio sudo[4733]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:48 volumio go-librespot[4784]: time="2024-08-11T03:36:48+02:00" level=info msg="generated new device id: 8cf3b3cb7e003e02960070b5de6bfc3cdb9a9220" Aug 11 03:36:48 volumio go-librespot[4784]: time="2024-08-11T03:36:48+02:00" level=debug msg="stored credentials found for rocqqoxbqr2vt9yo7ckmuz0ji" Aug 11 03:36:48 volumio volumio[4744]: cset: --> shielding system active with Aug 11 03:36:48 volumio volumio[4744]: cset: "system" cpuset of CPUSPEC(1-3) with 105 tasks running Aug 11 03:36:48 volumio volumio[4744]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Aug 11 03:36:48 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:36:48 volumio volumio[4539]: error: updateQueue error: null Aug 11 03:36:48 volumio volumio[4539]: info: MPD running with PID4688 Aug 11 03:36:48 volumio volumio[4539]: ,establishing connection Aug 11 03:36:48 volumio volumio[4539]: info: minidlna: systemctl start minidlna.service succeeded. Aug 11 03:36:48 volumio volumio[4539]: info: Starting Shairport Sync Aug 11 03:36:48 volumio volumio[4539]: info: Starting Shairport Sync Aug 11 03:36:48 volumio sudo[4801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Aug 11 03:36:48 volumio sudo[4801]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:48 volumio sudo[4803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 11 03:36:48 volumio volumio[4539]: info: Starting Shairport Sync Aug 11 03:36:48 volumio sudo[4808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 11 03:36:48 volumio sudo[4808]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:48 volumio sudo[4803]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36:48 volumio systemd[1]: Reloading. Aug 11 03:36:48 volumio volumio[4539]: error: updateQueue error: null Aug 11 03:36:48 volumio sudo[4813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 11 03:36:48 volumio sudo[4813]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 11 03:36: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. Aug 11 03:36:49 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Aug 11 03:36:49 volumio sudo[4801]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:49 volumio volumio[4539]: info: succeeded Aug 11 03:36:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 11 03:36:49 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 11 03:36:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 11 03:36:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 11 03:36:49 volumio sudo[4808]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:49 volumio sudo[4813]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:49 volumio sudo[4803]: pam_unix(sudo:session): session closed for user root Aug 11 03:36:49 volumio volumio[4539]: info: Shairport-Sync Started Aug 11 03:36:49 volumio volumio[4539]: Error adding Membership: Error: addMembership EINVAL Aug 11 03:36:49 volumio volumio[4539]: info: Shairport-Sync Started Aug 11 03:36:49 volumio volumio[4539]: info: Shairport-Sync Started Aug 11 03:36:51 volumio volumio[4539]: info: go-librespot daemon successfully initialized Aug 11 03:36:54 volumio volumio[4539]: info: Initializing connection to go-librespot Websocket Aug 11 03:36:59 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 11 03:37:01 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 11 03:37:03 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 11 03:37:05 volumio wpa_supplicant[923]: RRM: Ignoring radio measurement request: Not RRM network Aug 11 03:37:10 volumio ntpd[6264]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 11 03:37:14 volumio volumio[4539]: info: Discovery: adding 7a13d819-6442-4117-94d3-acb038a3cde3 Aug 11 03:37:14 volumio volumio[4539]: info: Discovery: Found device Volumio Aug 11 03:37:14 volumio volumio[4539]: info: CoreCommandRouter::volumioGetState Aug 11 03:37:14 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:37:15 volumio volumio[4539]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Aug 11 03:37:15 volumio volumio[4539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 11 03:37:15 volumio volumio[4539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 11 03:37:16 volumio go-librespot[4784]: time="2024-08-11T03:37:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Aug 11 03:37:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 11 03:37:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 11 03:37:17 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 11 03:37:17 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:37:17 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 11 03:37:17 volumio volumio[4539]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Aug 11 03:37:17 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:37:17 volumio volumio[4539]: info: ----- MyVolumio plugins startup ---- Aug 11 03:37:17 volumio volumio[4539]: info: ------------------------------------------- Aug 11 03:37:17 volumio volumio[4539]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 11 03:37:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 11 03:37:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Aug 11 03:37:19 volumio systemd[1]: Stopped go-librespot Daemon. Aug 11 03:37:19 volumio systemd[1]: Started go-librespot Daemon. Aug 11 03:37:19 volumio go-librespot[4835]: Librespot-go daemon starting... Aug 11 03:37:19 volumio go-librespot[4835]: time="2024-08-11T03:37:19+02:00" level=info msg="generated new device id: f58d605a9562920f138de54fa27fc2a5256fbf1c" Aug 11 03:37:19 volumio go-librespot[4835]: time="2024-08-11T03:37:19+02:00" level=debug msg="stored credentials found for rocqqoxbqr2vt9yo7ckmuz0ji" Aug 11 03:37:25 volumio volumio[4539]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.742&uuid=90bf126e5180f5dc360fe221a4527fb2" http://updates.volumio.org/downloader-v1/track-device Aug 11 03:37:25 volumio volumio[4539]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 11 03:37:25 volumio volumio[4539]: Dload Upload Total Spent Left Speed Aug 11 03:37:25 volumio volumio[4539]: [2.2K blob data] Aug 11 03:37:25 volumio volumio[4539]: retrying in 5 seconds, trial 0 Aug 11 03:37:25 volumio volumio[4539]: info: Volumio Calling Home Aug 11 03:37:37 volumio volumio[4539]: error: MyVolumio Plugin failed to start in a timely fashion Aug 11 03:37:37 volumio volumio[4539]: info: BOOT COMPLETED Aug 11 03:37:37 volumio volumio[4539]: [Metrics] CommandRouter: 62s 977.24ms Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::volumiosetStartupVolume Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 11 03:37:37 volumio volumio[4539]: info: VolumeController:: Setting startup Volume 25 Aug 11 03:37:37 volumio volumio[4539]: info: VolumeController::SetAlsaVolume25 Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::Close All Modals sent Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::Close All Modals sent Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::volumioGetState Aug 11 03:37:37 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:37:37 volumio volumio[4539]: info: CoreStateMachine::pushState Aug 11 03:37:37 volumio volumio[4539]: info: CorePlayQueue::getTrack 0 Aug 11 03:37:37 volumio volumio[4539]: info: CoreCommandRouter::volumioPushState Aug 11 03:37:38 volumio ntpd[6264]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 11 03:37:38 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 11 03:37:38 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 11 03:37:38 volumio volumio[4539]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 11 03:37:42 volumio volumio[4539]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 11 03:37:42 volumio volumio[4539]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Aug 11 03:37:42 volumio volumio[4539]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Aug 11 03:37:42 volumio volumio[4539]: errno: -3007, Aug 11 03:37:42 volumio volumio[4539]: code: 'ENOTFOUND', Aug 11 03:37:42 volumio volumio[4539]: syscall: 'getaddrinfo', Aug 11 03:37:42 volumio volumio[4539]: hostname: 'ws.audioscrobbler.com' Aug 11 03:37:42 volumio volumio[4539]: } Aug 11 03:37:42 volumio volumio[4539]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 11 03:37:43 volumio sudo[4897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-11 03:36 Aug 11 03:37:43 volumio sudo[4897]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"