-- Logs begin at Thu 2024-11-21 00:38:18 UTC, end at Thu 2024-11-21 03:02:24 UTC. -- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ----- Volumio3 ---- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ----- System startup ---- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:00 volumio-2 volumio[14200]: info: MYVOLUMIO Environment detected Nov 21 03:01:00 volumio-2 volumio[14200]: info: Plugin folders cleanup Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning into folder /volumio/app/plugins/ Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category audio_interface Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category miscellanea Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category music_service Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category plugins.json Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category system_controller Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category user_interface Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning into folder /data/plugins/ Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category music_service Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category system_controller Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category system_hardware Nov 21 03:01:00 volumio-2 volumio[14200]: info: Scanning category user_interface Nov 21 03:01:00 volumio-2 volumio[14200]: info: Plugin folders cleanup completed Nov 21 03:01:00 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ----- Core plugins startup ---- Nov 21 03:01:00 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:00 volumio-2 volumio[14200]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 03:01:00 volumio-2 volumio[14200]: info: Adding plugin upnp to MyMusic Plugins Nov 21 03:01:00 volumio-2 volumio[14200]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 03:01:00 volumio-2 volumio[14200]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 03:01:00 volumio-2 volumio[14200]: info: Loading plugins from folder /data/plugins/ Nov 21 03:01:00 volumio-2 volumio[14200]: info: Loading plugin "system"... Nov 21 03:01:00 volumio-2 volumio[14200]: info: Loading plugin "appearance"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "network"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: Refreshing Cached IP Addresses Nov 21 03:01:01 volumio-2 sudo[14224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 03:01:01 volumio-2 sudo[14224]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:01 volumio-2 sudo[14224]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "services"... Nov 21 03:01:01 volumio-2 sudo[14227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 03:01:01 volumio-2 sudo[14227]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "alsa_controller"... Nov 21 03:01:01 volumio-2 sudo[14227]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:01 volumio-2 sudo[14229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 03:01:01 volumio-2 sudo[14229]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:01 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "wizard"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "volumio_command_line_client"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "upnp"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: [1732158061819] Starting Upmpd Daemon Nov 21 03:01:01 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "my_music"... Nov 21 03:01:01 volumio-2 volumio[14200]: info: Loading plugin "mpd"... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Creating MPD Configuration file Nov 21 03:01:02 volumio-2 sudo[14235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 03:01:02 volumio-2 sudo[14235]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:02 volumio-2 sudo[14235]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "upnp_browser"... Nov 21 03:01:02 volumio-2 sudo[14238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 03:01:02 volumio-2 sudo[14238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:02 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 21 03:01:02 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 21 03:01:02 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 21 03:01:02 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "networkfs"... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Starting Udev Watcher for removable devices Nov 21 03:01:02 volumio-2 volumio[14200]: info: Ignoring mount for partition: boot Nov 21 03:01:02 volumio-2 volumio[14200]: info: Ignoring mount for partition: volumio Nov 21 03:01:02 volumio-2 volumio[14200]: info: Ignoring mount for partition: volumio_data Nov 21 03:01:02 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "alarm-clock"... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "airplay_emulation"... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Starting Shairport Sync Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "last_100"... Nov 21 03:01:02 volumio-2 volumio[14200]: info: Loading plugin "webradio"... Nov 21 03:01:03 volumio-2 volumio[14200]: info: Loading plugin "i2s_dacs"... Nov 21 03:01:03 volumio-2 volumio[14200]: info: Loading plugin "volumiodiscovery"... Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** For more information see Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** For more information see Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:03 volumio-2 volumio[14200]: *** WARNING *** For more information see Nov 21 03:01:03 volumio-2 node[14200]: *** WARNING *** For more information see Nov 21 03:01:03 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 03:01:03 volumio-2 volumio[14200]: info: Discovery: Started advertising with name: Volumio 2 Nov 21 03:01:03 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:03 volumio-2 volumio[14200]: info: Loading plugin "calmradio"... Nov 21 03:01:03 volumio-2 mpd[14242]: Nov 21 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 03:01:03 volumio-2 mpd[14242]: Nov 21 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 21 03:01:03 volumio-2 mpd[14242]: Nov 21 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 21 03:01:03 volumio-2 systemd[1]: Started Music Player Daemon. Nov 21 03:01:03 volumio-2 sudo[14238]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:03 volumio-2 volumio[14200]: info: Loading plugin "autostart"... Nov 21 03:01:03 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:03] [connect] Successful connection Nov 21 03:01:03 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin autostart Nov 21 03:01:03 volumio-2 volumio[14200]: info: AutoStart - onVolumioStart - read config.json Nov 21 03:01:03 volumio-2 volumio[14200]: info: Loading plugin "gpio-buttons"... Nov 21 03:01:04 volumio-2 volumio[14200]: info: GPIO-Buttons initialized Nov 21 03:01:04 volumio-2 volumio[14200]: info: Loading plugin "now_playing"... Nov 21 03:01:04 volumio-2 volumio[14200]: info: Loading plugin "outputs"... Nov 21 03:01:04 volumio-2 volumio[14200]: info: Loading plugin "albumart"... Nov 21 03:01:04 volumio-2 volumio[14200]: info: Plugin example_plugin is not enabled Nov 21 03:01:04 volumio-2 volumio[14200]: info: Loading plugin "inputs"... Nov 21 03:01:04 volumio-2 volumio[14200]: info: Loading plugin "updater_comm"... Nov 21 03:01:05 volumio-2 volumio[14200]: info: Plugin mpdemulation is not enabled Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "rest_api"... Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "websocket"... Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "80s80s"... Nov 21 03:01:05 volumio-2 volumio[14200]: Forking 3 albumart workers Nov 21 03:01:05 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin 80s80s Nov 21 03:01:05 volumio-2 volumio[14200]: info: [1732158065317] [80s80s] API delay: 30 Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "lms"... Nov 21 03:01:05 volumio-2 sudo[14229]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "swarmstation"... Nov 21 03:01:05 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin swarmstation Nov 21 03:01:05 volumio-2 volumio[14200]: info: [1732158065623] [Swarmstation] API delay: 5 Nov 21 03:01:05 volumio-2 volumio[14200]: info: Loading plugin "audiophonicsonoff"... Nov 21 03:01:06 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin audiophonicsonoff Nov 21 03:01:06 volumio-2 volumio[14200]: info: Audiophonics on/off initiated Nov 21 03:01:06 volumio-2 volumio[14200]: info: Loading plugin "backup_restore"... Nov 21 03:01:06 volumio-2 volumio[14200]: Starting albumart workers Nov 21 03:01:06 volumio-2 volumio[14200]: Starting albumart workers Nov 21 03:01:06 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin backup_restore Nov 21 03:01:06 volumio-2 volumio[14200]: info: Loading plugin "audiophonics_evo_sabre"... Nov 21 03:01:06 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Nov 21 03:01:06 volumio-2 volumio[14200]: Starting albumart workers Nov 21 03:01:06 volumio-2 volumio[14200]: info: Loading plugin "gpio_control"... Nov 21 03:01:06 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin gpio_control Nov 21 03:01:06 volumio-2 volumio[14200]: info: [GPIO_Control] Detected Raspberry Pi 4 Model B Rev 1.4 Nov 21 03:01:06 volumio-2 volumio[14200]: info: [GPIO_Control] 40 GPIOs: true Nov 21 03:01:06 volumio-2 volumio[14200]: info: [GPIO_Control] Initialized Nov 21 03:01:06 volumio-2 volumio[14200]: info: Loading plugin "ir_controller"... Nov 21 03:01:07 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin ir_controller Nov 21 03:01:07 volumio-2 volumio[14200]: info: Loading plugin "pirateaudio"... Nov 21 03:01:07 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin pirateaudio Nov 21 03:01:07 volumio-2 volumio[14200]: info: Plugin remotepi is not enabled Nov 21 03:01:07 volumio-2 volumio[14200]: info: Plugin rotaryencoder2 is not enabled Nov 21 03:01:07 volumio-2 volumio[14200]: info: Loading plugin "Systeminfo"... Nov 21 03:01:07 volumio-2 volumio[14200]: info: Loading plugin "lastfm"... Nov 21 03:01:07 volumio-2 volumio[14200]: info: Loading plugin "randomizer"... Nov 21 03:01:07 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin randomizer Nov 21 03:01:07 volumio-2 volumio[14200]: info: Loading plugin "touch_display"... Nov 21 03:01:08 volumio-2 volumio[14200]: info: Applying required configuration parameters for plugin touch_display Nov 21 03:01:08 volumio-2 volumio[14200]: info: Loading i18n strings for locale en Nov 21 03:01:08 volumio-2 volumio[14200]: Updating browse sources language Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::initPlayerControls Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: Express server listening on port 3000 Nov 21 03:01:08 volumio-2 volumio[14200]: [Metrics] WebUI: 8s 260.77ms Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::resetVolumioState Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::getcurrentVolume Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::pushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: MPD Permissions set Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 3 Nov 21 03:01:08 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732158063 101 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Nov 21 03:01:08 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Nov 21 03:01:08 volumio-2 volumio[14200]: info: MPD running with PID14242 Nov 21 03:01:08 volumio-2 volumio[14200]: ,establishing connection Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: Executing start script for DAC IQaudIO DAC Plus Nov 21 03:01:08 volumio-2 volumio[14200]: info: Reloading queue from file Nov 21 03:01:08 volumio-2 volumio[14200]: error: updateQueue error: null Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::setRepeat null single undefined Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::pushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::setRandom null Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreStateMachine::pushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:08 volumio-2 volumio[14200]: info: Setting Device type: Raspberry PI Nov 21 03:01:08 volumio-2 volumio[14200]: info: DAC script executed Nov 21 03:01:08 volumio-2 volumio[14200]: info: Completed loading Core Plugins Nov 21 03:01:08 volumio-2 volumio[14200]: info: Preparing to generate the ALSA configuration file Nov 21 03:01:08 volumio-2 volumio[14200]: error: updateQueue error: null Nov 21 03:01:08 volumio-2 volumio[14200]: info: Asound.conf file unchanged, so no further update is needed Nov 21 03:01:08 volumio-2 volumio[14200]: info: Output device has changed, restarting MPD Nov 21 03:01:08 volumio-2 sudo[14327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 03:01:08 volumio-2 sudo[14327]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:08 volumio-2 volumio[14200]: info: Output device has changed, restarting Shairport Sync Nov 21 03:01:08 volumio-2 sudo[14327]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:08 volumio-2 sudo[14330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 03:01:08 volumio-2 sudo[14330]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:08 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 21 03:01:08 volumio-2 volumio[14200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:08 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 21 03:01:08 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 21 03:01:08 volumio-2 volumio[14200]: info: ___________ START PLUGINS ___________ Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:08 volumio-2 volumio[14200]: info: [1732158068977] CoreMusicLibrary::Adding element Media Servers Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:08 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:09 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 21 03:01:09 volumio-2 volumio[14200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:09 volumio-2 volumio[14200]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069110] CoreMusicLibrary::Adding element Last_100 Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069116] CoreMusicLibrary::Adding element Webradio Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:09 volumio-2 volumio[14200]: info: Adding Calm Radio to Browse Sources Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069126] CoreMusicLibrary::Adding element Calm Radio Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source Calm Radio Nov 21 03:01:09 volumio-2 volumio[14200]: info: AutoStart - onStart Nov 21 03:01:09 volumio-2 volumio[14200]: info: GPIO-Buttons: Reading config and creating triggers... Nov 21 03:01:09 volumio-2 volumio[14200]: info: GPIO-Buttons: playPause on pin 17 Nov 21 03:01:09 volumio-2 volumio[14200]: info: GPIO-Buttons: previous on pin 23 Nov 21 03:01:09 volumio-2 volumio[14200]: info: [now-playing] ConfigUpdater: config is up to date. Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069167] CoreMusicLibrary::Adding element 80s80s Radio Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source Calm Radio Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source 80s80s Radio Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069196] CoreMusicLibrary::Adding element SWARM Station Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source Calm Radio Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source 80s80s Radio Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source SWARM Station Nov 21 03:01:09 volumio-2 volumio[14200]: info: Configuring GPIO pins Nov 21 03:01:09 volumio-2 volumio[14200]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write Nov 21 03:01:09 volumio-2 volumio[14200]: info: EVO SABRE : Starting Plugin Nov 21 03:01:09 volumio-2 volumio[14200]: info: Loading i18n strings for locale en Nov 21 03:01:09 volumio-2 sudo[14345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 21 03:01:09 volumio-2 sudo[14345]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:09 volumio-2 systemd[1]: Stopping Logitech Media Server Daemon... Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:29.9243] Slim::Networking::Repositories::__ANON__ (147) Failed to fetch https://github.com/LMS-Community/lms-plugin-repository/raw/master/extensions.xml: Connect timed out: Connection refused Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:29.9456] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: Connect timed out: Connection refused Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:29.9461] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Connect timed out: Connection refused Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:32.2516] Slim::Formats::XML::gotViaHTTP (271) XML/JSON parse error: Invalid XML feed Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:32.2520] Slim::Plugin::InternetRadio::Plugin::_gotRadioError (71) Unable to retrieve radio directory from SN: Failed to parse Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:44.9186] Slim::Networking::Repositories::__ANON__ (147) Failed to fetch https://github.com/LMS-Community/lms-plugin-repository/raw/master/extensions.xml: Connect timed out: Bad file descriptor Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:44.9218] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: Connect timed out: Bad file descriptor Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:44.9223] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Connect timed out: Bad file descriptor Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:46.3288] Slim::Formats::XML::gotViaHTTP (271) XML/JSON parse error: Invalid XML feed Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:46.3293] Slim::Plugin::InternetRadio::Plugin::_gotRadioError (71) Unable to retrieve radio directory from SN: Failed to parse Nov 21 03:01:09 volumio-2 squeezeboxserver[14036]: [24-11-21 03:00:46.5841] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 21 03:01:09 volumio-2 volumio[14200]: info: [GPIO_Control] Reading config and creating GPIOs Nov 21 03:01:09 volumio-2 volumio[14200]: info: Loading i18n strings for locale en Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:09 volumio-2 volumio[14200]: info: [1732158069391] CoreMusicLibrary::Adding element LastFM Nov 21 03:01:09 volumio-2 systemd[1]: logitechmediaserver.service: Succeeded. Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:09 volumio-2 systemd[1]: Stopped Logitech Media Server Daemon. Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source Calm Radio Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source 80s80s Radio Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source SWARM Station Nov 21 03:01:09 volumio-2 volumio[14200]: Cannot find translation for source LastFM Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] scrobbler initiated! Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] extended logging: false Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] try scrobble stream/radio plays: true Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] Left init routine Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] Socket already connected: true Nov 21 03:01:09 volumio-2 sudo[14350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Nov 21 03:01:09 volumio-2 sudo[14350]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:09 volumio-2 systemd[1]: Starting Logitech Media Server Daemon... Nov 21 03:01:09 volumio-2 volumio[14200]: info: Loading i18n strings for locale en Nov 21 03:01:09 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:09 volumio-2 volumio[14200]: info: Volumio Calling Home Nov 21 03:01:09 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:09 volumio-2 systemd[1]: Started Logitech Media Server Daemon. Nov 21 03:01:09 volumio-2 sudo[14345]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:09 volumio-2 sudo[14350]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:09 volumio-2 volumio[14200]: info: GPIO-Buttons started Nov 21 03:01:09 volumio-2 volumio[14200]: info: [GPIO_Control] GPIOs created Nov 21 03:01:09 volumio-2 volumio[14200]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Nov 21 03:01:09 volumio-2 sudo[14362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 21 03:01:09 volumio-2 sudo[14362]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:10 volumio-2 sudo[14362]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:10 volumio-2 sudo[14365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 03:01:10 volumio-2 sudo[14365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:10 volumio-2 systemd[1]: Reloading. Nov 21 03:01:10 volumio-2 volumio[14200]: info: [now-playing] App is listening on port 4004. Nov 21 03:01:10 volumio-2 mpd[14343]: Nov 21 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 03:01:10 volumio-2 mpd[14343]: Nov 21 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 21 03:01:10 volumio-2 mpd[14343]: Nov 21 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 21 03:01:10 volumio-2 volumio[14200]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 21 03:01:10 volumio-2 volumio[14354]: Traceback (most recent call last): Nov 21 03:01:10 volumio-2 volumio[14354]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:10 volumio-2 volumio[14354]: offset_top=0 # v0.0.6 Nov 21 03:01:10 volumio-2 volumio[14354]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:10 volumio-2 volumio[14354]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:10 volumio-2 volumio[14354]: RuntimeError: Not running on a RPi! Nov 21 03:01:10 volumio-2 volumio[14200]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 21 03:01:10 volumio-2 volumio[14200]: info: Pirate audio service (daemon) started Nov 21 03:01:10 volumio-2 volumio[14200]: info: logitechmediaserver started Nov 21 03:01:10 volumio-2 volumio[14200]: info: MPD Permissions set Nov 21 03:01:10 volumio-2 sudo[14383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 03:01:10 volumio-2 sudo[14383]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:10 volumio-2 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. Nov 21 03:01:10 volumio-2 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:10 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:11 volumio-2 volumio[14200]: info: touch_display: Raspberry Pi Foundation touch screen detected. Nov 21 03:01:11 volumio-2 volumio[14200]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Nov 21 03:01:11 volumio-2 volumio[14200]: info: Starting Shairport Sync Nov 21 03:01:11 volumio-2 volumio[14200]: info: Starting Shairport Sync Nov 21 03:01:11 volumio-2 sudo[14390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:11 volumio-2 volumio[14200]: info: Starting Shairport Sync Nov 21 03:01:11 volumio-2 sudo[14390]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 sudo[14392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:11 volumio-2 sudo[14392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 systemd[1]: Started Music Player Daemon. Nov 21 03:01:11 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:11 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:11 volumio-2 sudo[14330]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 sudo[14365]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 volumio[14200]: info: ir_controller: LIRC correctly updated. Nov 21 03:01:11 volumio-2 volumio[14200]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 21 03:01:11 volumio-2 systemd[1]: Reloading. Nov 21 03:01:11 volumio-2 sudo[14396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:11 volumio-2 volumio[14200]: info: touch_display: systemctl daemon-reload succeeded. Nov 21 03:01:11 volumio-2 sudo[14396]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 sudo[14401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 21 03:01:11 volumio-2 sudo[14401]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 volumio[14200]: error: updateQueue error: null Nov 21 03:01:11 volumio-2 sudo[14415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Nov 21 03:01:11 volumio-2 sudo[14415]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 sudo[14415]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 volumio[14200]: info: touch_display: File permissions for backlight brightness control set. Nov 21 03:01:11 volumio-2 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. Nov 21 03:01:11 volumio-2 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 21 03:01:11 volumio-2 sudo[14420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 03:01:11 volumio-2 sudo[14420]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 sudo[14420]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 sudo[14423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 03:01:11 volumio-2 sudo[14423]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:11 volumio-2 sudo[14423]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 sudo[14383]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:11 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:11 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1. Nov 21 03:01:11 volumio-2 volumio[14200]: info: EVO SABRE : systemd cmd daemon-reload : success Nov 21 03:01:12 volumio-2 sudo[14426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 03:01:12 volumio-2 sudo[14426]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 sudo[14401]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 03:01:12 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:12 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:12 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 21 03:01:12 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 03:01:12 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 03:01:12 volumio-2 volumio[14200]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 21 03:01:12 volumio-2 sudo[14392]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 sudo[14390]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 volumio[14200]: info: touch_display: Volumio Kiosk started Nov 21 03:01:12 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:12 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:12 volumio-2 sudo[14396]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 sudo[14426]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 sudo[14431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Nov 21 03:01:12 volumio-2 sudo[14431]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 systemd[1]: Stopping OLED Display Service for EVO SABRE... Nov 21 03:01:12 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:12 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:12 volumio-2 sudo[14124]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 volumio[14200]: info: Upmpdcli Daemon Started Nov 21 03:01:12 volumio-2 volumio[14200]: info: Shairport-Sync Started Nov 21 03:01:12 volumio-2 systemd[1]: evo_oled2.service: Succeeded. Nov 21 03:01:12 volumio-2 systemd[1]: Stopped OLED Display Service for EVO SABRE. Nov 21 03:01:12 volumio-2 volumio[14200]: Error adding Membership: Error: addMembership EINVAL Nov 21 03:01:12 volumio-2 volumio[14200]: info: Shairport-Sync Started Nov 21 03:01:12 volumio-2 volumio[14200]: info: Shairport-Sync Started Nov 21 03:01:12 volumio-2 volumio[14200]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 03:01:12 volumio-2 sudo[14439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 21 03:01:12 volumio-2 sudo[14439]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 systemd[1]: Started OLED Display Service for EVO SABRE. Nov 21 03:01:12 volumio-2 sudo[14431]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 lircd[14130]: lircd-0.10.1[14130]: Notice: caught signal Nov 21 03:01:12 volumio-2 lircd-0.10.1[14130]: Notice: caught signal Nov 21 03:01:12 volumio-2 systemd[1]: Stopping Flexible IR remote input/output application support... Nov 21 03:01:12 volumio-2 systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Nov 21 03:01:12 volumio-2 systemd[1]: lircd.service: Succeeded. Nov 21 03:01:12 volumio-2 systemd[1]: Stopped Flexible IR remote input/output application support. Nov 21 03:01:12 volumio-2 sudo[14437]: 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 Nov 21 03:01:12 volumio-2 sudo[14437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 03:01:12 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:12 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:12 volumio-2 volumio[14200]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Nov 21 03:01:12 volumio-2 sudo[14451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Nov 21 03:01:12 volumio-2 sudo[14451]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 volumio[14200]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 9 Nov 21 03:01:12 volumio-2 volumio[14200]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 03:01:12 volumio-2 lircd-setup[14443]: /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. Nov 21 03:01:12 volumio-2 lircd-setup[14443]: parser = configparser.SafeConfigParser() Nov 21 03:01:12 volumio-2 systemd[1]: lircd-setup.service: Succeeded. Nov 21 03:01:12 volumio-2 systemd[1]: Started lircd(8) initialization helper tool. Nov 21 03:01:12 volumio-2 volumio[14200]: info: touch_display: Setting screensaver timeout to 501 seconds. Nov 21 03:01:12 volumio-2 systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Version: lircd 0.10.1 Nov 21 03:01:12 volumio-2 systemd[1]: Started evo_remote.service. Nov 21 03:01:12 volumio-2 sudo[14451]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Version: lircd 0.10.1 Nov 21 03:01:12 volumio-2 volumio[14200]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: System info: Linux volumio-2 6.1.58-v7l+ #1690 SMP Tue Oct 17 15:36:02 BST 2023 armv7l GNU/Linux Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: System info: Linux volumio-2 6.1.58-v7l+ #1690 SMP Tue Oct 17 15:36:02 BST 2023 armv7l GNU/Linux Nov 21 03:01:12 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:12 volumio-2 sudo[14463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Nov 21 03:01:12 volumio-2 sudo[14463]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:12 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: Initial device: /dev/lirc0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: [lirc] protocol is enabled Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: Initial device: /dev/lirc0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: [lirc] protocol is enabled Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: driver: default Nov 21 03:01:12 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: output: /var/run/lirc/lircd Nov 21 03:01:12 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Notice: Using systemd fd Nov 21 03:01:12 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Warning: Running as root Nov 21 03:01:12 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Info: Using remote: lircd.conf. Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: nodaemon: 1 Nov 21 03:01:12 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 03:01:12 volumio-2 systemd[1]: Started Flexible IR remote input/output application support. Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: logfile: syslog Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: immediate-init: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: permission: 666 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: driver-options: Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: listen: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: connect: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: userelease: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: effective_user: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: release_suffix: _EVUP Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: allow_simulate: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: repeat_max: 600 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Options: dynamic_codes: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Current driver: default Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Driver API version: 3 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Driver version: 0.10.0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: Using systemd fd Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Warning: Running as root Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Info: Using remote: lircd.conf. Nov 21 03:01:12 volumio-2 lircd-0.10.1[14455]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 03:01:12 volumio-2 sudo[14439]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: Initial device: /dev/lirc0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: [lirc] protocol is enabled Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: Initial device: /dev/lirc0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: [lirc] protocol is enabled Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: driver: default Nov 21 03:01:12 volumio-2 lircd[14457]: lircd-0.10.1[14457]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 lircd[14457]: lircd: there seems to already be a lircd process with pid 14455 Nov 21 03:01:12 volumio-2 lircd[14457]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: output: /var/run/lirc/lircd Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: nodaemon: 1 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: logfile: syslog Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: immediate-init: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: permission: 666 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: driver-options: Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: listen: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: connect: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: userelease: 0 Nov 21 03:01:12 volumio-2 systemd[1]: evo_remote.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: effective_user: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: release_suffix: _EVUP Nov 21 03:01:12 volumio-2 systemd[1]: evo_remote.service: Failed with result 'exit-code'. Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: allow_simulate: 0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: repeat_max: 600 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Options: dynamic_codes: (null) Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Current driver: default Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Driver API version: 3 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Driver version: 0.10.0 Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 21 03:01:12 volumio-2 lircd-0.10.1[14457]: Info: lircd: Opening log, level: Info Nov 21 03:01:12 volumio-2 volumio[14200]: info: ir_controller: systemctl restart lircd.service succeeded. Nov 21 03:01:12 volumio-2 systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 03:01:12 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 03:01:13 volumio-2 volumio[14200]: info: Listing playlists Nov 21 03:01:13 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:01:13 volumio-2 volumio[14200]: info: CoreStateMachine::getQueue Nov 21 03:01:13 volumio-2 volumio[14200]: info: CorePlayQueue::getQueue Nov 21 03:01:13 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 03:01:13 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:13 volumio-2 sudo[14468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Nov 21 03:01:13 volumio-2 sudo[14468]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:13 volumio-2 lircd-setup[14465]: /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. Nov 21 03:01:13 volumio-2 lircd-setup[14465]: parser = configparser.SafeConfigParser() Nov 21 03:01:13 volumio-2 systemd[1]: lircd-setup.service: Succeeded. Nov 21 03:01:13 volumio-2 systemd[1]: Started lircd(8) initialization helper tool. Nov 21 03:01:13 volumio-2 volumio[14428]: Traceback (most recent call last): Nov 21 03:01:13 volumio-2 volumio[14428]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:13 volumio-2 volumio[14428]: offset_top=0 # v0.0.6 Nov 21 03:01:13 volumio-2 volumio[14428]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:13 volumio-2 volumio[14428]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:13 volumio-2 volumio[14428]: RuntimeError: Not running on a RPi! Nov 21 03:01:13 volumio-2 systemd[1]: Started evo_irexec.service. Nov 21 03:01:13 volumio-2 sudo[14463]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:13 volumio-2 irexec[14470]: do_connect: could not connect to socket Nov 21 03:01:13 volumio-2 irexec[14470]: connect: Connection refused Nov 21 03:01:13 volumio-2 systemd[1]: evo_irexec.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:13 volumio-2 systemd[1]: evo_irexec.service: Failed with result 'exit-code'. Nov 21 03:01:13 volumio-2 volumio[14200]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Nov 21 03:01:13 volumio-2 volumio[14200]: info: Completed starting Core Plugins Nov 21 03:01:13 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:13 volumio-2 volumio[14200]: info: ----- MyVolumio plugins startup ---- Nov 21 03:01:13 volumio-2 volumio[14200]: info: ------------------------------------------- Nov 21 03:01:13 volumio-2 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Nov 21 03:01:13 volumio-2 irexec[14471]: do_connect: could not connect to socket Nov 21 03:01:13 volumio-2 irexec[14471]: connect: Connection refused Nov 21 03:01:13 volumio-2 sudo[14468]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:13 volumio-2 volumio[14200]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 03:01:13 volumio-2 volumio[14200]: info: ir_controller: systemctl restart irexec.service succeeded. Nov 21 03:01:13 volumio-2 systemd[1]: irexec.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:13 volumio-2 systemd[1]: irexec.service: Failed with result 'exit-code'. Nov 21 03:01:13 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:13 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:13 volumio-2 volumio[14200]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 21 03:01:13 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:13 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 2. Nov 21 03:01:13 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:13 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:14 volumio-2 volumio[14476]: Traceback (most recent call last): Nov 21 03:01:14 volumio-2 volumio[14476]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:14 volumio-2 volumio[14476]: offset_top=0 # v0.0.6 Nov 21 03:01:14 volumio-2 volumio[14476]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:14 volumio-2 volumio[14476]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:14 volumio-2 volumio[14476]: RuntimeError: Not running on a RPi! Nov 21 03:01:14 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:14 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:14 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Nov 21 03:01:14 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:14 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 3. Nov 21 03:01:14 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:14 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:14 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:14 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:14 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:01:14 volumio-2 volumio[14200]: info: CoreStateMachine::getQueue Nov 21 03:01:14 volumio-2 volumio[14200]: info: CorePlayQueue::getQueue Nov 21 03:01:14 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:14 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:13.1846] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9477] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9485] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9493] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9538] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9557] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9570] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9594] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9652] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:14.9680] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 21 03:01:15 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:15.1526] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 21 03:01:15 volumio-2 volumio[14477]: Traceback (most recent call last): Nov 21 03:01:15 volumio-2 volumio[14477]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:15 volumio-2 volumio[14477]: offset_top=0 # v0.0.6 Nov 21 03:01:15 volumio-2 volumio[14477]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:15 volumio-2 volumio[14477]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:15 volumio-2 volumio[14477]: RuntimeError: Not running on a RPi! Nov 21 03:01:15 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:15 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:15 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:15 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 4. Nov 21 03:01:15 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:15 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:16 volumio-2 autossh[12919]: starting ssh (count 10) Nov 21 03:01:16 volumio-2 autossh[12919]: ssh child pid is 14480 Nov 21 03:01:16 volumio-2 volumio[14479]: Traceback (most recent call last): Nov 21 03:01:16 volumio-2 volumio[14479]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:16 volumio-2 volumio[14479]: offset_top=0 # v0.0.6 Nov 21 03:01:16 volumio-2 volumio[14479]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:16 volumio-2 volumio[14479]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:16 volumio-2 volumio[14479]: RuntimeError: Not running on a RPi! Nov 21 03:01:16 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:16 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:16 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:16.7325] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 21 03:01:16 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:16 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5. Nov 21 03:01:16 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:16 volumio-2 volumio[14200]: info: Discovery: adding e24397a4-2819-4e00-9a76-d91cf8feb329 Nov 21 03:01:16 volumio-2 volumio[14200]: info: Discovery: Found device Volumio 2 Nov 21 03:01:16 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:16 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:16 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:16 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:16 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Nov 21 03:01:16 volumio-2 volumio[14200]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Nov 21 03:01:16 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:16 volumio-2 volumio[14200]: info: Discovery: this is already registered, e24397a4-2819-4e00-9a76-d91cf8feb329 Nov 21 03:01:16 volumio-2 volumio[14200]: info: Discovery: Found device Volumio 2 Nov 21 03:01:16 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:16 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:17 volumio-2 volumio[14481]: Traceback (most recent call last): Nov 21 03:01:17 volumio-2 volumio[14481]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:17 volumio-2 volumio[14481]: offset_top=0 # v0.0.6 Nov 21 03:01:17 volumio-2 volumio[14481]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:17 volumio-2 volumio[14481]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:17 volumio-2 volumio[14481]: RuntimeError: Not running on a RPi! Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 6. Nov 21 03:01:17 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Start request repeated too quickly. Nov 21 03:01:17 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:17 volumio-2 systemd[1]: Failed to start Service for using pirate audio display in volumio. Nov 21 03:01:24 volumio-2 volumiossh-tunnel[12917]: ssh: connect to host eu4.myvolumio.org port 2222: Connection refused Nov 21 03:01:24 volumio-2 autossh[12919]: ssh exited with error status 255; restarting ssh Nov 21 03:01:24 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:24 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:25 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:25.4845] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (Connection refused) Nov 21 03:01:25 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:25.5394] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 21 03:01:25 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:25.5401] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 21 03:01:25 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:25.5424] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 21 03:01:27 volumio-2 volumio[14200]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.569&uuid=8d328452240fc1066cf80b63dd4dd6e9" http://updates.volumio.org/downloader-v1/track-device Nov 21 03:01:27 volumio-2 volumio[14200]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 03:01:27 volumio-2 volumio[14200]: Dload Upload Total Spent Left Speed Nov 21 03:01:27 volumio-2 volumio[14200]: [790B blob data] Nov 21 03:01:27 volumio-2 volumio[14200]: curl: (52) Empty reply from server Nov 21 03:01:27 volumio-2 volumio[14200]: retrying in 5 seconds, trial 0 Nov 21 03:01:27 volumio-2 volumio[14200]: info: Volumio Calling Home Nov 21 03:01:29 volumio-2 volumio[14200]: info: AutoStart - Plugin is starting Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::getQueue Nov 21 03:01:29 volumio-2 volumio[14200]: info: CorePlayQueue::getQueue Nov 21 03:01:29 volumio-2 volumio[14200]: info: AutoStart - start playing Nov 21 03:01:29 volumio-2 volumio[14200]: info: AutoStart - start playing with no specific position Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioPlay Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::play index 0 Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::stop Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::play index undefined Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:01:29 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:29 volumio-2 volumio[14200]: info: CoreStateMachine::startPlaybackTimer Nov 21 03:01:29 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:29 volumio-2 volumio[14200]: info: [1732158089139] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Nov 21 03:01:29 volumio-2 volumio[14200]: info: [1732158089139] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Nov 21 03:01:33 volumio-2 volumio[14200]: error: MyVolumio Plugin failed to start in a timely fashion Nov 21 03:01:33 volumio-2 volumio[14200]: info: BOOT COMPLETED Nov 21 03:01:33 volumio-2 volumio[14200]: [Metrics] CommandRouter: 32s 949.56ms Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::volumiosetStartupVolume Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:33 volumio-2 volumio[14200]: info: VolumeController:: Setting startup Volume 5 Nov 21 03:01:33 volumio-2 volumio[14200]: info: VolumeController::SetAlsaVolume5 Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::Close All Modals sent Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::Close All Modals sent Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreStateMachine::pushState Nov 21 03:01:33 volumio-2 volumio[14200]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:01:33 volumio-2 volumio[14200]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:33 volumio-2 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5550] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (Connection refused) Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5558] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5565] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5571] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5585] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5590] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.5610] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6279] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6294] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6305] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6367] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6464] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6470] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.090 seconds. Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6481] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6504] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.002 seconds. Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6526] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6531] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.001 seconds. Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.6540] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 21 03:01:33 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.9641] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.309 seconds. Nov 21 03:01:34 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 03:01:34 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 03:01:34 volumio-2 volumio[14200]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 03:01:37 volumio-2 volumio[14200]: info: [1732158097267] [80s80s] Failed to query api, status code: 302 Nov 21 03:01:37 volumio-2 volumio[14200]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 03:01:37 volumio-2 volumio[14200]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Nov 21 03:01:37 volumio-2 volumio[14200]: at Object.openSync (fs.js:476:3) Nov 21 03:01:37 volumio-2 volumio[14200]: at Object.readFileSync (fs.js:377:35) Nov 21 03:01:37 volumio-2 volumio[14200]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Nov 21 03:01:37 volumio-2 volumio[14200]: at Object.onceWrapper (events.js:422:26) Nov 21 03:01:37 volumio-2 volumio[14200]: at ClientRequest.emit (events.js:315:20) Nov 21 03:01:37 volumio-2 volumio[14200]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Nov 21 03:01:37 volumio-2 volumio[14200]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Nov 21 03:01:37 volumio-2 volumio[14200]: at Socket.socketOnData (_http_client.js:509:22) Nov 21 03:01:37 volumio-2 volumio[14200]: at Socket.emit (events.js:315:20) Nov 21 03:01:37 volumio-2 volumio[14200]: at addChunk (internal/streams/readable.js:309:12) Nov 21 03:01:37 volumio-2 volumio[14200]: at readableAddChunk (internal/streams/readable.js:284:9) Nov 21 03:01:37 volumio-2 volumio[14200]: at Socket.Readable.push (internal/streams/readable.js:223:10) Nov 21 03:01:37 volumio-2 volumio[14200]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Nov 21 03:01:37 volumio-2 volumio[14200]: errno: -2, Nov 21 03:01:37 volumio-2 volumio[14200]: syscall: 'open', Nov 21 03:01:37 volumio-2 volumio[14200]: code: 'ENOENT', Nov 21 03:01:37 volumio-2 volumio[14200]: path: '/data/plugins/music_service/80s80s/fake-data.json' Nov 21 03:01:37 volumio-2 volumio[14200]: } Nov 21 03:01:37 volumio-2 volumio[14200]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 03:01:37 volumio-2 sudo[14497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 03:00 Nov 21 03:01:37 volumio-2 sudo[14497]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:37 volumio-2 sudo[14497]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:45 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 21 03:01:45 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 21 03:01:45 volumio-2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:45 volumio-2 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 21 03:01:45 volumio-2 systemd[1]: Started dynamicswap service. Nov 21 03:01:45 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 21 03:01:46 volumio-2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:46 volumio-2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Nov 21 03:01:46 volumio-2 systemd[1]: Started dynamicswap service. Nov 21 03:01:46 volumio-2 systemd[1]: Stopped Volumio Backend Module. Nov 21 03:01:46 volumio-2 systemd[1]: Started Volumio Backend Module. Nov 21 03:01:46 volumio-2 systemd[1]: dynamicswap.service: Succeeded. Nov 21 03:01:47 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ----- Volumio3 ---- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ----- System startup ---- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:01:47 volumio-2 volumio[14514]: info: MYVOLUMIO Environment detected Nov 21 03:01:47 volumio-2 volumio[14514]: info: Plugin folders cleanup Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning into folder /volumio/app/plugins/ Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category audio_interface Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category miscellanea Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category music_service Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category plugins.json Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category system_controller Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category user_interface Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning into folder /data/plugins/ Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category music_service Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category system_controller Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category system_hardware Nov 21 03:01:47 volumio-2 volumio[14514]: info: Scanning category user_interface Nov 21 03:01:47 volumio-2 volumio[14514]: info: Plugin folders cleanup completed Nov 21 03:01:47 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ----- Core plugins startup ---- Nov 21 03:01:47 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:01:47 volumio-2 volumio[14514]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 03:01:47 volumio-2 volumio[14514]: info: Adding plugin upnp to MyMusic Plugins Nov 21 03:01:47 volumio-2 volumio[14514]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 03:01:47 volumio-2 volumio[14514]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 03:01:47 volumio-2 volumio[14514]: info: Loading plugins from folder /data/plugins/ Nov 21 03:01:47 volumio-2 volumio[14514]: info: Loading plugin "system"... Nov 21 03:01:47 volumio-2 volumio[14514]: info: Loading plugin "appearance"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "network"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Refreshing Cached IP Addresses Nov 21 03:01:48 volumio-2 sudo[14537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 03:01:48 volumio-2 sudo[14537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:48 volumio-2 sudo[14537]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:48 volumio-2 sudo[14540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 03:01:48 volumio-2 sudo[14540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "services"... Nov 21 03:01:48 volumio-2 sudo[14540]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "alsa_controller"... Nov 21 03:01:48 volumio-2 sudo[14543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 21 03:01:48 volumio-2 sudo[14543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:48 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "wizard"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "volumio_command_line_client"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "upnp"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: [1732158108714] Starting Upmpd Daemon Nov 21 03:01:48 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "my_music"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "mpd"... Nov 21 03:01:48 volumio-2 volumio[14514]: info: Creating MPD Configuration file Nov 21 03:01:48 volumio-2 volumio[14514]: info: Loading plugin "upnp_browser"... Nov 21 03:01:48 volumio-2 sudo[14548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 03:01:48 volumio-2 sudo[14548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:48 volumio-2 sudo[14548]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:49 volumio-2 sudo[14550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 03:01:49 volumio-2 sudo[14550]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:49 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 21 03:01:49 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 21 03:01:49 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 21 03:01:49 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "networkfs"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Starting Udev Watcher for removable devices Nov 21 03:01:49 volumio-2 volumio[14514]: info: Ignoring mount for partition: boot Nov 21 03:01:49 volumio-2 volumio[14514]: info: Ignoring mount for partition: volumio Nov 21 03:01:49 volumio-2 volumio[14514]: info: Ignoring mount for partition: volumio_data Nov 21 03:01:49 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "alarm-clock"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "airplay_emulation"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Starting Shairport Sync Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "last_100"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "webradio"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "i2s_dacs"... Nov 21 03:01:49 volumio-2 volumio[14514]: info: Loading plugin "volumiodiscovery"... Nov 21 03:01:49 volumio-2 mpd[14555]: Nov 21 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 03:01:49 volumio-2 mpd[14555]: Nov 21 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 21 03:01:49 volumio-2 mpd[14555]: Nov 21 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** For more information see Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:49 volumio-2 volumio[14514]: *** WARNING *** For more information see Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** For more information see Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 03:01:49 volumio-2 node[14514]: *** WARNING *** For more information see Nov 21 03:01:50 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 03:01:50 volumio-2 systemd[1]: Started Music Player Daemon. Nov 21 03:01:50 volumio-2 sudo[14550]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:50 volumio-2 volumio[14514]: info: Discovery: Started advertising with name: Volumio 2 Nov 21 03:01:50 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 03:01:50 volumio-2 volumio[14514]: info: Loading plugin "calmradio"... Nov 21 03:01:50 volumio-2 volumio[14514]: info: Loading plugin "autostart"... Nov 21 03:01:50 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin autostart Nov 21 03:01:50 volumio-2 volumio[14514]: info: AutoStart - onVolumioStart - read config.json Nov 21 03:01:50 volumio-2 volumio[14514]: info: Loading plugin "gpio-buttons"... Nov 21 03:01:50 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:50] [connect] Successful connection Nov 21 03:01:51 volumio-2 volumio[14514]: info: GPIO-Buttons initialized Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "now_playing"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "outputs"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "albumart"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Plugin example_plugin is not enabled Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "inputs"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "updater_comm"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Plugin mpdemulation is not enabled Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "rest_api"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "websocket"... Nov 21 03:01:51 volumio-2 volumio[14514]: info: Loading plugin "80s80s"... Nov 21 03:01:52 volumio-2 volumio[14514]: Forking 3 albumart workers Nov 21 03:01:52 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin 80s80s Nov 21 03:01:52 volumio-2 volumio[14514]: info: [1732158112236] [80s80s] API delay: 30 Nov 21 03:01:52 volumio-2 volumio[14514]: info: Loading plugin "lms"... Nov 21 03:01:52 volumio-2 sudo[14543]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:52 volumio-2 volumio[14514]: info: Loading plugin "swarmstation"... Nov 21 03:01:52 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin swarmstation Nov 21 03:01:52 volumio-2 volumio[14514]: info: [1732158112589] [Swarmstation] API delay: 5 Nov 21 03:01:52 volumio-2 volumio[14514]: info: Loading plugin "audiophonicsonoff"... Nov 21 03:01:52 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin audiophonicsonoff Nov 21 03:01:52 volumio-2 volumio[14514]: info: Audiophonics on/off initiated Nov 21 03:01:52 volumio-2 volumio[14514]: info: Loading plugin "backup_restore"... Nov 21 03:01:52 volumio-2 volumio[14514]: Starting albumart workers Nov 21 03:01:53 volumio-2 volumio[14514]: Starting albumart workers Nov 21 03:01:53 volumio-2 volumio[14514]: Starting albumart workers Nov 21 03:01:53 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin backup_restore Nov 21 03:01:53 volumio-2 volumio[14514]: info: Loading plugin "audiophonics_evo_sabre"... Nov 21 03:01:53 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Nov 21 03:01:53 volumio-2 volumio[14514]: info: Loading plugin "gpio_control"... Nov 21 03:01:53 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin gpio_control Nov 21 03:01:53 volumio-2 volumio[14514]: info: [GPIO_Control] Detected Raspberry Pi 4 Model B Rev 1.4 Nov 21 03:01:53 volumio-2 volumio[14514]: info: [GPIO_Control] 40 GPIOs: true Nov 21 03:01:53 volumio-2 volumio[14514]: info: [GPIO_Control] Initialized Nov 21 03:01:53 volumio-2 volumio[14514]: info: Loading plugin "ir_controller"... Nov 21 03:01:53 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin ir_controller Nov 21 03:01:54 volumio-2 volumio[14514]: info: Loading plugin "pirateaudio"... Nov 21 03:01:54 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin pirateaudio Nov 21 03:01:54 volumio-2 volumio[14514]: info: Plugin remotepi is not enabled Nov 21 03:01:54 volumio-2 volumio[14514]: info: Plugin rotaryencoder2 is not enabled Nov 21 03:01:54 volumio-2 volumio[14514]: info: Loading plugin "Systeminfo"... Nov 21 03:01:54 volumio-2 volumio[14514]: info: Loading plugin "lastfm"... Nov 21 03:01:54 volumio-2 volumio[14514]: info: Loading plugin "randomizer"... Nov 21 03:01:54 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin randomizer Nov 21 03:01:54 volumio-2 volumio[14514]: info: Loading plugin "touch_display"... Nov 21 03:01:54 volumio-2 volumio[14514]: info: Applying required configuration parameters for plugin touch_display Nov 21 03:01:55 volumio-2 volumio[14514]: info: Loading i18n strings for locale en Nov 21 03:01:55 volumio-2 volumio[14514]: Updating browse sources language Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::initPlayerControls Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: Express server listening on port 3000 Nov 21 03:01:55 volumio-2 volumio[14514]: [Metrics] WebUI: 8s 222.68ms Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::resetVolumioState Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::getcurrentVolume Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::pushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: MPD Permissions set Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 2 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 3 Nov 21 03:01:55 volumio-2 volumio-remote-updater[556]: [2024-11-21 03:01:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732158110 101 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Nov 21 03:01:55 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Nov 21 03:01:55 volumio-2 volumio[14514]: info: MPD running with PID14555 Nov 21 03:01:55 volumio-2 volumio[14514]: ,establishing connection Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: Executing start script for DAC IQaudIO DAC Plus Nov 21 03:01:55 volumio-2 volumio[14514]: info: Reloading queue from file Nov 21 03:01:55 volumio-2 volumio[14514]: error: updateQueue error: null Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::setRepeat null single undefined Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::pushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::setRandom null Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreStateMachine::pushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioPushState Nov 21 03:01:55 volumio-2 volumio[14514]: info: Setting Device type: Raspberry PI Nov 21 03:01:55 volumio-2 volumio[14514]: info: DAC script executed Nov 21 03:01:55 volumio-2 volumio[14514]: info: Completed loading Core Plugins Nov 21 03:01:55 volumio-2 volumio[14514]: info: Preparing to generate the ALSA configuration file Nov 21 03:01:55 volumio-2 volumio[14514]: error: updateQueue error: null Nov 21 03:01:55 volumio-2 volumio[14514]: info: Asound.conf file unchanged, so no further update is needed Nov 21 03:01:55 volumio-2 volumio[14514]: info: Output device has changed, restarting MPD Nov 21 03:01:55 volumio-2 sudo[14641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 03:01:55 volumio-2 sudo[14641]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:55 volumio-2 sudo[14641]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:55 volumio-2 volumio[14514]: info: Output device has changed, restarting Shairport Sync Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:55 volumio-2 sudo[14644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 03:01:55 volumio-2 sudo[14644]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:55 volumio-2 systemd[1]: Stopping Music Player Daemon... Nov 21 03:01:55 volumio-2 systemd[1]: mpd.service: Succeeded. Nov 21 03:01:55 volumio-2 systemd[1]: Stopped Music Player Daemon. Nov 21 03:01:55 volumio-2 volumio[14514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: ___________ START PLUGINS ___________ Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:55 volumio-2 volumio[14514]: info: [1732158115858] CoreMusicLibrary::Adding element Media Servers Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:55 volumio-2 systemd[1]: Starting Music Player Daemon... Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:55 volumio-2 volumio[14514]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:55 volumio-2 volumio[14514]: info: [1732158115988] CoreMusicLibrary::Adding element Last_100 Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:55 volumio-2 volumio[14514]: info: [1732158115990] CoreMusicLibrary::Adding element Webradio Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:55 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:56 volumio-2 volumio[14514]: info: Adding Calm Radio to Browse Sources Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:56 volumio-2 volumio[14514]: info: [1732158116001] CoreMusicLibrary::Adding element Calm Radio Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source Calm Radio Nov 21 03:01:56 volumio-2 volumio[14514]: info: AutoStart - onStart Nov 21 03:01:56 volumio-2 volumio[14514]: info: GPIO-Buttons: Reading config and creating triggers... Nov 21 03:01:56 volumio-2 volumio[14514]: info: GPIO-Buttons: playPause on pin 17 Nov 21 03:01:56 volumio-2 volumio[14514]: info: GPIO-Buttons: previous on pin 23 Nov 21 03:01:56 volumio-2 volumio[14514]: info: [now-playing] ConfigUpdater: config is up to date. Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:56 volumio-2 volumio[14514]: info: [1732158116038] CoreMusicLibrary::Adding element 80s80s Radio Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source Calm Radio Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source 80s80s Radio Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:56 volumio-2 volumio[14514]: info: [1732158116067] CoreMusicLibrary::Adding element SWARM Station Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source Calm Radio Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source 80s80s Radio Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source SWARM Station Nov 21 03:01:56 volumio-2 volumio[14514]: info: Configuring GPIO pins Nov 21 03:01:56 volumio-2 volumio[14514]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write Nov 21 03:01:56 volumio-2 volumio[14514]: info: EVO SABRE : Starting Plugin Nov 21 03:01:56 volumio-2 volumio[14514]: info: Loading i18n strings for locale en Nov 21 03:01:56 volumio-2 sudo[14659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 21 03:01:56 volumio-2 sudo[14659]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:56 volumio-2 autossh[12919]: starting ssh (count 11) Nov 21 03:01:56 volumio-2 autossh[12919]: ssh child pid is 14661 Nov 21 03:01:56 volumio-2 systemd[1]: Stopping Logitech Media Server Daemon... Nov 21 03:01:56 volumio-2 volumio[14514]: info: [GPIO_Control] Reading config and creating GPIOs Nov 21 03:01:56 volumio-2 volumio[14514]: info: Loading i18n strings for locale en Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:17.2350] Slim::Networking::Repositories::__ANON__ (147) Failed to fetch https://github.com/LMS-Community/lms-plugin-repository/raw/master/extensions.xml: Connect timed out: Connection refused Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:17.2510] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: Connect timed out: Connection refused Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:17.2517] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Connect timed out: Connection refused Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:19.5352] Slim::Formats::XML::gotViaHTTP (271) XML/JSON parse error: Invalid XML feed Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:19.5357] Slim::Plugin::InternetRadio::Plugin::_gotRadioError (71) Unable to retrieve radio directory from SN: Failed to parse Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:32.2287] Slim::Networking::Repositories::__ANON__ (147) Failed to fetch https://github.com/LMS-Community/lms-plugin-repository/raw/master/extensions.xml: Connect timed out: Bad file descriptor Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:32.2320] Slim::Formats::XML::gotErrorViaHTTP (343) Error: getting https://www.mysqueezebox.com/api/sounds/v1/opml Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: Connect timed out: Bad file descriptor Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:32.2325] Slim::Plugin::Sounds::Plugin::_gotSoundsError (82) Error: Unable to cache Sounds & Effects menu from SN: Connect timed out: Bad file descriptor Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.3146] Slim::Formats::XML::gotViaHTTP (271) XML/JSON parse error: Invalid XML feed Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:33.3154] Slim::Plugin::InternetRadio::Plugin::_gotRadioError (71) Unable to retrieve radio directory from SN: Failed to parse Nov 21 03:01:56 volumio-2 squeezeboxserver[14356]: [24-11-21 03:01:34.1951] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 03:01:56 volumio-2 volumio[14514]: info: [1732158116180] CoreMusicLibrary::Adding element LastFM Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source Calm Radio Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source 80s80s Radio Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source SWARM Station Nov 21 03:01:56 volumio-2 volumio[14514]: Cannot find translation for source LastFM Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] scrobbler initiated! Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] extended logging: false Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] try scrobble stream/radio plays: true Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] Left init routine Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] Socket already connected: true Nov 21 03:01:56 volumio-2 sudo[14665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Nov 21 03:01:56 volumio-2 sudo[14665]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:56 volumio-2 volumio[14514]: info: Loading i18n strings for locale en Nov 21 03:01:56 volumio-2 systemd[1]: logitechmediaserver.service: Succeeded. Nov 21 03:01:56 volumio-2 systemd[1]: Stopped Logitech Media Server Daemon. Nov 21 03:01:56 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 03:01:56 volumio-2 volumio[14514]: info: Volumio Calling Home Nov 21 03:01:56 volumio-2 systemd[1]: Starting Logitech Media Server Daemon... Nov 21 03:01:56 volumio-2 systemd[1]: Started Logitech Media Server Daemon. Nov 21 03:01:56 volumio-2 volumio[14514]: info: GPIO-Buttons started Nov 21 03:01:56 volumio-2 sudo[14659]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:56 volumio-2 volumio[14514]: info: [GPIO_Control] GPIOs created Nov 21 03:01:56 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:56 volumio-2 sudo[14665]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:56 volumio-2 volumio[14514]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Nov 21 03:01:56 volumio-2 sudo[14677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 21 03:01:56 volumio-2 sudo[14677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:56 volumio-2 sudo[14677]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:56 volumio-2 sudo[14680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 03:01:56 volumio-2 sudo[14680]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:56 volumio-2 systemd[1]: Reloading. Nov 21 03:01:56 volumio-2 volumio[14514]: info: [now-playing] App is listening on port 4004. Nov 21 03:01:56 volumio-2 volumio[14514]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Nov 21 03:01:57 volumio-2 volumio[14514]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 21 03:01:57 volumio-2 volumio[14514]: info: Pirate audio service (daemon) started Nov 21 03:01:57 volumio-2 mpd[14657]: Nov 21 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 03:01:57 volumio-2 mpd[14657]: Nov 21 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 21 03:01:57 volumio-2 mpd[14657]: Nov 21 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 21 03:01:57 volumio-2 volumio[14514]: info: logitechmediaserver started Nov 21 03:01:57 volumio-2 volumio[14514]: info: MPD Permissions set Nov 21 03:01:57 volumio-2 sudo[14698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 03:01:57 volumio-2 sudo[14698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:57 volumio-2 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. Nov 21 03:01:57 volumio-2 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 21 03:01:57 volumio-2 volumio[14674]: Traceback (most recent call last): Nov 21 03:01:57 volumio-2 volumio[14674]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:57 volumio-2 volumio[14674]: offset_top=0 # v0.0.6 Nov 21 03:01:57 volumio-2 volumio[14674]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:57 volumio-2 volumio[14674]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:57 volumio-2 volumio[14674]: RuntimeError: Not running on a RPi! Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:01:57 volumio-2 volumio[14514]: info: touch_display: Raspberry Pi Foundation touch screen detected. Nov 21 03:01:57 volumio-2 volumio[14514]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Nov 21 03:01:57 volumio-2 volumio[14514]: info: Starting Shairport Sync Nov 21 03:01:57 volumio-2 volumio[14514]: info: Starting Shairport Sync Nov 21 03:01:57 volumio-2 systemd[1]: Started Music Player Daemon. Nov 21 03:01:57 volumio-2 sudo[14680]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:57 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:57 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:01:57 volumio-2 sudo[14644]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:57 volumio-2 sudo[14705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:57 volumio-2 sudo[14705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:57 volumio-2 systemd[1]: Reloading. Nov 21 03:01:57 volumio-2 volumio[14514]: info: Starting Shairport Sync Nov 21 03:01:57 volumio-2 sudo[14708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:57 volumio-2 sudo[14708]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 volumio[14514]: info: ir_controller: LIRC correctly updated. Nov 21 03:01:58 volumio-2 sudo[14716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 03:01:58 volumio-2 sudo[14716]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 volumio[14514]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 21 03:01:58 volumio-2 volumio[14514]: info: touch_display: systemctl daemon-reload succeeded. Nov 21 03:01:58 volumio-2 sudo[14727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 21 03:01:58 volumio-2 sudo[14727]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 volumio[14514]: error: updateQueue error: null Nov 21 03:01:58 volumio-2 sudo[14730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Nov 21 03:01:58 volumio-2 sudo[14730]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 sudo[14730]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 volumio[14514]: info: touch_display: File permissions for backlight brightness control set. Nov 21 03:01:58 volumio-2 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. Nov 21 03:01:58 volumio-2 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 21 03:01:58 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:01:58 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1. Nov 21 03:01:58 volumio-2 sudo[14698]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 03:01:58 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:01:58 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:01:58 volumio-2 sudo[14735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 03:01:58 volumio-2 sudo[14735]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 systemd[1]: shairport-sync.service: Succeeded. Nov 21 03:01:58 volumio-2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 03:01:58 volumio-2 sudo[14727]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 03:01:58 volumio-2 sudo[14705]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 sudo[14708]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 sudo[14735]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 sudo[14716]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 volumio[14514]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 10 Nov 21 03:01:58 volumio-2 volumio[14514]: info: EVO SABRE : systemd cmd daemon-reload : success Nov 21 03:01:58 volumio-2 sudo[14738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 03:01:58 volumio-2 sudo[14738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:58 volumio-2 volumio[14514]: info: Shairport-Sync Started Nov 21 03:01:58 volumio-2 volumio[14514]: Error adding Membership: Error: addMembership EINVAL Nov 21 03:01:58 volumio-2 sudo[14738]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:58 volumio-2 volumio[14514]: info: Shairport-Sync Started Nov 21 03:01:58 volumio-2 volumio[14514]: info: Shairport-Sync Started Nov 21 03:01:58 volumio-2 volumio[14514]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 21 03:01:59 volumio-2 volumio[14514]: info: touch_display: Volumio Kiosk started Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:59 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:59 volumio-2 sudo[14745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Nov 21 03:01:59 volumio-2 sudo[14745]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:59 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:59 volumio-2 systemd[1]: Stopping OLED Display Service for EVO SABRE... Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:59 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:59 volumio-2 volumio[14514]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 03:01:59 volumio-2 sudo[14750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 21 03:01:59 volumio-2 sudo[14750]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 sudo[14437]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:59 volumio-2 systemd[1]: evo_oled2.service: Succeeded. Nov 21 03:01:59 volumio-2 systemd[1]: Stopped OLED Display Service for EVO SABRE. Nov 21 03:01:59 volumio-2 sudo[14754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 03:01:59 volumio-2 sudo[14754]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 systemd[1]: Started OLED Display Service for EVO SABRE. Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 03:01:59 volumio-2 sudo[14745]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:59 volumio-2 sudo[14753]: 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 Nov 21 03:01:59 volumio-2 sudo[14753]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 volumio[14514]: info: Listing playlists Nov 21 03:01:59 volumio-2 lircd[14455]: lircd-0.10.1[14455]: Notice: caught signal Nov 21 03:01:59 volumio-2 lircd-0.10.1[14455]: Notice: caught signal Nov 21 03:01:59 volumio-2 systemd[1]: Stopping Flexible IR remote input/output application support... Nov 21 03:01:59 volumio-2 systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Nov 21 03:01:59 volumio-2 systemd[1]: lircd.service: Succeeded. Nov 21 03:01:59 volumio-2 systemd[1]: Stopped Flexible IR remote input/output application support. Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreStateMachine::getQueue Nov 21 03:01:59 volumio-2 volumio[14514]: info: CorePlayQueue::getQueue Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 03:01:59 volumio-2 sudo[14754]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:59 volumio-2 systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 03:01:59 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:01:59 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:01:59 volumio-2 volumio[14514]: info: Upmpdcli Daemon Started Nov 21 03:01:59 volumio-2 volumio[14514]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 21 03:01:59 volumio-2 volumio[14514]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Nov 21 03:01:59 volumio-2 volumio[14514]: info: touch_display: Setting screensaver timeout to 501 seconds. Nov 21 03:01:59 volumio-2 sudo[14769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Nov 21 03:01:59 volumio-2 sudo[14769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 lircd-setup[14764]: /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. Nov 21 03:01:59 volumio-2 lircd-setup[14764]: parser = configparser.SafeConfigParser() Nov 21 03:01:59 volumio-2 systemd[1]: lircd-setup.service: Succeeded. Nov 21 03:01:59 volumio-2 systemd[1]: Started lircd(8) initialization helper tool. Nov 21 03:01:59 volumio-2 systemd[1]: Started evo_remote.service. Nov 21 03:01:59 volumio-2 sudo[14769]: pam_unix(sudo:session): session closed for user root Nov 21 03:01:59 volumio-2 volumio[14514]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Nov 21 03:01:59 volumio-2 systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Version: lircd 0.10.1 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: System info: Linux volumio-2 6.1.58-v7l+ #1690 SMP Tue Oct 17 15:36:02 BST 2023 armv7l GNU/Linux Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Version: lircd 0.10.1 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: System info: Linux volumio-2 6.1.58-v7l+ #1690 SMP Tue Oct 17 15:36:02 BST 2023 armv7l GNU/Linux Nov 21 03:01:59 volumio-2 sudo[14778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Nov 21 03:01:59 volumio-2 sudo[14778]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: Initial device: /dev/lirc0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: [lirc] protocol is enabled Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: Initial device: /dev/lirc0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: [lirc] protocol is enabled Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: driver: default Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: output: /var/run/lirc/lircd Nov 21 03:01:59 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: nodaemon: 1 Nov 21 03:01:59 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Warning: Running as root Nov 21 03:01:59 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Info: Using remote: ApEvo. Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 03:01:59 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: logfile: syslog Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: immediate-init: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: permission: 666 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: driver-options: Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: listen: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: connect: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: userelease: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: effective_user: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: release_suffix: _EVUP Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: allow_simulate: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: repeat_max: 600 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Options: dynamic_codes: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Current driver: default Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Driver API version: 3 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Driver version: 0.10.0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Warning: Running as root Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Info: Using remote: ApEvo. Nov 21 03:01:59 volumio-2 lircd-0.10.1[14771]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: Initial device: /dev/lirc0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: [lirc] protocol is enabled Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: Initial device: /dev/lirc0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: [lirc] protocol is enabled Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: driver: default Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: output: /var/run/lirc/lircd Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: nodaemon: 1 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: logfile: syslog Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: immediate-init: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: permission: 666 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: driver-options: Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: listen: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: connect: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: userelease: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: effective_user: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: release_suffix: _EVUP Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: allow_simulate: 0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: repeat_max: 600 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Options: dynamic_codes: (null) Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Current driver: default Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Driver API version: 3 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Driver version: 0.10.0 Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 21 03:01:59 volumio-2 lircd[14772]: lircd-0.10.1[14772]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd-0.10.1[14772]: Info: lircd: Opening log, level: Info Nov 21 03:01:59 volumio-2 lircd[14772]: lircd: there seems to already be a lircd process with pid 14771 Nov 21 03:01:59 volumio-2 lircd[14772]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 03:01:59 volumio-2 volumio[14737]: Traceback (most recent call last): Nov 21 03:01:59 volumio-2 volumio[14737]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:01:59 volumio-2 volumio[14737]: offset_top=0 # v0.0.6 Nov 21 03:01:59 volumio-2 volumio[14737]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:01:59 volumio-2 volumio[14737]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:01:59 volumio-2 volumio[14737]: RuntimeError: Not running on a RPi! Nov 21 03:01:59 volumio-2 systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 03:01:59 volumio-2 systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:01:59 volumio-2 systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 03:02:00 volumio-2 systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 03:02:00 volumio-2 sudo[14750]: pam_unix(sudo:session): session closed for user root Nov 21 03:02:00 volumio-2 volumio[14514]: error: ir_controller: Failed to restart lircd.service: Error: Command failed: /usr/bin/sudo /bin/systemctl restart lircd.service Nov 21 03:02:00 volumio-2 volumio[14514]: Job for lircd.service failed because the control process exited with error code. Nov 21 03:02:00 volumio-2 volumio[14514]: See "systemctl status lircd.service" and "journalctl -xe" for details. Nov 21 03:02:00 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:02:00 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:00 volumio-2 lircd-setup[14780]: /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. Nov 21 03:02:00 volumio-2 lircd-setup[14780]: parser = configparser.SafeConfigParser() Nov 21 03:02:00 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:02:00 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 2. Nov 21 03:02:00 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:02:00 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:02:00 volumio-2 systemd[1]: lircd-setup.service: Succeeded. Nov 21 03:02:00 volumio-2 systemd[1]: Started lircd(8) initialization helper tool. Nov 21 03:02:00 volumio-2 systemd[1]: Started evo_irexec.service. Nov 21 03:02:00 volumio-2 sudo[14778]: pam_unix(sudo:session): session closed for user root Nov 21 03:02:00 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Notice: accepted new client on /var/run/lirc/lircd Nov 21 03:02:00 volumio-2 lircd-0.10.1[14771]: Notice: accepted new client on /var/run/lirc/lircd Nov 21 03:02:00 volumio-2 lircd[14771]: lircd-0.10.1[14771]: Info: [lirc] protocol is enabled Nov 21 03:02:00 volumio-2 lircd-0.10.1[14771]: Info: [lirc] protocol is enabled Nov 21 03:02:00 volumio-2 volumio[14514]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Nov 21 03:02:00 volumio-2 volumio[14514]: info: Completed starting Core Plugins Nov 21 03:02:00 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:02:00 volumio-2 volumio[14514]: info: ----- MyVolumio plugins startup ---- Nov 21 03:02:00 volumio-2 volumio[14514]: info: ------------------------------------------- Nov 21 03:02:00 volumio-2 volumio[14514]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 03:02:00 volumio-2 volumio[14514]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 21 03:02:01 volumio-2 volumio[14785]: Traceback (most recent call last): Nov 21 03:02:01 volumio-2 volumio[14785]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:02:01 volumio-2 volumio[14785]: offset_top=0 # v0.0.6 Nov 21 03:02:01 volumio-2 volumio[14785]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:02:01 volumio-2 volumio[14785]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:02:01 volumio-2 volumio[14785]: RuntimeError: Not running on a RPi! Nov 21 03:02:01 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:02:01 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:01 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:02:01 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 3. Nov 21 03:02:01 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:02:01 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:02:01 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Nov 21 03:02:01 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:01 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:01 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:02:01 volumio-2 volumio[14514]: info: CoreStateMachine::getQueue Nov 21 03:02:01 volumio-2 volumio[14514]: info: CorePlayQueue::getQueue Nov 21 03:02:01 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:01 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:00.3646] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 - arm-linux-gnueabihf-thread-multi-64int Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1879] Slim::Plugin::FullTextSearch::Plugin::_initPopularTerms (592) Fulltext index missing or outdated - re-building Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1887] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1895] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1948] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1966] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.1980] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.2007] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.2072] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.2106] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 21 03:02:02 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:02.4124] main::checkDataSource (1107) Warning: Schema updated or no media found in the database, initiating scan. Nov 21 03:02:02 volumio-2 volumio[14788]: Traceback (most recent call last): Nov 21 03:02:02 volumio-2 volumio[14788]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:02:02 volumio-2 volumio[14788]: offset_top=0 # v0.0.6 Nov 21 03:02:02 volumio-2 volumio[14788]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:02:02 volumio-2 volumio[14788]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:02:02 volumio-2 volumio[14788]: RuntimeError: Not running on a RPi! Nov 21 03:02:02 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:02:02 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:02 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:02:02 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 4. Nov 21 03:02:02 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:02:02 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:02:03 volumio-2 volumio[14790]: Traceback (most recent call last): Nov 21 03:02:03 volumio-2 volumio[14790]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:02:03 volumio-2 volumio[14790]: offset_top=0 # v0.0.6 Nov 21 03:02:03 volumio-2 volumio[14790]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:02:03 volumio-2 volumio[14790]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:02:03 volumio-2 volumio[14790]: RuntimeError: Not running on a RPi! Nov 21 03:02:03 volumio-2 volumio[14514]: info: Discovery: adding e24397a4-2819-4e00-9a76-d91cf8feb329 Nov 21 03:02:03 volumio-2 volumio[14514]: info: Discovery: Found device Volumio 2 Nov 21 03:02:03 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:03 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:03 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:02:03 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:03 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:03 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:03 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Nov 21 03:02:03 volumio-2 volumio[14514]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Nov 21 03:02:03 volumio-2 volumio[14514]: info: Discovery: this is already registered, e24397a4-2819-4e00-9a76-d91cf8feb329 Nov 21 03:02:03 volumio-2 volumio[14514]: info: Discovery: Found device Volumio 2 Nov 21 03:02:03 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:03 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:04 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:02:04 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5. Nov 21 03:02:04 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:02:04 volumio-2 systemd[1]: Started Service for using pirate audio display in volumio. Nov 21 03:02:04 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:04.1196] main::main (202) Starting Logitech Media Server scanner (v8.3.1, 1676361197, Fri 17 Feb 2023 06:39:27 AM CET) perl 5.028001 Nov 21 03:02:04 volumio-2 volumiossh-tunnel[12917]: ssh: connect to host eu4.myvolumio.org port 2222: Connection refused Nov 21 03:02:04 volumio-2 autossh[12919]: ssh exited with error status 255; restarting ssh Nov 21 03:02:04 volumio-2 volumio[14791]: Traceback (most recent call last): Nov 21 03:02:04 volumio-2 volumio[14791]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 39, in Nov 21 03:02:04 volumio-2 volumio[14791]: offset_top=0 # v0.0.6 Nov 21 03:02:04 volumio-2 volumio[14791]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 139, in __init__ Nov 21 03:02:04 volumio-2 volumio[14791]: GPIO.setup(dc, GPIO.OUT) Nov 21 03:02:04 volumio-2 volumio[14791]: RuntimeError: Not running on a RPi! Nov 21 03:02:04 volumio-2 systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 21 03:02:04 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:05 volumio-2 systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 21 03:02:05 volumio-2 systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 6. Nov 21 03:02:05 volumio-2 systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 21 03:02:05 volumio-2 systemd[1]: pirateaudio.service: Start request repeated too quickly. Nov 21 03:02:05 volumio-2 systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 21 03:02:05 volumio-2 systemd[1]: Failed to start Service for using pirate audio display in volumio. Nov 21 03:02:11 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetState Nov 21 03:02:11 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:12 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:12.9820] Slim::Plugin::Deezer::Importer::isImportEnabled (42) Failed to get Deezer accounts: Can't connect to www.mysqueezebox.com:443 (Connection refused) Nov 21 03:02:13 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:13.0187] Slim::Music::Import::runImporter (579) Starting Slim::Media::MediaFolderScan scan Nov 21 03:02:13 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:13.0192] Slim::Music::Import::endImporter (712) Completed Slim::Media::MediaFolderScan Scan in 0.001 seconds. Nov 21 03:02:13 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:13.0213] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::WiMP::Importer scan Nov 21 03:02:14 volumio-2 volumio[14514]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.569&uuid=8d328452240fc1066cf80b63dd4dd6e9" http://updates.volumio.org/downloader-v1/track-device Nov 21 03:02:14 volumio-2 volumio[14514]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 03:02:14 volumio-2 volumio[14514]: Dload Upload Total Spent Left Speed Nov 21 03:02:14 volumio-2 volumio[14514]: [790B blob data] Nov 21 03:02:14 volumio-2 volumio[14514]: curl: (52) Empty reply from server Nov 21 03:02:14 volumio-2 volumio[14514]: retrying in 5 seconds, trial 0 Nov 21 03:02:14 volumio-2 volumio[14514]: info: Volumio Calling Home Nov 21 03:02:16 volumio-2 volumio[14514]: info: AutoStart - Plugin is starting Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioGetQueue Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::getQueue Nov 21 03:02:16 volumio-2 volumio[14514]: info: CorePlayQueue::getQueue Nov 21 03:02:16 volumio-2 volumio[14514]: info: AutoStart - start playing Nov 21 03:02:16 volumio-2 volumio[14514]: info: AutoStart - start playing with no specific position Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioPlay Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::play index 0 Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::stop Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::play index undefined Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 21 03:02:16 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:16 volumio-2 volumio[14514]: info: CoreStateMachine::startPlaybackTimer Nov 21 03:02:16 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:16 volumio-2 volumio[14514]: info: [1732158136015] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Nov 21 03:02:16 volumio-2 volumio[14514]: info: [1732158136016] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Nov 21 03:02:20 volumio-2 volumio[14514]: error: MyVolumio Plugin failed to start in a timely fashion Nov 21 03:02:20 volumio-2 volumio[14514]: info: BOOT COMPLETED Nov 21 03:02:20 volumio-2 volumio[14514]: [Metrics] CommandRouter: 33s 44.69ms Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::volumiosetStartupVolume Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 03:02:20 volumio-2 volumio[14514]: info: VolumeController:: Setting startup Volume 5 Nov 21 03:02:20 volumio-2 volumio[14514]: info: VolumeController::SetAlsaVolume5 Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::Close All Modals sent Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::Close All Modals sent Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreStateMachine::pushState Nov 21 03:02:20 volumio-2 volumio[14514]: info: CorePlayQueue::getTrack 0 Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 03:02:20 volumio-2 volumio[14514]: info: CoreCommandRouter::volumioPushState Nov 21 03:02:20 volumio-2 kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0360] Slim::Plugin::WiMP::Importer::startScan (44) Failed to get TIDAL accounts: Can't connect to www.mysqueezebox.com:443 (Connection refused) Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0369] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::ExtendedBrowseModes::Libraries scan Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0374] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::ExtendedBrowseModes::Libraries Scan in 0.001 seconds. Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0383] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::FullTextSearch::Plugin scan Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0398] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (480) Starting fulltext index build Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0402] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (488) Initialize fulltext table Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.0422] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (501) Create fulltext index for tracks Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1121] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (511) Create fulltext index for albums Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1145] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (520) Create fulltext index for contributors Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1169] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (530) Create fulltext index for playlists Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1260] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (545) Optimize fulltext index Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1411] Slim::Plugin::FullTextSearch::Plugin::_rebuildIndex (560) Fulltext index build done! Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1432] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::FullTextSearch::Plugin Scan in 0.104 seconds. Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1448] Slim::Music::Import::runImporter (579) Starting Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup scan Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1467] Slim::Music::Import::endImporter (712) Completed Slim::Plugin::OnlineLibrary::Importer::VirtualLibrariesCleanup Scan in 0.001 seconds. Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1503] Slim::Music::Artwork::precacheAllArtwork (657) Starting precacheArtwork for 0 albums Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1508] Slim::Music::Import::endImporter (712) Completed precacheArtwork Scan in 0.002 seconds. Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.1516] Slim::Music::Import::runScanPostProcessing (481) Starting Database optimization. Nov 21 03:02:21 volumio-2 squeezeboxserver[14672]: [24-11-21 03:02:21.2687] Slim::Music::Import::endImporter (712) Completed dbOptimize Scan in 0.117 seconds. Nov 21 03:02:21 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 03:02:21 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 03:02:21 volumio-2 volumio[14514]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 03:02:24 volumio-2 volumio[14514]: info: [1732158144153] [80s80s] Failed to query api, status code: 302 Nov 21 03:02:24 volumio-2 volumio[14514]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 03:02:24 volumio-2 volumio[14514]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Nov 21 03:02:24 volumio-2 volumio[14514]: at Object.openSync (fs.js:476:3) Nov 21 03:02:24 volumio-2 volumio[14514]: at Object.readFileSync (fs.js:377:35) Nov 21 03:02:24 volumio-2 volumio[14514]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Nov 21 03:02:24 volumio-2 volumio[14514]: at Object.onceWrapper (events.js:422:26) Nov 21 03:02:24 volumio-2 volumio[14514]: at ClientRequest.emit (events.js:315:20) Nov 21 03:02:24 volumio-2 volumio[14514]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Nov 21 03:02:24 volumio-2 volumio[14514]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Nov 21 03:02:24 volumio-2 volumio[14514]: at Socket.socketOnData (_http_client.js:509:22) Nov 21 03:02:24 volumio-2 volumio[14514]: at Socket.emit (events.js:315:20) Nov 21 03:02:24 volumio-2 volumio[14514]: at addChunk (internal/streams/readable.js:309:12) Nov 21 03:02:24 volumio-2 volumio[14514]: at readableAddChunk (internal/streams/readable.js:284:9) Nov 21 03:02:24 volumio-2 volumio[14514]: at Socket.Readable.push (internal/streams/readable.js:223:10) Nov 21 03:02:24 volumio-2 volumio[14514]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) { Nov 21 03:02:24 volumio-2 volumio[14514]: errno: -2, Nov 21 03:02:24 volumio-2 volumio[14514]: syscall: 'open', Nov 21 03:02:24 volumio-2 volumio[14514]: code: 'ENOENT', Nov 21 03:02:24 volumio-2 volumio[14514]: path: '/data/plugins/music_service/80s80s/fake-data.json' Nov 21 03:02:24 volumio-2 volumio[14514]: } Nov 21 03:02:24 volumio-2 volumio[14514]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 03:02:24 volumio-2 sudo[14807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 03:01 Nov 21 03:02:24 volumio-2 sudo[14807]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"