-- 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"