-- Logs begin at Sat 2024-07-20 22:24:41 EDT, end at Sat 2024-07-20 22:26:29 EDT. -- Jul 20 22:25:00 volumio wireless.js[939]: trying... Jul 20 22:25:00 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 20 22:25:00 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:00 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:00 volumio wireless.js[939]: Connected to: ----SpyLinux Jul 20 22:25:00 volumio wireless.js[939]: ---- Jul 20 22:25:00 volumio sudo[1273]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 22:25:00 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:00 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:00 volumio wireless.js[939]: ... joined AP, wlan0 IPv4 is 192.168.1.76, ipV6 is undefined Jul 20 22:25:00 volumio wireless.js[939]: It's done! AP Jul 20 22:25:00 volumio systemd[1]: Started Wireless Services. Jul 20 22:25:00 volumio systemd[1]: Started Volumio Backend Module. Jul 20 22:25:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 20 22:25:00 volumio systemd[1]: Started Volumio Cpu Tweaker. Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: Setting RT Priority for mpd Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: pid 35's current scheduling policy: SCHED_OTHER Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: pid 35's current scheduling priority: 0 Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: Setting MPD Affinity Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: pid 3's current affinity mask: f Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 20 22:25:00 volumio volumio-cpu-tweak[1279]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Jul 20 22:25:00 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 20 22:25:01 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:01 volumio volumio[1278]: info: ----- Volumio3 ---- Jul 20 22:25:01 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:01 volumio volumio[1278]: info: ----- System startup ---- Jul 20 22:25:01 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:01 volumio ntpd[1100]: Listen normally on 3 wlan0 192.168.1.76:123 Jul 20 22:25:01 volumio ntpd[1100]: new interface(s) found: waking up resolver Jul 20 22:25:01 volumio volumio[1278]: info: MYVOLUMIO Environment detected Jul 20 22:25:01 volumio volumio[1278]: info: Plugin folders cleanup Jul 20 22:25:01 volumio volumio[1278]: info: Scanning into folder /volumio/app/plugins/ Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category audio_interface Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category miscellanea Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category music_service Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category plugins.json Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category system_controller Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category user_interface Jul 20 22:25:01 volumio volumio[1278]: info: Scanning into folder /data/plugins/ Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category audio_interface Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category music_service Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category system_controller Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category system_hardware Jul 20 22:25:01 volumio volumio[1278]: info: Scanning category user_interface Jul 20 22:25:01 volumio volumio[1278]: info: Plugin folders cleanup completed Jul 20 22:25:01 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:01 volumio volumio[1278]: info: ----- Core plugins startup ---- Jul 20 22:25:01 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:01 volumio volumio[1278]: info: Loading plugins from folder /volumio/app/plugins/ Jul 20 22:25:01 volumio volumio[1278]: info: Adding plugin upnp to MyMusic Plugins Jul 20 22:25:01 volumio volumio[1278]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 20 22:25:01 volumio volumio[1278]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 20 22:25:01 volumio volumio[1278]: info: Loading plugins from folder /data/plugins/ Jul 20 22:25:01 volumio volumio[1278]: info: Loading plugin "system"... Jul 20 22:25:01 volumio volumio[1278]: info: Loading plugin "appearance"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "network"... Jul 20 22:25:02 volumio volumio[1278]: info: Refreshing Cached IP Addresses Jul 20 22:25:02 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 20 22:25:02 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:02 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:02 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 22:25:02 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "services"... Jul 20 22:25:02 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "alsa_controller"... Jul 20 22:25:02 volumio sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 20 22:25:02 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:02 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "wizard"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "networkfs"... Jul 20 22:25:02 volumio volumio[1278]: info: Starting Udev Watcher for removable devices Jul 20 22:25:02 volumio volumio[1278]: info: Ignoring mount for partition: boot Jul 20 22:25:02 volumio volumio[1278]: info: Ignoring mount for partition: volumio Jul 20 22:25:02 volumio volumio[1278]: info: Ignoring mount for partition: volumio_data Jul 20 22:25:02 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "volumio_command_line_client"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "upnp"... Jul 20 22:25:02 volumio volumio[1278]: info: [1721528702245] Starting Upmpd Daemon Jul 20 22:25:02 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "my_music"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "mpd"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "upnp_browser"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "alarm-clock"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "airplay_emulation"... Jul 20 22:25:02 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "last_100"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "webradio"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "i2s_dacs"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "volumiodiscovery"... Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 20 22:25:02 volumio node[1278]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 20 22:25:02 volumio node[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** For more information see Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 20 22:25:02 volumio volumio[1278]: *** WARNING *** For more information see Jul 20 22:25:02 volumio node[1278]: *** WARNING *** For more information see Jul 20 22:25:02 volumio node[1278]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 20 22:25:02 volumio node[1278]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 20 22:25:02 volumio node[1278]: *** WARNING *** For more information see Jul 20 22:25:02 volumio volumio[1278]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 20 22:25:02 volumio volumio[1278]: info: Discovery: Started advertising with name: Volumio Jul 20 22:25:02 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "bandcamp"... Jul 20 22:25:02 volumio volumio[1278]: info: Loading plugin "calmradio"... Jul 20 22:25:03 volumio volumio[1278]: info: Loading plugin "jellyfin"... Jul 20 22:25:03 volumio volumio[1278]: info: Loading plugin "jellyfin_server"... Jul 20 22:25:03 volumio volumio[1278]: info: Loading plugin "mixcloud"... Jul 20 22:25:03 volumio volumio[1278]: info: Loading plugin "soundcloud"... Jul 20 22:25:03 volumio volumio[1278]: info: Loading plugin "spop"... Jul 20 22:25:04 volumio nmbd[1042]: [2024/07/20 22:25:04.026148, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 20 22:25:04 volumio nmbd[1042]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 20 22:25:04 volumio systemd[1]: Started Samba NMB Daemon. Jul 20 22:25:04 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 20 22:25:04 volumio winbindd[1321]: [2024/07/20 22:25:04.122701, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 20 22:25:04 volumio winbindd[1321]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 20 22:25:04 volumio winbindd[1321]: [2024/07/20 22:25:04.134528, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 20 22:25:04 volumio winbindd[1321]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 20 22:25:04 volumio systemd[1]: Started Samba Winbind Daemon. Jul 20 22:25:04 volumio systemd[1]: Starting Samba SMB Daemon... Jul 20 22:25:04 volumio volumio[1278]: info: Loading plugin "squeezelite_mc"... Jul 20 22:25:04 volumio smbd[1326]: [2024/07/20 22:25:04.307167, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 20 22:25:04 volumio smbd[1326]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 20 22:25:04 volumio systemd[1]: Started Samba SMB Daemon. Jul 20 22:25:04 volumio systemd[1]: Reached target Multi-User System. Jul 20 22:25:04 volumio systemd[1]: Reached target Graphical Interface. Jul 20 22:25:04 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 20 22:25:04 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 20 22:25:04 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 20 22:25:04 volumio systemd[1]: Startup finished in 11.248s (kernel) + 23.074s (userspace) = 34.323s. Jul 20 22:25:04 volumio volumio[1278]: info: Loading plugin "youtube2"... Jul 20 22:25:04 volumio volumio[1278]: info: Loading plugin "ytmusic"... Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "now_playing"... Jul 20 22:25:05 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "outputs"... Jul 20 22:25:05 volumio volumio-remote-updater[947]: [2024-07-20 22:25:05] [connect] Successful connection Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "albumart"... Jul 20 22:25:05 volumio volumio[1278]: info: Plugin example_plugin is not enabled Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "inputs"... Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "updater_comm"... Jul 20 22:25:05 volumio volumio[1278]: info: Plugin mpdemulation is not enabled Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "rest_api"... Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "websocket"... Jul 20 22:25:05 volumio volumio[1278]: info: Starting Socket.io Server version 2.3.0 Jul 20 22:25:05 volumio volumio[1278]: info: Loading plugin "fusiondsp"... Jul 20 22:25:05 volumio volumio[1278]: Forking 3 albumart workers Jul 20 22:25:05 volumio volumio[1278]: info: Applying required configuration parameters for plugin fusiondsp Jul 20 22:25:05 volumio volumio[1278]: Starting albumart workers Jul 20 22:25:05 volumio volumio[1278]: Starting albumart workers Jul 20 22:25:06 volumio volumio[1278]: info: Loading plugin "80s80s"... Jul 20 22:25:06 volumio volumio[1278]: Starting albumart workers Jul 20 22:25:06 volumio volumio[1278]: info: Applying required configuration parameters for plugin 80s80s Jul 20 22:25:06 volumio volumio[1278]: info: [1721528706141] [80s80s] API delay: 30 Jul 20 22:25:06 volumio volumio[1278]: info: Loading plugin "RoonBridge"... Jul 20 22:25:06 volumio volumio[1278]: info: Applying required configuration parameters for plugin RoonBridge Jul 20 22:25:06 volumio volumio[1278]: info: Loading plugin "dmd2_music"... Jul 20 22:25:06 volumio volumio[1278]: info: Loading plugin "motherearthradio"... Jul 20 22:25:06 volumio volumio[1278]: info: Applying required configuration parameters for plugin motherearthradio Jul 20 22:25:06 volumio volumio[1278]: info: [1721528706711] [MotherEarth] API delay: 5 Jul 20 22:25:06 volumio volumio[1278]: info: Loading plugin "pandora"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin pandora Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "personal_radio"... Jul 20 22:25:07 volumio volumio[1278]: info: ControllerPersonalRadio::constructor Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "podcast"... Jul 20 22:25:07 volumio volumio[1278]: info: ControllerPodcast::constructor Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "rad357pl"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin rad357pl Jul 20 22:25:07 volumio volumio[1278]: info: [1721528707467] [rad357pl] API delay: 5 Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "radio_paradise"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin radio_paradise Jul 20 22:25:07 volumio volumio[1278]: info: [1721528707545] [RadioParadise] API delay: 5 Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "audiophonicsonoff"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin audiophonicsonoff Jul 20 22:25:07 volumio volumio[1278]: info: Audiophonics on/off initiated Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "backup_restore"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin backup_restore Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "audiophonics_evo_sabre"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "gpio_control"... Jul 20 22:25:07 volumio volumio[1278]: info: Applying required configuration parameters for plugin gpio_control Jul 20 22:25:07 volumio volumio[1278]: info: Loading plugin "mpd_oled"... Jul 20 22:25:08 volumio volumio[1278]: info: Applying required configuration parameters for plugin mpd_oled Jul 20 22:25:08 volumio volumio[1278]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jul 20 22:25:08 volumio volumio[1278]: info: Loading plugin "pirateaudio"... Jul 20 22:25:08 volumio volumio[1278]: info: Applying required configuration parameters for plugin pirateaudio Jul 20 22:25:08 volumio volumio[1278]: info: Loading plugin "rotaryencoder2"... Jul 20 22:25:08 volumio volumio[1278]: info: Loading plugin "Systeminfo"... Jul 20 22:25:08 volumio volumio[1278]: info: Loading plugin "peppyspectrum"... Jul 20 22:25:08 volumio volumio[1278]: info: Loading plugin "touch_display"... Jul 20 22:25:08 volumio volumio[1278]: info: Applying required configuration parameters for plugin touch_display Jul 20 22:25:08 volumio volumio[1278]: info: Loading i18n strings for locale fr Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: Updating browse sources language Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::initPlayerControls Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: Express server listening on port 3000 Jul 20 22:25:08 volumio volumio[1278]: [Metrics] WebUI: 7s 348.30ms Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::resetVolumioState Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::getcurrentVolume Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioRetrievevolume Jul 20 22:25:08 volumio volumio[1278]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 20 22:25:08 volumio volumio[1278]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jul 20 22:25:08 volumio volumio[1278]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 20 22:25:08 volumio volumio-remote-updater[947]: [2024-07-20 22:25:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1721528705 101 Jul 20 22:25:08 volumio volumio[1278]: 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: 4 Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::updateTrackBlock Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrackBlock Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioRetrievevolume Jul 20 22:25:08 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 20 22:25:08 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::setRepeat true single undefined Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::setRandom null Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:08 volumio volumio[1278]: info: Setting Device type: Raspberry PI Jul 20 22:25:08 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:08 volumio volumio[1278]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 20 22:25:08 volumio volumio[1278]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Jul 20 22:25:08 volumio volumio[1278]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 20 22:25:08 volumio volumio[1278]: info: Discovery: adding 45fd82af-91a5-4ebb-a6df-b5f868576664 Jul 20 22:25:08 volumio volumio[1278]: info: Discovery: Found device Volumio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: Completed loading Core Plugins Jul 20 22:25:08 volumio volumio[1278]: info: Preparing to generate the ALSA configuration file Jul 20 22:25:08 volumio volumio[1278]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 20 22:25:08 volumio volumio[1278]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 20 22:25:08 volumio volumio[1278]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 20 22:25:08 volumio volumio[1278]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 20 22:25:08 volumio volumio[1278]: info: Reading ALSA contributions from plugins. Jul 20 22:25:08 volumio volumio[1278]: info: Asound.conf file unchanged, so no further update is needed Jul 20 22:25:08 volumio volumio[1278]: info: Output device has changed, restarting MPD Jul 20 22:25:08 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:25:08 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:08 volumio volumio[1278]: info: Output device has changed, restarting Shairport Sync Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:08 volumio sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:25:08 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:08 volumio volumio[1278]: info: ___________ START PLUGINS ___________ Jul 20 22:25:08 volumio volumio[1278]: info: ControllerMpd::onStart: Initializing MPD Jul 20 22:25:08 volumio volumio[1278]: info: Creating MPD Configuration file Jul 20 22:25:08 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:25:08 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708816] CoreMusicLibrary::Adding element Serveurs Média Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:08 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:08 volumio sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:25:08 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio systemd[1]: Listening on mpd.socket. Jul 20 22:25:08 volumio systemd[1]: Starting Music Player Daemon... Jul 20 22:25:08 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 20 22:25:08 volumio systemd[1]: mpd.service: Succeeded. Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:08 volumio systemd[1]: Starting Music Player Daemon... Jul 20 22:25:08 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708861] CoreMusicLibrary::Adding element Last_100 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708862] CoreMusicLibrary::Adding element Webradio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:08 volumio volumio[1278]: info: Initializing BBC Radios Jul 20 22:25:08 volumio sudo[1433]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 20 22:25:08 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio sudo[1433]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 20 22:25:08 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708902] CoreMusicLibrary::Adding element Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: info: Adding Calm Radio to Browse Sources Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708903] CoreMusicLibrary::Adding element Calm Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 22:25:08 volumio volumio[1278]: info: Discovery: Getting this device information Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:08 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708907] CoreMusicLibrary::Adding element Jellyfin Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: info: [jellyfin] Initialized plugin with device info: {"id":"45fd82af-91a5-4ebb-a6df-b5f868576664","host":"http://192.168.1.76","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"10","mute":false,"artist":"","track":"","albumart":"http://192.168.1.76/albumart"}} Jul 20 22:25:08 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708924] CoreMusicLibrary::Adding element Mixcloud Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Jul 20 22:25:08 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708928] CoreMusicLibrary::Adding element SoundCloud Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:08 volumio volumio[1278]: info: Creating Spotify config file Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: [squeezelite_mc] Starting proxy server... Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708946] CoreMusicLibrary::Adding element YouTube2 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708949] CoreMusicLibrary::Adding element YouTube Music Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:08 volumio volumio[1278]: info: [now-playing] ConfigUpdater: config is up to date. Jul 20 22:25:08 volumio volumio[1278]: info: Loading i18n strings for locale fr Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:08 volumio volumio[1278]: info: FusionDsp - mixtype--------------------- Software Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708964] CoreMusicLibrary::Adding element 80s80s Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:08 volumio volumio[1278]: info: Stopping AccessToken refresher cron for DMD2 Music Jul 20 22:25:08 volumio sudo[1454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 20 22:25:08 volumio sudo[1454]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:08 volumio systemd[1]: Starting Jellyfin... Jul 20 22:25:08 volumio volumio[1278]: info: AccessToken refresher cron started for DMD2 Music Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708988] CoreMusicLibrary::Adding element Mother Earth Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708989] CoreMusicLibrary::Adding element Pandora Radio Jul 20 22:25:08 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:08 volumio volumio[1278]: Cannot find translation for source Pandora Radio Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708990] ControllerPandora::initializeMQTT Jul 20 22:25:08 volumio volumio[1278]: info: [1721528708990] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jul 20 22:25:08 volumio systemd[1]: Started Jellyfin. Jul 20 22:25:08 volumio systemd[1]: Started RoonBridge. Jul 20 22:25:08 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio sudo[1454]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709016] CoreMusicLibrary::Adding element Personal Radio Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Pandora Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Personal Radio Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709023] CoreMusicLibrary::Adding element Podcast Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Pandora Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Personal Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Podcast Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709031] CoreMusicLibrary::Adding element RADIO 357 Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Pandora Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Personal Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Podcast Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source RADIO 357 Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709038] CoreMusicLibrary::Adding element Radio Paradise Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Bandcamp Discover Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Calm Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Jellyfin Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mixcloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source SoundCloud Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube2 Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source YouTube Music Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source 80s80s Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Mother Earth Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Pandora Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Personal Radio Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Podcast Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source RADIO 357 Jul 20 22:25:09 volumio volumio[1278]: Cannot find translation for source Radio Paradise Jul 20 22:25:09 volumio volumio[1278]: info: Configuring GPIO pins Jul 20 22:25:09 volumio volumio[1278]: error: Plugin audiophonicsonoff failed to start! Error: EINVAL: invalid argument, write Jul 20 22:25:09 volumio volumio[1278]: info: EVO SABRE : Starting Plugin Jul 20 22:25:09 volumio volumio[1278]: info: Loading i18n strings for locale fr Jul 20 22:25:09 volumio kernel: export_store: invalid GPIO 4 Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 20 22:25:09 volumio volumio[1278]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Jul 20 22:25:09 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Jul 20 22:25:09 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 20 22:25:09 volumio volumio[1278]: info: Loading i18n strings for locale fr Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:09 volumio volumio[1278]: info: Volumio Calling Home Jul 20 22:25:09 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:09 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Proxy server started on port 44239 Jul 20 22:25:09 volumio volumio[1278]: info: Preparing to generate the ALSA configuration file Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709164] ControllerPandora::initialSetup Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709173] ControllerPandora::PandoraHandler::init Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709174] ControllerPandora::PandoraHandler::loadErrorCodes Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709202] [Pandora] PandoraHandler::setMaxStationTracks: 16 Jul 20 22:25:09 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 20 22:25:09 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 20 22:25:09 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709214] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709215] ControllerPandora::flushPandora Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::getQueue Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::clearPlayQueue Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::saveQueue Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioPushQueue Jul 20 22:25:09 volumio volumio[1278]: info: [1721528709221] ControllerPandora::validateAndSetAccountOptions Jul 20 22:25:09 volumio volumio[1278]: error: [1721528709221] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioRetrievevolume Jul 20 22:25:09 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 20 22:25:09 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: [now-playing] App is listening on port 4004. Jul 20 22:25:09 volumio volumio[1278]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 20 22:25:09 volumio systemd[1]: Reloading. Jul 20 22:25:09 volumio volumio[1278]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 20 22:25:09 volumio volumio[1278]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 20 22:25:09 volumio volumio[1278]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 20 22:25:09 volumio volumio[1278]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 20 22:25:09 volumio volumio[1278]: info: Reading ALSA contributions from plugins. Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: No backlight interface detected. Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:09 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Server discovery started Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Player finder started Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Jul 20 22:25:09 volumio volumio[1278]: info: Pirate audio service (daemon) started Jul 20 22:25:09 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:25:09 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:25:09 volumio volumio[1472]: Traceback (most recent call last): Jul 20 22:25:09 volumio volumio[1472]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:09 volumio volumio[1472]: import ST7789 # v0.0.6 Jul 20 22:25:09 volumio volumio[1472]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:09 volumio sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 20 22:25:09 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: Volumio called home Jul 20 22:25:09 volumio volumio[1278]: info: Spotify config file written Jul 20 22:25:09 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 20 22:25:09 volumio sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 20 22:25:09 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 20 22:25:09 volumio sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE"] Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"5","mixerType":"Software","mixer":"SoftMaster"} Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 20 22:25:09 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. Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jul 20 22:25:09 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 20 22:25:09 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 20 22:25:09 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jul 20 22:25:09 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jul 20 22:25:09 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 20 22:25:09 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 20 22:25:09 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:09 volumio sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 20 22:25:09 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:09 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:09 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:09 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:09 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio systemd[1]: Reloading. Jul 20 22:25:09 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:09 volumio sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:09 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:09 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio RoonBridge[1456]: 00:00:00.005 Warn: get lock file path: /tmp/.rnbgem0- Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:09 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:09 volumio volumio[1278]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: systemctl daemon-reload succeeded. Jul 20 22:25:09 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jul 20 22:25:09 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 20 22:25:09 volumio volumio[1278]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 20 22:25:09 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Jul 20 22:25:09 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 20 22:25:09 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio volumio[1278]: info: Asound.conf file unchanged, so no further update is needed Jul 20 22:25:09 volumio volumio[1278]: info: Output device has changed, restarting MPD Jul 20 22:25:09 volumio sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Jul 20 22:25:09 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:25:09 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:09 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. Jul 20 22:25:09 volumio volumio[1278]: info: Output device has changed, restarting Shairport Sync Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:09 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:09 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:25:09 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:09 volumio RoonBridge[1456]: 00:00:00.262 Trace: [childprocess] using unix child process Jul 20 22:25:09 volumio RoonBridge[1456]: 00:00:00.289 Debug: PathForResource, filename: ../.update Jul 20 22:25:09 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:09 volumio volumio[1278]: info: Preparing to generate the ALSA configuration file Jul 20 22:25:10 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jul 20 22:25:10 volumio volumio[1278]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 20 22:25:10 volumio volumio[1278]: info: Reading ALSA contributions from plugins. Jul 20 22:25:10 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 20 22:25:10 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio volumio[1278]: (node:1278) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 20 22:25:10 volumio volumio[1278]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 20 22:25:10 volumio volumio[1278]: (node:1278) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Jul 20 22:25:10 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:10 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.369 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jul 20 22:25:10 volumio sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:10 volumio RoonBridge[1456]: Initializing Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.374 Debug: PathForResource, filename: RAATServer Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.374 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.374 Debug: PathForResource, filename: RoonBridgeHelper Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.375 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 20 22:25:10 volumio sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1. Jul 20 22:25:10 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio volumio[1278]: info: Asound.conf file unchanged, so no further update is needed Jul 20 22:25:10 volumio volumio[1278]: info: Output device has changed, restarting MPD Jul 20 22:25:10 volumio systemd[1]: Reloading. Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.407 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jul 20 22:25:10 volumio sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:25:10 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio volumio[1278]: info: Output device has changed, restarting Shairport Sync Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:10 volumio sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:25:10 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.453 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jul 20 22:25:10 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:10 volumio volumio[1278]: info: Preparing to generate the ALSA configuration file Jul 20 22:25:10 volumio volumio[1278]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 20 22:25:10 volumio volumio[1278]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 20 22:25:10 volumio volumio[1278]: info: Reading ALSA contributions from plugins. Jul 20 22:25:10 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:25:10 volumio volumio[1278]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio mpd[1438]: Jul 20 22:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 20 22:25:10 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:10 volumio RoonBridge[1456]: Not Running (.o) Jul 20 22:25:10 volumio volumio[1278]: info: Asound.conf file unchanged, so no further update is needed Jul 20 22:25:10 volumio volumio[1278]: info: Output device has changed, restarting MPD Jul 20 22:25:10 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:10 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 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. Jul 20 22:25:10 volumio sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:25:10 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio volumio[1278]: info: Output device has changed, restarting Shairport Sync Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:10 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:25:10 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio volumio[1278]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 20 22:25:10 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio RoonBridge[1456]: Running Jul 20 22:25:10 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:10 volumio volumio[1278]: info: Starting Shairport Sync Jul 20 22:25:10 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 20 22:25:10 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio systemd[1]: Started Music Player Daemon. Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 2. Jul 20 22:25:10 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio volumio[1278]: info: EVO SABRE : systemd cmd daemon-reload : success Jul 20 22:25:10 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Jul 20 22:25:10 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:10 volumio systemd[1]: Reloading. Jul 20 22:25:10 volumio volumio[1278]: info: MPD running with PID1438 Jul 20 22:25:10 volumio volumio[1278]: ,establishing connection Jul 20 22:25:10 volumio volumio[1278]: error: MPD error: The expression evaluated to a falsy value: Jul 20 22:25:10 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:10 volumio volumio[1278]: error: The expression evaluated to a falsy value: Jul 20 22:25:10 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:10 volumio volumio[1278]: error: MPD error: The expression evaluated to a falsy value: Jul 20 22:25:10 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:10 volumio volumio[1278]: error: The expression evaluated to a falsy value: Jul 20 22:25:10 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:10 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:25:10 volumio RoonBridge[1456]: 00:00:00.012 Warn: get lock file path: /tmp/.rnbhgem0- Jul 20 22:25:10 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. Jul 20 22:25:10 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:10 volumio volumio[1278]: verbose: New Socket.io Connection to 192.168.1.76 from 192.168.1.70 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:10 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 20 22:25:10 volumio volumio[1278]: info: Listing playlists Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::volumioGetVisibleSources Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:10 volumio volumio[1278]: info: CoreCommandRouter::volumioGetQueue Jul 20 22:25:10 volumio volumio[1278]: info: CoreStateMachine::getQueue Jul 20 22:25:10 volumio volumio[1278]: info: CorePlayQueue::getQueue Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:10 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 3. Jul 20 22:25:10 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Jellyfin version: 10.9.6 Jul 20 22:25:10 volumio systemd[1]: Started OLED Display Service for EVO SABRE. Jul 20 22:25:10 volumio sudo[1691]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio Jul 20 22:25:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 20 22:25:10 volumio sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:10 volumio systemd[1]: Stopping Music Player Daemon... Jul 20 22:25:10 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:10 volumio systemd[1]: Started Volumio Kiosk. Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Operating system: Raspbian GNU/Linux 10 (buster) Jul 20 22:25:10 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio systemd[1]: Started go-librespot Daemon. Jul 20 22:25:10 volumio go-librespot[1699]: Librespot-go daemon starting... Jul 20 22:25:10 volumio sudo[1517]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Architecture: Arm64 Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: 64-Bit Process: False Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: User Interactive: True Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Processor count: 4 Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Program data path: /opt/jellyfin/data Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Log directory path: /opt/jellyfin/log Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Config directory path: /opt/jellyfin/config Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Cache path: /opt/jellyfin/cache Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Jul 20 22:25:10 volumio jellyfin[1452]: [22:25:10] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Jul 20 22:25:10 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:10 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:10 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:10 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 20 22:25:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 20 22:25:11 volumio systemd[1]: mpd.service: Succeeded. Jul 20 22:25:11 volumio systemd[1]: Stopped Music Player Daemon. Jul 20 22:25:11 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio systemd[1]: Starting Music Player Daemon... Jul 20 22:25:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 20 22:25:11 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1592]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1278]: info: camilladsp spawned new process with pid 1744, instance 1, run: true Jul 20 22:25:11 volumio volumio[1278]: info: camilladsp service started and running in background, instance 1 Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:11 volumio sudo[1727]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 20 22:25:11 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1278]: info: FusionDsp - AAAAAAAAAAAAAA-> 48000 <-AAAAAAAAAAAAA Jul 20 22:25:11 volumio volumio[1278]: info: FusionDsp loaded Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 22:25:11 volumio volumio[1278]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 20 22:25:11 volumio volumio[1278]: info: Adding Signal Path Element [object Object] Jul 20 22:25:11 volumio volumio[1278]: info: Adding fusiondspeq DSP Signal Path Element Jul 20 22:25:11 volumio volumio[1278]: info: FusionDsp - ---- installed callbackRead Jul 20 22:25:11 volumio volumio[1278]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 20 22:25:11 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 20 22:25:11 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jul 20 22:25:11 volumio startx[1693]: X.Org X Server 1.20.4 Jul 20 22:25:11 volumio startx[1693]: X Protocol Version 11, Revision 0 Jul 20 22:25:11 volumio startx[1693]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 20 22:25:11 volumio startx[1693]: Current Operating System: Linux volumio 6.1.77-v8+ #1730 SMP PREEMPT Thu Feb 8 15:26:11 GMT 2024 aarch64 Jul 20 22:25:11 volumio startx[1693]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 smsc95xx.macaddr=2C:CF:67:08:C3:8B vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no cfg80211.ieee80211_regdom=CA Jul 20 22:25:11 volumio startx[1693]: Build Date: 04 April 2023 07:50:56AM Jul 20 22:25:11 volumio startx[1693]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 20 22:25:11 volumio startx[1693]: Current version of pixman: 0.36.0 Jul 20 22:25:11 volumio startx[1693]: Before reporting problems, check http://wiki.x.org Jul 20 22:25:11 volumio startx[1693]: to make sure that you have the latest version. Jul 20 22:25:11 volumio startx[1693]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 20 22:25:11 volumio startx[1693]: (++) from command line, (!!) notice, (II) informational, Jul 20 22:25:11 volumio startx[1693]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 20 22:25:11 volumio startx[1693]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jul 20 22:25:11 2024 Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: Error adding Membership: Error: addMembership EINVAL Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: info: Shairport-Sync Started Jul 20 22:25:11 volumio volumio[1278]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Jul 20 22:25:11 volumio startx[1693]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 20 22:25:11 volumio startx[1693]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 20 22:25:11 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jul 20 22:25:11 volumio systemd[1]: Started FusionDsp Daemon. Jul 20 22:25:11 volumio go-librespot[1699]: time="2024-07-20T22:25:11-04:00" level=info msg="generated new device id: 3e99a3a8d64ff30dc871f35413b3645d9ac3f764" Jul 20 22:25:11 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio volumio[1704]: Traceback (most recent call last): Jul 20 22:25:11 volumio volumio[1704]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:11 volumio volumio[1704]: import ST7789 # v0.0.6 Jul 20 22:25:11 volumio volumio[1704]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:11 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jul 20 22:25:11 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio kernel: process '/opt/squeezelite/squeezelite' started with executable stack Jul 20 22:25:11 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jul 20 22:25:11 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1278]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 20 22:25:11 volumio volumio[1278]: info: touch_display: Volumio Kiosk started. Jul 20 22:25:11 volumio sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Jul 20 22:25:11 volumio sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:11 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:11 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jul 20 22:25:11 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:11 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:11 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:11 volumio volumio[1278]: error: FusionDsp - WebSocket error: [object Object] Jul 20 22:25:11 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 20 22:25:11 volumio volumio[1278]: info: touch_display: X display number found: 0 Jul 20 22:25:11 volumio lircd-setup[1768]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jul 20 22:25:11 volumio lircd-setup[1768]: parser = configparser.SafeConfigParser() Jul 20 22:25:11 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Jul 20 22:25:11 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:11 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 4. Jul 20 22:25:11 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:11 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:11 volumio systemd[1]: lircd-setup.service: Succeeded. Jul 20 22:25:11 volumio systemd[1]: Started lircd(8) initialization helper tool. Jul 20 22:25:11 volumio systemd[1]: Started evo_remote.service. Jul 20 22:25:11 volumio sudo[1765]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: lircd: Opening log, level: Info Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Version: lircd 0.10.1 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: System info: Linux volumio 6.1.77-v8+ #1730 SMP PREEMPT Thu Feb 8 15:26:11 GMT 2024 aarch64 GNU/Linux Jul 20 22:25:11 volumio volumio[1278]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Jul 20 22:25:11 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Jul 20 22:25:11 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: Initial device: /dev/lirc0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: Initial device: /dev/lirc0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: [lirc] protocol is enabled Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: driver: default Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Info: lircd: Opening log, level: Info Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: output: /var/run/lirc/lircd Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: nodaemon: 1 Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Warning: Running as root Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: logfile: syslog Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: immediate-init: 0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: permission: 666 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: driver-options: Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: listen: 0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: connect: (null) Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: userelease: 0 Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Info: Using remote: ApEvo. Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: effective_user: (null) Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: release_suffix: _EVUP Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: allow_simulate: 0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: repeat_max: 600 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Options: dynamic_codes: (null) Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Current driver: default Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Driver API version: 3 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Driver version: 0.10.0 Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: lircd: Opening log, level: Info Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Warning: Running as root Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: Using remote: ApEvo. Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 20 22:25:11 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Jul 20 22:25:11 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jul 20 22:25:11 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Jul 20 22:25:11 volumio volumio[1278]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 20 22:25:11 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1278]: info: camilladsp spawned new process with pid 1795, instance 1, run: true Jul 20 22:25:11 volumio volumio[1278]: info: touch_display: X display number found: 0 Jul 20 22:25:11 volumio lircd-setup[1786]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jul 20 22:25:11 volumio lircd-setup[1786]: parser = configparser.SafeConfigParser() Jul 20 22:25:11 volumio go-librespot[1699]: time="2024-07-20T22:25:11-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Jul 20 22:25:11 volumio go-librespot[1699]: time="2024-07-20T22:25:11-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 20 22:25:11 volumio go-librespot[1699]: time="2024-07-20T22:25:11-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 20 22:25:11 volumio go-librespot[1699]: time="2024-07-20T22:25:11-04:00" level=debug msg="zeroconf server listening on port 35743" Jul 20 22:25:11 volumio systemd[1]: lircd-setup.service: Succeeded. Jul 20 22:25:11 volumio systemd[1]: Started lircd(8) initialization helper tool. Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:11 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:11 volumio systemd[1]: Started evo_irexec.service. Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Notice: accepted new client on /var/run/lirc/lircd Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Notice: accepted new client on /var/run/lirc/lircd Jul 20 22:25:11 volumio lircd[1776]: lircd-0.10.1[1776]: Info: [lirc] protocol is enabled Jul 20 22:25:11 volumio lircd-0.10.1[1776]: Info: [lirc] protocol is enabled Jul 20 22:25:11 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1278]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Jul 20 22:25:11 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:11 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:11 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:11 volumio mpd[1748]: Jul 20 22:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 20 22:25:11 volumio systemd[1]: Started Music Player Daemon. Jul 20 22:25:11 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:11 volumio volumio[1774]: Traceback (most recent call last): Jul 20 22:25:11 volumio volumio[1774]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:11 volumio volumio[1774]: import ST7789 # v0.0.6 Jul 20 22:25:11 volumio volumio[1774]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:12 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio volumio[1278]: error: MPD error: The expression evaluated to a falsy value: Jul 20 22:25:12 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:12 volumio volumio[1278]: error: The expression evaluated to a falsy value: Jul 20 22:25:12 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:12 volumio volumio[1278]: error: MPD error: The expression evaluated to a falsy value: Jul 20 22:25:12 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:12 volumio volumio[1278]: error: The expression evaluated to a falsy value: Jul 20 22:25:12 volumio volumio[1278]: assert.ok(self.idling) Jul 20 22:25:12 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5. Jul 20 22:25:12 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:12 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:12 volumio sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 20 22:25:12 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Jul 20 22:25:12 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 20 22:25:12 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Jul 20 22:25:12 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio sudo[1827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 20 22:25:12 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 20 22:25:12 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio volumio[1278]: info: Upmpdcli Daemon Started Jul 20 22:25:12 volumio startx[1693]: V3D 7.1 not supported by this version of Mesa. Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Jul 20 22:25:12 volumio volumio[1829]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 20 22:25:12 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:12 volumio sudo[1857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:12 volumio sudo[1857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio sudo[1857]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio volumio[1816]: Traceback (most recent call last): Jul 20 22:25:12 volumio volumio[1816]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:12 volumio volumio[1816]: import ST7789 # v0.0.6 Jul 20 22:25:12 volumio volumio[1816]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:12 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:12 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jul 20 22:25:12 volumio startx[1693]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.1.76"] Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Jul 20 22:25:12 volumio jellyfin[1452]: [22:25:12] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Jul 20 22:25:12 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Jul 20 22:25:12 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Jul 20 22:25:12 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:12 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 6. Jul 20 22:25:12 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:12 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:12 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:12 volumio sh[790]: timed out Jul 20 22:25:12 volumio dhcpcd[879]: timed out Jul 20 22:25:12 volumio sh[790]: dhcpcd exited Jul 20 22:25:12 volumio dhcpcd[879]: dhcpcd exited Jul 20 22:25:12 volumio sh[790]: ifup: failed to bring up eth0 Jul 20 22:25:12 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:12 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 20 22:25:13 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:13 volumio volumio[1881]: Traceback (most recent call last): Jul 20 22:25:13 volumio volumio[1881]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:13 volumio volumio[1881]: import ST7789 # v0.0.6 Jul 20 22:25:13 volumio volumio[1881]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:13 volumio sudo[1892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:13 volumio sudo[1892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:13 volumio sudo[1892]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:13 volumio volumio[1278]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 7. Jul 20 22:25:13 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:13 volumio systemd[1]: Started Service for using pirate audio display in volumio. Jul 20 22:25:13 volumio volumio[1278]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Jul 20 22:25:13 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Jul 20 22:25:13 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:13 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:13 volumio volumio[1914]: Traceback (most recent call last): Jul 20 22:25:13 volumio volumio[1914]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Jul 20 22:25:13 volumio volumio[1914]: import ST7789 # v0.0.6 Jul 20 22:25:13 volumio volumio[1914]: ModuleNotFoundError: No module named 'ST7789' Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:13 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:13 volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:13 volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 8. Jul 20 22:25:13 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Start request repeated too quickly. Jul 20 22:25:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Jul 20 22:25:13 volumio systemd[1]: Failed to start Service for using pirate audio display in volumio. Jul 20 22:25:13 volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:13 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 20 22:25:14 volumio volumio[1278]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jul 20 22:25:14 volumio sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jul 20 22:25:14 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:14 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:14 volumio volumio[1278]: info: Completed starting Core Plugins Jul 20 22:25:14 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:14 volumio volumio[1278]: info: ----- MyVolumio plugins startup ---- Jul 20 22:25:14 volumio volumio[1278]: info: ------------------------------------------- Jul 20 22:25:14 volumio volumio[1278]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 20 22:25:14 volumio volumio[1278]: info: go-librespot daemon successfully initialized Jul 20 22:25:14 volumio volumio[1829]: ....................................++++ Jul 20 22:25:14 volumio volumio[1829]: .......++++ Jul 20 22:25:14 volumio volumio[1829]: e is 65537 (0x010001) Jul 20 22:25:14 volumio volumio[1829]: writing RSA key Jul 20 22:25:15 volumio volumio[1278]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Jul 20 22:25:16 volumio volumio[1278]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"] Jul 20 22:25:16 volumio jellyfin[1452]: [22:25:16] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::volumioGetVisibleSources Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:17 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 22:25:17 volumio volumio[1278]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jul 20 22:25:17 volumio volumio[1278]: info: Initializing connection to go-librespot Websocket Jul 20 22:25:17 volumio go-librespot[1699]: time="2024-07-20T22:25:17-04:00" level=debug msg="new websocket client" Jul 20 22:25:17 volumio volumio[1278]: info: Connection to go-librespot Websocket established Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:17 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 20 22:25:17 volumio volumio[1278]: info: Listing playlists Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 20 22:25:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-07-21 03:00:00.000 -04:00, which is 04:34:41.6057566 from now. Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-07-21 02:00:00.000 -04:00, which is 03:34:41.5997682 from now. Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_rkmpp", "h264", "h264_rkmpp", "hevc", "hevc_rkmpp", "mpeg1_rkmpp", "mpeg2video", "mpeg2_rkmpp", "mpeg4", "mpeg4_rkmpp", "msmpeg4", "vp8", "vp8_rkmpp", "libvpx", "vp9", "vp9_rkmpp", "libvpx-vp9", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "libx264", "h264_v4l2m2m", "h264_rkmpp", "libx265", "hevc_rkmpp", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["overlay_opencl", "overlay_rkrga", "scale_opencl", "scale_rkrga", "tonemap_opencl", "vpp_rkrga", "zscale", "alphasrc"] Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [WRN] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Filter: scale_cuda with option Output format (default "same") is not available Jul 20 22:25:18 volumio jellyfin[1452]: [22:25:18] [WRN] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Filter: tonemap_cuda with option GPU accelerated HDR to SDR tonemapping is not available Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [WRN] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Filter: overlay_vaapi with option Action to take when encountering EOF from secondary input is not available Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [WRN] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Filter: overlay_vulkan with option Action to take when encountering EOF from secondary input is not available Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["drm", "opencl", "rkmpp"] Jul 20 22:25:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 20 22:25:19 volumio volumio[1278]: info: Received Get System Info Jul 20 22:25:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 22:25:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 22:25:19 volumio volumio[1278]: info: Discovery: Getting this device information Jul 20 22:25:19 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:19 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: ee437745d05443389ab41c62abef85bf Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Jul 20 22:25:19 volumio jellyfin[1452]: [22:25:19] [INF] [1] Main: Startup complete 0:00:09.3818202 Jul 20 22:25:20 volumio volumio[1278]: info: Getting Spotify volume Jul 20 22:25:20 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 20 22:25:20 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:20 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:20 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 20 22:25:20 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME undefined Jul 20 22:25:20 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 10 Jul 20 22:25:20 volumio volumio[1278]: info: Aligning Spotify Volume to Volumio Volume Jul 20 22:25:20 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:20 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:20 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 10 Jul 20 22:25:21 volumio jellyfin[1452]: [22:25:21] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Jul 20 22:25:21 volumio volumio[1278]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Jul 20 22:25:21 volumio volumio[1278]: info: Sending Spotify command with payload to local API: /player/volume Jul 20 22:25:21 volumio volumio[1278]: error: Failed to send command to Spotify local API: /player/volume: Error: Internal Server Error Jul 20 22:25:22 volumio jellyfin[1452]: [22:25:22] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 0 seconds Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin bluetooth to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin multiroom to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin metavolumio to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin cd_controller to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 20 22:25:22 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:22 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:22 volumio volumio[1278]: info: Starting MyVolumio Remote Streaming Endpoints Jul 20 22:25:22 volumio volumio[1278]: info: MyVolumio login type: Token Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 20 22:25:22 volumio volumio[1278]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 20 22:25:23 volumio volumio[1278]: info: Starting Streaming Service Transparent Proxy Jul 20 22:25:23 volumio volumio[1278]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 20 22:25:23 volumio volumio[1278]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 20 22:25:23 volumio volumio[1278]: info: Streaming services startup Jul 20 22:25:23 volumio volumio[1278]: info: Starting Streaming Daemon Jul 20 22:25:23 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 20 22:25:23 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:23 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:23 volumio volumio[1278]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 20 22:25:23 volumio volumio[1278]: error: Cannot start Volumio Streaming Daemon Jul 20 22:25:23 volumio volumio[1278]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 20 22:25:23 volumio volumio[1278]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 20 22:25:23 volumio volumio[1278]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 20 22:25:23 volumio volumio[1278]: STREAMING PROXY: Starting server on port 3245 Jul 20 22:25:23 volumio volumio[1278]: Node JS runtime: 14 Jul 20 22:25:23 volumio volumio[1278]: info: MyVolumio token set successfully Jul 20 22:25:23 volumio volumio[1278]: info: MYVOLUMIO: Adding device Jul 20 22:25:23 volumio volumio[1278]: info: MYVOLUMIO: Evaluating Server Jul 20 22:25:24 volumio volumio[1278]: info: MyVolumio status changed Jul 20 22:25:24 volumio volumio[1278]: info: Streaming services startup Jul 20 22:25:24 volumio volumio[1278]: info: Starting Streaming Daemon Jul 20 22:25:24 volumio volumio[1278]: info: Removing browser output: myVolumio user plan is not superstar Jul 20 22:25:24 volumio volumio[1278]: info: Removing audio output: Jul 20 22:25:24 volumio volumio[1278]: info: Stoppping Tunnel 1 Jul 20 22:25:24 volumio sudo[2188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 20 22:25:24 volumio sudo[2190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 20 22:25:24 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:24 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:24 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:24 volumio volumio[1278]: error: Cannot start Volumio Streaming Daemon Jul 20 22:25:24 volumio volumio[1278]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 20 22:25:24 volumio volumio[1278]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 20 22:25:24 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:24 volumio volumio[1278]: info: Remote SSH Stopped Jul 20 22:25:24 volumio volumio[1278]: info: Setting Geolocation for MyVolumio to us1 Jul 20 22:25:24 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:24 volumio volumio[1278]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 20 22:25:25 volumio volumio[1278]: info: Updating MyVolumio device info Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 20 22:25:25 volumio volumio[1278]: info: Received Get System Info Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 22:25:25 volumio volumio[1278]: info: Discovery: Getting this device information Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:25 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:25 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 22:25:25 volumio volumio[1278]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 20 22:25:28 volumio volumio[1278]: info: Preload queue cleared Jul 20 22:25:28 volumio volumio[1278]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::ClearQueue Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::stop Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::clearPlayQueue Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::saveQueue Jul 20 22:25:28 volumio volumio[1278]: info: CoreCommandRouter::volumioPushQueue Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::addQueueItems Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::addQueueItems Jul 20 22:25:28 volumio volumio[1278]: info: Preload queue cleared Jul 20 22:25:28 volumio volumio[1278]: info: Adding Item to queue: webeighties/2 Jul 20 22:25:28 volumio volumio[1278]: info: Exploding uri webeighties/2 in service 80s80s Jul 20 22:25:28 volumio volumio[1278]: info: [1721528728565] [80s80s] explodeUri: webeighties/2 Jul 20 22:25:28 volumio volumio[1278]: info: CoreCommandRouter::volumioPushQueue Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::saveQueue Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::updateTrackBlock Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::getTrackBlock Jul 20 22:25:28 volumio volumio[1278]: info: CoreCommandRouter::volumioPlay Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::play index 0 Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::stop Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::play index undefined Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:28 volumio volumio[1278]: info: CoreStateMachine::startPlaybackTimer Jul 20 22:25:28 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:28 volumio volumio[1278]: info: [1721528728570] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/love/mp3-192/volumio Jul 20 22:25:28 volumio volumio[1278]: info: [1721528728570] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=85&count=2 Jul 20 22:25:28 volumio volumio[1278]: info: MYVOLUMIO: Adding device Jul 20 22:25:28 volumio volumio[1278]: info: MYVOLUMIO: Evaluating Server Jul 20 22:25:29 volumio volumio[1278]: info: [1721528729045] [80s80s] received new event containing 2 songs. Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand stop Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand stop took 1 milliseconds Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand clear Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand clear took 0 milliseconds Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces state update: options Jul 20 22:25:29 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:25:29 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 3ms Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand consume 1 took 2 milliseconds Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 2ms Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 2ms Jul 20 22:25:29 volumio volumio[1278]: info: [1721528729051] [80s80s] adding url: http://streams.80s80s.de/love/mp3-192/volumio Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces state update: options Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces state update: options Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand add "http://streams.80s80s.de/love/mp3-192/volumio" took 1 milliseconds Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand status took 1 milliseconds Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand play Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:29 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:29 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:29 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 14ms Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 15ms Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 15ms Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: info: Jul 20 22:25:29 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:29 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 20ms Jul 20 22:25:29 volumio volumio[1278]: info: sendMpdCommand play took 17 milliseconds Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 5ms Jul 20 22:25:29 volumio volumio[1278]: info: ------------------------------ 5ms Jul 20 22:25:29 volumio volumio[1278]: info: [1721528729074] [80s80s] Pushing the next song state: Depeche Mode - A Question of Lust Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music123/v4/1c/29/20/1c292028-f505-e02c-5225-200b3d09a062/source/600x600bb.jpg","name":"Depeche Mode - A Question of Lust","title":"A Question of Lust","artist":"80s80s Love","album":"","streaming":true,"disableUiControls":true,"duration":"250","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 20 22:25:29 volumio volumio[1278]: verbose: CURRENT POSITION 0 Jul 20 22:25:29 volumio volumio[1278]: info: CoreStateMachine::syncState stateService play Jul 20 22:25:29 volumio volumio[1278]: info: CoreStateMachine::syncState currentStatus stop Jul 20 22:25:29 volumio volumio[1278]: info: [1721528729075] [80s80s] PlayNextTrack API delay: 30 Jul 20 22:25:29 volumio volumio[1278]: info: [1721528729080] [80s80s] Setting timer to: 250000 milliseconds. Jul 20 22:25:29 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status stop Jul 20 22:25:29 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status stop Jul 20 22:25:29 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status stop Jul 20 22:25:29 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 20 22:25:29 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:29 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 20 22:25:29 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 20 22:25:29 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 20 22:25:29 volumio volumio[1278]: info: Setting Geolocation for MyVolumio to us1 Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:29 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:29 volumio sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 20 22:25:29 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:29 volumio sudo[2395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jul 20 22:25:29 volumio sudo[2395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:29 volumio volumio[1278]: info: Prefetching next song Jul 20 22:25:29 volumio sudo[2395]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:29 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:29 volumio volumio[1278]: info: peppyspectrum Daemon Stop Jul 20 22:25:29 volumio volumio[1278]: info: peppyspectrum Daemon Stop Jul 20 22:25:29 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:29 volumio volumio[1278]: info: peppyspectrum Daemon Stop Jul 20 22:25:29 volumio volumio[1278]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 20 22:25:30 volumio volumio[1278]: info: Updating MyVolumio device info Jul 20 22:25:30 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:30 volumio volumio[1278]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 20 22:25:32 volumio volumio[1278]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 20 22:25:32 volumio volumio[1278]: info: camilladsp stopping service pid 1795... Jul 20 22:25:32 volumio volumio[1278]: info: camilladsp service terminated, instance 1 Jul 20 22:25:32 volumio volumio[1278]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 20 22:25:32 volumio volumio[1278]: info: camilladsp service started and running in background, instance 1 Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 23 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 23 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 23 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 4 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 33ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 32ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 32ms Jul 20 22:25:32 volumio volumio[1278]: info: camilladsp spawned new process with pid 2414, instance 1, run: true Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 27ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 24ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 23ms Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 7ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 5 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 3ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 3 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 3ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 11ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 8ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 7ms Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces system playlist update Jul 20 22:25:32 volumio volumio[1278]: info: Ignoring MPD Status Update Jul 20 22:25:32 volumio volumio[1278]: info: Jul 20 22:25:32 volumio volumio[1278]: ---------------------------- MPD announces state update: player Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::getState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand status Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 4ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 3 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 2ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 1ms Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand status took 2 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseState Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: verbose: ControllerMpd::parseTrackInfo Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ControllerMpd::pushState Jul 20 22:25:32 volumio volumio[1278]: info: CoreCommandRouter::servicePushState Jul 20 22:25:32 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:32 volumio volumio[1278]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 7ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 6ms Jul 20 22:25:32 volumio volumio[1278]: info: ------------------------------ 5ms Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 22:25:35 volumio volumio[1278]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 20 22:25:35 volumio volumio[1278]: info: Received Get System Version Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:35 volumio volumio[1278]: info: Received Get System Info Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 20 22:25:35 volumio volumio[1278]: info: Discovery: Getting this device information Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:35 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:35 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 20 22:25:37 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 20 22:25:37 volumio volumio[1278]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jul 20 22:25:37 volumio volumio[1278]: info: CoreCommandRouter::volumioGetState Jul 20 22:25:37 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:38 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:38 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:38 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 20 22:25:39 volumio volumio-remote-updater[947]: No test mode Jul 20 22:25:39 volumio volumio-remote-updater[947]: No alpha test mode Jul 20 22:25:39 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 20 22:25:39 volumio volumio[1278]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 20 22:25:39 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::volumioGetBrowseSources Jul 20 22:25:42 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 20 22:25:43 volumio volumio[1278]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 20 22:25:43 volumio volumio[1278]: info: Completed starting MyVolumio Plugin Jul 20 22:25:43 volumio volumio[1278]: info: BOOT COMPLETED Jul 20 22:25:43 volumio volumio[1278]: [Metrics] CommandRouter: 41s 615.51ms Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::volumiosetStartupVolume Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:25:43 volumio volumio[1278]: info: VolumeController:: Setting startup Volume 10 Jul 20 22:25:43 volumio volumio[1278]: info: VolumeController::SetAlsaVolume10 Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::Close All Modals sent Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::Close All Modals sent Jul 20 22:25:43 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:43 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:43 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:43 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:43 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Jul 20 22:25:43 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 10 Jul 20 22:25:43 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 10 Jul 20 22:25:43 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 20 22:25:43 volumio sudo[2455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:43 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:43 volumio volumio[1278]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 20 22:25:43 volumio volumio[1278]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 20 22:25:43 volumio volumio[1278]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 20 22:25:43 volumio volumio[1278]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 20 22:25:43 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:43 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:43 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:43 volumio volumio[2458]: No protocol specified Jul 20 22:25:43 volumio volumio[2458]: No protocol specified Jul 20 22:25:43 volumio volumio[2458]: No protocol specified Jul 20 22:25:43 volumio volumio[2458]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:43 volumio volumio[2458]: Current thread 0xf7906600 (most recent call first): Jul 20 22:25:43 volumio volumio[2458]: File "spectrum.py", line 104 in init_display Jul 20 22:25:43 volumio volumio[2458]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:43 volumio volumio[2458]: File "spectrum.py", line 715 in Jul 20 22:25:43 volumio volumio[2458]: pygame 1.9.4.post1 Jul 20 22:25:43 volumio volumio[2458]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:43 volumio volumio[2458]: Aborted Jul 20 22:25:43 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:43 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:44 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 20 22:25:44 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 20 22:25:44 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 20 22:25:47 volumio volumio[1278]: info: VolumeController::SetAlsaVolume50 Jul 20 22:25:47 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:47 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:47 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:47 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:47 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:47 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Jul 20 22:25:47 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 10 Jul 20 22:25:47 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 50 Jul 20 22:25:47 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:47 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 50 Jul 20 22:25:47 volumio sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:47 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:47 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:47 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:47 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:47 volumio volumio[2518]: No protocol specified Jul 20 22:25:47 volumio volumio[2518]: No protocol specified Jul 20 22:25:47 volumio volumio[2518]: No protocol specified Jul 20 22:25:47 volumio volumio[2518]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:47 volumio volumio[2518]: Current thread 0xf792f600 (most recent call first): Jul 20 22:25:47 volumio volumio[2518]: File "spectrum.py", line 104 in init_display Jul 20 22:25:47 volumio volumio[2518]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:47 volumio volumio[2518]: File "spectrum.py", line 715 in Jul 20 22:25:47 volumio volumio[2518]: pygame 1.9.4.post1 Jul 20 22:25:47 volumio volumio[2518]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:47 volumio volumio[2518]: Aborted Jul 20 22:25:47 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:47 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:48 volumio ntpd[1100]: Soliciting pool server 198.50.174.203 Jul 20 22:25:49 volumio volumio[1278]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Jul 20 22:25:49 volumio volumio[1278]: info: Sending Spotify command with payload to local API: /player/volume Jul 20 22:25:49 volumio ntpd[1100]: Soliciting pool server 199.182.221.110 Jul 20 22:25:49 volumio ntpd[1100]: Soliciting pool server 167.114.93.151 Jul 20 22:25:50 volumio volumio[1278]: info: VolumeController::SetAlsaVolume69 Jul 20 22:25:50 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:50 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:50 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:50 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:50 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:50 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Jul 20 22:25:50 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 50 Jul 20 22:25:50 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 69 Jul 20 22:25:50 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:50 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 69 Jul 20 22:25:50 volumio sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:50 volumio sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:50 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:50 volumio sudo[2527]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:50 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:50 volumio volumio[2529]: No protocol specified Jul 20 22:25:50 volumio volumio[2529]: No protocol specified Jul 20 22:25:50 volumio volumio[2529]: No protocol specified Jul 20 22:25:50 volumio volumio[2529]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:50 volumio volumio[2529]: Current thread 0xf789e600 (most recent call first): Jul 20 22:25:50 volumio volumio[2529]: File "spectrum.py", line 104 in init_display Jul 20 22:25:50 volumio volumio[2529]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:50 volumio volumio[2529]: File "spectrum.py", line 715 in Jul 20 22:25:50 volumio volumio[2529]: pygame 1.9.4.post1 Jul 20 22:25:50 volumio volumio[2529]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:50 volumio volumio[2529]: Aborted Jul 20 22:25:50 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:50 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:50 volumio ntpd[1100]: Soliciting pool server 158.69.20.38 Jul 20 22:25:50 volumio ntpd[1100]: Soliciting pool server 162.159.200.123 Jul 20 22:25:51 volumio volumio[1278]: info: VolumeController::SetAlsaVolume80 Jul 20 22:25:51 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:51 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:51 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:51 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:51 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 69 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 80 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:51 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 80 Jul 20 22:25:51 volumio sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:51 volumio sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:51 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:51 volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:51 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:51 volumio volumio[2537]: No protocol specified Jul 20 22:25:51 volumio volumio[2537]: No protocol specified Jul 20 22:25:51 volumio volumio[2537]: No protocol specified Jul 20 22:25:51 volumio volumio[2537]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:51 volumio volumio[2537]: Current thread 0xf7d97600 (most recent call first): Jul 20 22:25:51 volumio volumio[2537]: File "spectrum.py", line 104 in init_display Jul 20 22:25:51 volumio volumio[2537]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:51 volumio volumio[2537]: File "spectrum.py", line 715 in Jul 20 22:25:51 volumio volumio[2537]: pygame 1.9.4.post1 Jul 20 22:25:51 volumio volumio[2537]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:51 volumio volumio[2537]: Aborted Jul 20 22:25:51 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:51 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:51 volumio volumio[1278]: info: VolumeController::SetAlsaVolume89 Jul 20 22:25:51 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:51 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:51 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:51 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:51 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 80 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 89 Jul 20 22:25:51 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:51 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 89 Jul 20 22:25:51 volumio sudo[2543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:51 volumio sudo[2543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:51 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:51 volumio sudo[2543]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:51 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:51 volumio ntpd[1100]: Soliciting pool server 162.159.200.1 Jul 20 22:25:51 volumio ntpd[1100]: Soliciting pool server 66.49.222.68 Jul 20 22:25:51 volumio ntpd[1100]: Soliciting pool server 192.99.160.57 Jul 20 22:25:51 volumio volumio[2545]: No protocol specified Jul 20 22:25:51 volumio volumio[2545]: No protocol specified Jul 20 22:25:51 volumio volumio[2545]: No protocol specified Jul 20 22:25:51 volumio volumio[2545]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:51 volumio volumio[2545]: Current thread 0xf7ba3600 (most recent call first): Jul 20 22:25:51 volumio volumio[2545]: File "spectrum.py", line 104 in init_display Jul 20 22:25:51 volumio volumio[2545]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:51 volumio volumio[2545]: File "spectrum.py", line 715 in Jul 20 22:25:51 volumio volumio[2545]: pygame 1.9.4.post1 Jul 20 22:25:51 volumio volumio[2545]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:51 volumio volumio[2545]: Aborted Jul 20 22:25:51 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:51 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:52 volumio volumio[1278]: info: VolumeController::SetAlsaVolume94 Jul 20 22:25:52 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:52 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:52 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:52 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:52 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:52 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Jul 20 22:25:52 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 89 Jul 20 22:25:52 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 94 Jul 20 22:25:52 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:52 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 94 Jul 20 22:25:52 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:52 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:52 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Jul 20 22:25:52 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:52 volumio systemd[1]: Failed to start peppyspectrum Daemon. Jul 20 22:25:52 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:52 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 20 22:25:52 volumio volumio[1278]: Job for peppyspectrum.service failed because the control process exited with error code. Jul 20 22:25:52 volumio volumio[1278]: See "systemctl status peppyspectrum.service" and "journalctl -xe" for details. Jul 20 22:25:52 volumio ntpd[1100]: Soliciting pool server 206.108.0.132 Jul 20 22:25:52 volumio ntpd[1100]: Soliciting pool server 192.99.168.180 Jul 20 22:25:53 volumio ntpd[1100]: Soliciting pool server 207.34.48.31 Jul 20 22:25:53 volumio ntpd[1100]: Soliciting pool server 216.232.132.102 Jul 20 22:25:53 volumio volumio[1278]: SPOTIFY: SETTING SPOTIFY VOLUME 94 Jul 20 22:25:53 volumio volumio[1278]: info: Sending Spotify command with payload to local API: /player/volume Jul 20 22:25:54 volumio ntpd[1100]: Soliciting pool server 216.58.108.188 Jul 20 22:25:54 volumio ntpd[1100]: Soliciting pool server 216.197.156.83 Jul 20 22:25:55 volumio ntpd[1100]: Soliciting pool server 2607:5300:201:3100::5d2e Jul 20 22:25:56 volumio volumio[1278]: info: VolumeController::SetAlsaVolume48 Jul 20 22:25:56 volumio volumio[1278]: info: CoreStateMachine::pushState Jul 20 22:25:56 volumio volumio[1278]: info: CorePlayQueue::getTrack 0 Jul 20 22:25:56 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 20 22:25:56 volumio volumio[1278]: info: CoreCommandRouter::volumioPushState Jul 20 22:25:56 volumio volumio[1278]: info: PeppySpectrum ---peppyspectrum status play Jul 20 22:25:56 volumio volumio[1278]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jul 20 22:25:56 volumio volumio[1278]: SPOTIFY: SPOTIFY VOLUME 94 Jul 20 22:25:56 volumio volumio[1278]: SPOTIFY: VOLUMIO VOLUME 48 Jul 20 22:25:56 volumio volumio[1278]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 20 22:25:56 volumio volumio[1278]: info: Setting Spotify Volume from Volumio: 48 Jul 20 22:25:56 volumio sudo[2561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 20 22:25:56 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:25:56 volumio systemd[1]: Started peppyspectrum Daemon. Jul 20 22:25:56 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Jul 20 22:25:56 volumio volumio[1278]: info: peppyspectrum Daemon Started Jul 20 22:25:56 volumio volumio[2563]: No protocol specified Jul 20 22:25:56 volumio volumio[2563]: No protocol specified Jul 20 22:25:56 volumio volumio[2563]: No protocol specified Jul 20 22:25:56 volumio volumio[2563]: Fatal Python error: (pygame parachute) Segmentation Fault Jul 20 22:25:56 volumio volumio[2563]: Current thread 0xf7e5c600 (most recent call first): Jul 20 22:25:56 volumio volumio[2563]: File "spectrum.py", line 104 in init_display Jul 20 22:25:56 volumio volumio[2563]: File "spectrum.py", line 63 in __init__ Jul 20 22:25:56 volumio volumio[2563]: File "spectrum.py", line 715 in Jul 20 22:25:56 volumio volumio[2563]: pygame 1.9.4.post1 Jul 20 22:25:56 volumio volumio[2563]: Hello from the pygame community. https://www.pygame.org/contribute.html Jul 20 22:25:56 volumio volumio[2563]: Aborted Jul 20 22:25:56 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=134/n/a Jul 20 22:25:56 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Jul 20 22:25:57 volumio volumio[1278]: SPOTIFY: SETTING SPOTIFY VOLUME 48 Jul 20 22:25:57 volumio volumio[1278]: info: Sending Spotify command with payload to local API: /player/volume Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 20 22:26:00 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 20 22:26:17 volumio volumio[1278]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 20 22:26:17 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 20 22:26:17 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:26:17 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio sudo[2621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 20 22:26:17 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:26:17 volumio systemd[1]: Stopping Music Player Daemon... Jul 20 22:26:17 volumio systemd[1]: mpd.service: Succeeded. Jul 20 22:26:17 volumio systemd[1]: Stopped Music Player Daemon. Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:17 volumio volumio[1278]: info: MPD Permissions set Jul 20 22:26:17 volumio volumio[1278]: info: camilladsp spawned new process with pid 2628, instance 1, run: true Jul 20 22:26:17 volumio systemd[1]: Starting Music Player Daemon... Jul 20 22:26:17 volumio sudo[2627]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 20 22:26:17 volumio sudo[2627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 20 22:26:17 volumio sudo[2627]: pam_unix(sudo:session): session closed for user root Jul 20 22:26:18 volumio mpd[2636]: Jul 20 22:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 20 22:26:18 volumio systemd[1]: Started Music Player Daemon. Jul 20 22:26:18 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Jul 20 22:26:18 volumio volumio[1278]: error: updateQueue error: null Jul 20 22:26:18 volumio volumio[1278]: error: Upnp client error: Error: This socket has been ended by the other party Jul 20 22:26:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:19 volumio volumio[1278]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 20 22:26:29 volumio volumio[1278]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 20 22:26:29 volumio volumio[1278]: info: PLUGIN onReboot : networkfs Jul 20 22:26:29 volumio volumio[1278]: info: PLUGIN onReboot : audiophonicsonoff Jul 20 22:26:29 volumio volumio[1278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 22:26:29 volumio volumio[1278]: TypeError: Cannot read property 'writeSync' of undefined Jul 20 22:26:29 volumio volumio[1278]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 20 22:26:29 volumio volumio[1278]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 20 22:26:29 volumio volumio[1278]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 20 22:26:29 volumio volumio[1278]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 20 22:26:29 volumio volumio[1278]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 20 22:26:29 volumio volumio[1278]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 20 22:26:29 volumio volumio[1278]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 20 22:26:29 volumio volumio[1278]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Jul 20 22:26:29 volumio volumio[1278]: at Socket.emit (events.js:315:20) Jul 20 22:26:29 volumio volumio[1278]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 20 22:26:29 volumio volumio[1278]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 20 22:26:29 volumio volumio[1278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 20 22:26:29 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-20 22:25 Jul 20 22:26:29 volumio sudo[2672]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"