-- Logs begin at Tue 2024-08-20 16:50:39 EDT, end at Tue 2024-08-20 16:57:28 EDT. -- Aug 20 16:56:01 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:01 zeta7 volumio[1529]: info: ----- Volumio3 ---- Aug 20 16:56:01 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:01 zeta7 volumio[1529]: info: ----- System startup ---- Aug 20 16:56:01 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:02 zeta7 volumio-remote-updater[472]: [2024-08-20 16:56:02] [connect] Successful connection Aug 20 16:56:02 zeta7 volumio[1529]: info: MYVOLUMIO Environment detected Aug 20 16:56:03 zeta7 volumio[1529]: info: Plugin folders cleanup Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning into folder /volumio/app/plugins/ Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category audio_interface Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category miscellanea Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category music_service Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category plugins.json Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category system_controller Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category user_interface Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning into folder /data/plugins/ Aug 20 16:56:03 zeta7 volumio[1529]: info: Scanning category system_hardware Aug 20 16:56:03 zeta7 volumio[1529]: info: Plugin folders cleanup completed Aug 20 16:56:03 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:03 zeta7 volumio[1529]: info: ----- Core plugins startup ---- Aug 20 16:56:03 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:03 zeta7 volumio[1529]: info: Loading plugins from folder /volumio/app/plugins/ Aug 20 16:56:03 zeta7 volumio[1529]: info: Adding plugin upnp to MyMusic Plugins Aug 20 16:56:03 zeta7 volumio[1529]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 20 16:56:03 zeta7 volumio[1529]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 20 16:56:03 zeta7 volumio[1529]: info: Loading plugins from folder /data/plugins/ Aug 20 16:56:03 zeta7 volumio[1529]: info: Loading plugin "system"... Aug 20 16:56:03 zeta7 volumio[1529]: info: Loading plugin "appearance"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "network"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Refreshing Cached IP Addresses Aug 20 16:56:05 zeta7 sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 16:56:05 zeta7 sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:05 zeta7 sudo[1557]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "services"... Aug 20 16:56:05 zeta7 sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 16:56:05 zeta7 sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "alsa_controller"... Aug 20 16:56:05 zeta7 sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 20 16:56:05 zeta7 sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:05 zeta7 sudo[1559]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:05 zeta7 sudo[1562]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:05 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "wizard"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "networkfs"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Starting Udev Watcher for removable devices Aug 20 16:56:05 zeta7 volumio[1529]: info: Ignoring mount for partition: boot Aug 20 16:56:05 zeta7 volumio[1529]: info: Ignoring mount for partition: volumio Aug 20 16:56:05 zeta7 volumio[1529]: info: Ignoring mount for partition: volumio_data Aug 20 16:56:05 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "volumio_command_line_client"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "upnp"... Aug 20 16:56:05 zeta7 volumio[1529]: info: [1724187365709] Starting Upmpd Daemon Aug 20 16:56:05 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "my_music"... Aug 20 16:56:05 zeta7 volumio[1529]: info: Loading plugin "mpd"... Aug 20 16:56:06 zeta7 volumio[1529]: info: Loading plugin "upnp_browser"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "alarm-clock"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "airplay_emulation"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Starting Shairport Sync Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "last_100"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "webradio"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "i2s_dacs"... Aug 20 16:56:07 zeta7 volumio[1529]: info: I2S DAC not set, start Auto-detection Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "volumiodiscovery"... Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** For more information see Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** For more information see Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 20 16:56:07 zeta7 volumio[1529]: *** WARNING *** For more information see Aug 20 16:56:07 zeta7 node[1529]: *** WARNING *** For more information see Aug 20 16:56:07 zeta7 volumio[1529]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 20 16:56:07 zeta7 volumio[1529]: info: Discovery: Started advertising with name: Zeta7 Aug 20 16:56:07 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "outputs"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "albumart"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Plugin example_plugin is not enabled Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "inputs"... Aug 20 16:56:07 zeta7 volumio[1529]: info: Loading plugin "updater_comm"... Aug 20 16:56:08 zeta7 volumio[1529]: info: Plugin mpdemulation is not enabled Aug 20 16:56:08 zeta7 volumio[1529]: info: Loading plugin "rest_api"... Aug 20 16:56:08 zeta7 volumio[1529]: info: Loading plugin "websocket"... Aug 20 16:56:08 zeta7 volumio[1529]: info: Starting Socket.io Server version 2.3.0 Aug 20 16:56:08 zeta7 volumio[1529]: info: Loading plugin "rotaryencoder2"... Aug 20 16:56:08 zeta7 volumio[1529]: Forking 3 albumart workers Aug 20 16:56:09 zeta7 volumio[1529]: info: Loading i18n strings for locale en Aug 20 16:56:09 zeta7 volumio[1529]: Updating browse sources language Aug 20 16:56:09 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::initPlayerControls Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: Express server listening on port 3000 Aug 20 16:56:10 zeta7 volumio[1529]: [Metrics] WebUI: 9s 821.33ms Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreStateMachine::resetVolumioState Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreStateMachine::getcurrentVolume Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 16:56:10 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:10 zeta7 volumio[1529]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 20 16:56:10 zeta7 volumio[1529]: wlan0 Interface doesn't support scanning. Aug 20 16:56:10 zeta7 volumio[1529]: info: Cannot use regular scanning, forcing with ap-force Aug 20 16:56:10 zeta7 sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 20 16:56:10 zeta7 sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:10 zeta7 sudo[1609]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:10 zeta7 volumio[1529]: command failed: No such device (-19) Aug 20 16:56:10 zeta7 volumio[1529]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Aug 20 16:56:10 zeta7 volumio[1529]: command failed: No such device (-19) Aug 20 16:56:10 zeta7 volumio[1529]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 20 16:56:10 zeta7 volumio[1529]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 20 16:56:11 zeta7 volumio[1529]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Aug 20 16:56:11 zeta7 volumio[1529]: Starting albumart workers Aug 20 16:56:11 zeta7 volumio[1529]: Starting albumart workers Aug 20 16:56:11 zeta7 volumio[1529]: info: Reloading queue from file Aug 20 16:56:11 zeta7 volumio[1529]: Starting albumart workers Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::setRepeat false single undefined Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:11 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::setRandom false Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:11 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:11 zeta7 volumio[1529]: info: Setting Device type: Raspberry PI Aug 20 16:56:11 zeta7 volumio[1529]: info: VolumeController:: Volume=90 Mute =false Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:11 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreStateMachine::updateTrackBlock Aug 20 16:56:11 zeta7 volumio[1529]: info: CorePlayQueue::getTrackBlock Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 16:56:11 zeta7 volumio[1529]: info: Discovery: adding 5f154740-89df-4a9a-ba11-c8440f5cc7be Aug 20 16:56:11 zeta7 volumio[1529]: info: Discovery: Found device Zeta7 Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetState Aug 20 16:56:11 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:11 zeta7 volumio[1529]: info: Completed loading Core Plugins Aug 20 16:56:11 zeta7 volumio[1529]: info: Preparing to generate the ALSA configuration file Aug 20 16:56:11 zeta7 volumio[1529]: info: Asound.conf file unchanged, so no further update is needed Aug 20 16:56:11 zeta7 volumio[1529]: info: Output device has changed, restarting MPD Aug 20 16:56:11 zeta7 volumio[1529]: info: Output device has changed, restarting Shairport Sync Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:11 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:11 zeta7 sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 16:56:11 zeta7 sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:11 zeta7 sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 16:56:11 zeta7 sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:11 zeta7 sudo[1622]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:11 zeta7 systemd[1]: Stopping Music Player Daemon... Aug 20 16:56:11 zeta7 volumio[1529]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 16:56:11 zeta7 volumio[1529]: info: ___________ START PLUGINS ___________ Aug 20 16:56:11 zeta7 volumio[1529]: info: ControllerMpd::onStart: Initializing MPD Aug 20 16:56:11 zeta7 volumio[1529]: info: Creating MPD Configuration file Aug 20 16:56:11 zeta7 systemd[1]: mpd.service: Succeeded. Aug 20 16:56:11 zeta7 systemd[1]: Stopped Music Player Daemon. Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 16:56:12 zeta7 volumio[1529]: info: [1724187372043] CoreMusicLibrary::Adding element Media Servers Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 16:56:12 zeta7 systemd[1]: Starting Music Player Daemon... Aug 20 16:56:12 zeta7 sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 16:56:12 zeta7 sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:12 zeta7 sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 16:56:12 zeta7 sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:12 zeta7 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 20 16:56:12 zeta7 systemd[1]: mpd.service: Succeeded. Aug 20 16:56:12 zeta7 systemd[1]: Stopped Music Player Daemon. Aug 20 16:56:12 zeta7 sudo[1631]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:12 zeta7 systemd[1]: Starting Music Player Daemon... Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:12 zeta7 volumio[1529]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:12 zeta7 sudo[1637]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 20 16:56:12 zeta7 sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:12 zeta7 sudo[1637]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:12 zeta7 volumio[1529]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 16:56:12 zeta7 volumio[1529]: info: [1724187372560] CoreMusicLibrary::Adding element Last_100 Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 20 16:56:12 zeta7 volumio[1529]: info: [1724187372570] CoreMusicLibrary::Adding element Webradio Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 16:56:12 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 16:56:12 zeta7 volumio[1529]: info: Initializing BBC Radios Aug 20 16:56:13 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 16:56:13 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:13 zeta7 volumio[1529]: info: Volumio Calling Home Aug 20 16:56:13 zeta7 sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 Aug 20 16:56:13 zeta7 sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:13 zeta7 sudo[1684]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:13 zeta7 volumio[1529]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '1_rotary-encoder' (kernel) Aug 20 16:56:13 zeta7 volumio[1529]: info: MPD Permissions set Aug 20 16:56:13 zeta7 volumio[1529]: info: MPD Permissions set Aug 20 16:56:13 zeta7 volumio[1529]: info: VolumeController:: Volume=90 Mute =false Aug 20 16:56:13 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:13 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:13 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:13 zeta7 volumio[1529]: info: Volumio called home Aug 20 16:56:13 zeta7 volumio[1529]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 20 16:56:13 zeta7 volumio[1529]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:14 zeta7 volumio[1529]: info: Starting Shairport Sync Aug 20 16:56:14 zeta7 volumio[1529]: info: Starting Shairport Sync Aug 20 16:56:14 zeta7 volumio[1529]: info: Starting Shairport Sync Aug 20 16:56:14 zeta7 sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 16:56:14 zeta7 sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:14 zeta7 sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 16:56:14 zeta7 sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:14 zeta7 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 16:56:14 zeta7 systemd[1]: shairport-sync.service: Succeeded. Aug 20 16:56:14 zeta7 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetState Aug 20 16:56:14 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:14 zeta7 sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 16:56:14 zeta7 sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:14 zeta7 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 16:56:14 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetState Aug 20 16:56:14 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:14 zeta7 sudo[1701]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:14 zeta7 volumio[1529]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 20 16:56:14 zeta7 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 16:56:15 zeta7 volumio-remote-updater[472]: [2024-08-20 16:56:15] [connect] Successful connection Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetState Aug 20 16:56:15 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:15 zeta7 volumio[1529]: info: Shairport-Sync Started Aug 20 16:56:15 zeta7 volumio[1529]: Error adding Membership: Error: addMembership EINVAL Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 20 16:56:15 zeta7 volumio[1529]: info: Listing playlists Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetQueue Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreStateMachine::getQueue Aug 20 16:56:15 zeta7 volumio[1529]: info: CorePlayQueue::getQueue Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetVisibleSources Aug 20 16:56:15 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 20 16:56:15 zeta7 volumio-remote-updater[472]: [2024-08-20 16:56:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724187375 101 Aug 20 16:56:15 zeta7 volumio[1529]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Aug 20 16:56:15 zeta7 sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 16:56:15 zeta7 sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:15 zeta7 sudo[1712]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:15 zeta7 sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 16:56:15 zeta7 sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:15 zeta7 mpd[1645]: Aug 20 16:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 20 16:56:15 zeta7 sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 20 16:56:15 zeta7 sudo[1714]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:15 zeta7 sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:15 zeta7 sudo[1718]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:15 zeta7 volumio[1529]: info: Upmpdcli Daemon Started Aug 20 16:56:15 zeta7 systemd[1]: Started Music Player Daemon. Aug 20 16:56:15 zeta7 sudo[1624]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:15 zeta7 sudo[1634]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:16 zeta7 volumio[1529]: info: Completed starting Core Plugins Aug 20 16:56:16 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:16 zeta7 volumio[1529]: info: ----- MyVolumio plugins startup ---- Aug 20 16:56:16 zeta7 volumio[1529]: info: ------------------------------------------- Aug 20 16:56:16 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 20 16:56:16 zeta7 volumio[1529]: error: MPD error: The expression evaluated to a falsy value: Aug 20 16:56:16 zeta7 volumio[1529]: assert.ok(self.idling) Aug 20 16:56:16 zeta7 volumio[1529]: error: The expression evaluated to a falsy value: Aug 20 16:56:16 zeta7 volumio[1529]: assert.ok(self.idling) Aug 20 16:56:16 zeta7 volumio[1529]: info: MPD running with PID1645 Aug 20 16:56:16 zeta7 volumio[1529]: ,establishing connection Aug 20 16:56:16 zeta7 volumio[1529]: error: updateQueue error: null Aug 20 16:56:16 zeta7 volumio[1529]: error: updateQueue error: null Aug 20 16:56:21 zeta7 volumio[1529]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPlay Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::play index undefined Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::startPlaybackTimer Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/MYMP31/Hacker (Orbital).mp3 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand stop took 6 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand clear took 7 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/MYMP31/Hacker (Orbital).mp3" Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: error: updateQueue error: null Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 25ms Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand add "INTERNAL/MYMP31/Hacker (Orbital).mp3" took 19 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 17ms Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 14ms Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand play Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces system playlist update Aug 20 16:56:23 zeta7 volumio[1529]: info: Ignoring MPD Status Update Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 27ms Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand play took 18 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 19ms Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 15ms Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 30 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 26 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 21 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: Aug 20 16:56:23 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 32 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 26 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 23 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 16 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand status took 12 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus stop Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus play Aug 20 16:56:23 zeta7 volumio[1529]: info: Received an update from plugin. extracting info from payload Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":434,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus play Aug 20 16:56:23 zeta7 volumio[1529]: info: Received an update from plugin. extracting info from payload Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 165ms Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 200ms Aug 20 16:56:23 zeta7 volumio[1529]: info: ------------------------------ 198ms Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 146 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 139 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 138 milliseconds Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus play Aug 20 16:56:23 zeta7 volumio[1529]: info: Received an update from plugin. extracting info from payload Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus play Aug 20 16:56:23 zeta7 volumio[1529]: info: Received an update from plugin. extracting info from payload Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:56:23 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService play Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus play Aug 20 16:56:23 zeta7 volumio[1529]: info: Received an update from plugin. extracting info from payload Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:56:23 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:23 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:56:24 zeta7 volumio[1529]: info: ------------------------------ 319ms Aug 20 16:56:24 zeta7 volumio[1529]: info: ------------------------------ 297ms Aug 20 16:56:24 zeta7 volumio[1529]: info: ------------------------------ 300ms Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin bluetooth to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin multiroom to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin metavolumio to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin cd_controller to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 20 16:56:24 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 20 16:56:26 zeta7 volumio[1529]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 20 16:56:26 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 20 16:56:26 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:26 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:26 zeta7 volumio[1529]: info: Starting MyVolumio Remote Streaming Endpoints Aug 20 16:56:26 zeta7 volumio[1529]: info: MyVolumio login type: Token Aug 20 16:56:26 zeta7 volumio[1529]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 20 16:56:26 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 20 16:56:28 zeta7 volumio[1529]: info: Starting Streaming Service Transparent Proxy Aug 20 16:56:28 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 20 16:56:28 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 20 16:56:28 zeta7 volumio[1529]: info: Streaming services startup Aug 20 16:56:28 zeta7 volumio[1529]: info: Starting Streaming Daemon Aug 20 16:56:28 zeta7 sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 20 16:56:28 zeta7 sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:28 zeta7 volumio[1529]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 20 16:56:28 zeta7 sudo[1736]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:28 zeta7 volumio[1529]: error: Cannot start Volumio Streaming Daemon Aug 20 16:56:28 zeta7 volumio[1529]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 20 16:56:28 zeta7 volumio[1529]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 20 16:56:29 zeta7 volumio[1529]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 20 16:56:29 zeta7 volumio[1529]: info: MyVolumio token set successfully Aug 20 16:56:29 zeta7 volumio[1529]: info: MYVOLUMIO: Adding device Aug 20 16:56:29 zeta7 volumio[1529]: info: MYVOLUMIO: Evaluating Server Aug 20 16:56:30 zeta7 volumio[1529]: info: MyVolumio status changed Aug 20 16:56:30 zeta7 volumio[1529]: info: Streaming services startup Aug 20 16:56:30 zeta7 volumio[1529]: info: Starting Streaming Daemon Aug 20 16:56:30 zeta7 volumio[1529]: info: Removing browser output: myVolumio user plan is not superstar Aug 20 16:56:30 zeta7 volumio[1529]: info: Removing audio output: Aug 20 16:56:30 zeta7 volumio[1529]: info: Stoppping Tunnel 1 Aug 20 16:56:30 zeta7 sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 20 16:56:30 zeta7 sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:30 zeta7 sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 20 16:56:30 zeta7 sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:56:30 zeta7 sudo[1762]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:30 zeta7 volumio[1529]: error: Cannot start Volumio Streaming Daemon Aug 20 16:56:30 zeta7 volumio[1529]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 20 16:56:30 zeta7 volumio[1529]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 20 16:56:30 zeta7 sudo[1764]: pam_unix(sudo:session): session closed for user root Aug 20 16:56:30 zeta7 volumio[1529]: info: Setting Geolocation for MyVolumio to us1 Aug 20 16:56:30 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:30 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:30 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:30 zeta7 volumio[1529]: info: Remote SSH Stopped Aug 20 16:56:30 zeta7 volumio[1529]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 20 16:56:31 zeta7 volumio[1529]: STREAMING PROXY: Starting server on port 3245 Aug 20 16:56:31 zeta7 volumio[1529]: Node JS runtime: 14 Aug 20 16:56:31 zeta7 volumio[1529]: info: Updating MyVolumio device info Aug 20 16:56:31 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:31 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:31 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:31 zeta7 volumio[1529]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 20 16:56:34 zeta7 volumio[1529]: info: MYVOLUMIO: Adding device Aug 20 16:56:34 zeta7 volumio[1529]: info: MYVOLUMIO: Evaluating Server Aug 20 16:56:35 zeta7 volumio[1529]: info: Setting Geolocation for MyVolumio to us1 Aug 20 16:56:35 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:35 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:35 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:35 zeta7 volumio[1529]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 20 16:56:36 zeta7 volumio[1529]: info: Updating MyVolumio device info Aug 20 16:56:36 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:36 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:36 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:36 zeta7 volumio[1529]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 20 16:56:41 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 20 16:56:41 zeta7 volumio[1529]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 20 16:56:41 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetState Aug 20 16:56:41 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:56:43 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 16:56:43 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:43 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 20 16:56:43 zeta7 volumio-remote-updater[472]: No test mode Aug 20 16:56:43 zeta7 volumio-remote-updater[472]: No alpha test mode Aug 20 16:56:43 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 20 16:56:43 zeta7 volumio[1529]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 20 16:56:43 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::volumioGetBrowseSources Aug 20 16:56:46 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 20 16:56:47 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 20 16:56:47 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 20 16:56:48 zeta7 volumio[1529]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 20 16:56:48 zeta7 volumio[1529]: info: Completed starting MyVolumio Plugin Aug 20 16:56:48 zeta7 volumio[1529]: info: BOOT COMPLETED Aug 20 16:56:48 zeta7 volumio[1529]: [Metrics] CommandRouter: 47s 128.02ms Aug 20 16:56:48 zeta7 volumio[1529]: info: CoreCommandRouter::volumiosetStartupVolume Aug 20 16:56:48 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 16:56:48 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 16:56:48 zeta7 volumio[1529]: info: CoreCommandRouter::Close All Modals sent Aug 20 16:56:48 zeta7 volumio[1529]: info: CoreCommandRouter::Close All Modals sent Aug 20 16:56:49 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 20 16:56:49 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 16:56:49 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 20 16:56:50 zeta7 volumio[1529]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 16:57:13 zeta7 volumio[1529]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] Aug 20 16:57:13 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder Aug 20 16:57:13 zeta7 sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 20 16:57:13 zeta7 sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:57:13 zeta7 sudo[1830]: pam_unix(sudo:session): session closed for user root Aug 20 16:57:13 zeta7 sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Aug 20 16:57:13 zeta7 sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:57:13 zeta7 kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /rotary@5 Aug 20 16:57:13 zeta7 kernel: ------------[ cut here ]------------ Aug 20 16:57:13 zeta7 kernel: WARNING: CPU: 1 PID: 453 at lib/refcount.c:25 kobject_get+0xa0/0xa4 Aug 20 16:57:13 zeta7 kernel: refcount_t: addition on 0; use-after-free. Aug 20 16:57:13 zeta7 kernel: Modules linked in: rotary_encoder nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables 8021q garp stp llc nfnetlink sha256_generic cfg80211 rfkill snd_bcm2835(C) snd_pcm joydev snd_seq snd_seq_device snd_timer bcm2835_isp(C) bcm2835_codec(C) raspberrypi_hwmon bcm2835_v4l2(C) v4l2_mem2mem bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev snd i2c_bcm2835 mc vc_sm_cma(C) raspberrypi_gpiomem uio_pdrv_genirq fixed uio i2c_dev ip_tables x_tables ipv6 uas squashfs overlay nls_iso8859_1 fuse drm drm_panel_orientation_quirks backlight Aug 20 16:57:13 zeta7 kernel: CPU: 1 PID: 453 Comm: kworker/1:3 Tainted: G C 6.1.69-v7+ #1710 Aug 20 16:57:13 zeta7 kernel: Hardware name: BCM2835 Aug 20 16:57:13 zeta7 kernel: Workqueue: events_long device_link_release_fn Aug 20 16:57:13 zeta7 kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 20 16:57:13 zeta7 kernel: show_stack from dump_stack_lvl+0x68/0x8c Aug 20 16:57:13 zeta7 kernel: dump_stack_lvl from __warn+0x84/0x124 Aug 20 16:57:13 zeta7 kernel: __warn from warn_slowpath_fmt+0x11c/0x184 Aug 20 16:57:13 zeta7 kernel: warn_slowpath_fmt from kobject_get+0xa0/0xa4 Aug 20 16:57:13 zeta7 kernel: kobject_get from of_node_get+0x1c/0x24 Aug 20 16:57:13 zeta7 kernel: of_node_get from of_fwnode_get+0x3c/0x48 Aug 20 16:57:13 zeta7 kernel: of_fwnode_get from fwnode_full_name_string+0x34/0xa0 Aug 20 16:57:13 zeta7 kernel: fwnode_full_name_string from device_node_string+0x298/0x4f0 Aug 20 16:57:13 zeta7 kernel: device_node_string from pointer+0x3e8/0x5f0 Aug 20 16:57:13 zeta7 kernel: pointer from vsnprintf+0x114/0x3b0 Aug 20 16:57:13 zeta7 kernel: vsnprintf from vprintk_store+0x124/0x460 Aug 20 16:57:13 zeta7 kernel: vprintk_store from vprintk_emit+0x140/0x324 Aug 20 16:57:13 zeta7 kernel: vprintk_emit from vprintk_default+0x28/0x30 Aug 20 16:57:13 zeta7 kernel: vprintk_default from _printk+0x38/0x5c Aug 20 16:57:13 zeta7 kernel: _printk from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from platform_device_release+0x18/0x44 Aug 20 16:57:13 zeta7 kernel: platform_device_release from device_release+0x38/0xa8 Aug 20 16:57:13 zeta7 kernel: device_release from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from device_link_release_fn+0x4c/0xb0 Aug 20 16:57:13 zeta7 kernel: device_link_release_fn from process_one_work+0x244/0x508 Aug 20 16:57:13 zeta7 kernel: process_one_work from worker_thread+0x48/0x558 Aug 20 16:57:13 zeta7 kernel: worker_thread from kthread+0xe4/0x100 Aug 20 16:57:13 zeta7 kernel: kthread from ret_from_fork+0x14/0x30 Aug 20 16:57:13 zeta7 kernel: Exception stack(0xb89e5fb0 to 0xb89e5ff8) Aug 20 16:57:13 zeta7 kernel: 5fa0: 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Aug 20 16:57:13 zeta7 kernel: ---[ end trace 0000000000000000 ]--- Aug 20 16:57:13 zeta7 kernel: ------------[ cut here ]------------ Aug 20 16:57:13 zeta7 kernel: WARNING: CPU: 1 PID: 453 at lib/refcount.c:28 fwnode_full_name_string+0x8c/0xa0 Aug 20 16:57:13 zeta7 kernel: refcount_t: underflow; use-after-free. Aug 20 16:57:13 zeta7 kernel: Modules linked in: rotary_encoder nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables 8021q garp stp llc nfnetlink sha256_generic cfg80211 rfkill snd_bcm2835(C) snd_pcm joydev snd_seq snd_seq_device snd_timer bcm2835_isp(C) bcm2835_codec(C) raspberrypi_hwmon bcm2835_v4l2(C) v4l2_mem2mem bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev snd i2c_bcm2835 mc vc_sm_cma(C) raspberrypi_gpiomem uio_pdrv_genirq fixed uio i2c_dev ip_tables x_tables ipv6 uas squashfs overlay nls_iso8859_1 fuse drm drm_panel_orientation_quirks backlight Aug 20 16:57:13 zeta7 kernel: CPU: 1 PID: 453 Comm: kworker/1:3 Tainted: G WC 6.1.69-v7+ #1710 Aug 20 16:57:13 zeta7 kernel: Hardware name: BCM2835 Aug 20 16:57:13 zeta7 kernel: Workqueue: events_long device_link_release_fn Aug 20 16:57:13 zeta7 kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 20 16:57:13 zeta7 kernel: show_stack from dump_stack_lvl+0x68/0x8c Aug 20 16:57:13 zeta7 kernel: dump_stack_lvl from __warn+0x84/0x124 Aug 20 16:57:13 zeta7 kernel: __warn from warn_slowpath_fmt+0x11c/0x184 Aug 20 16:57:13 zeta7 kernel: warn_slowpath_fmt from fwnode_full_name_string+0x8c/0xa0 Aug 20 16:57:13 zeta7 kernel: fwnode_full_name_string from device_node_string+0x298/0x4f0 Aug 20 16:57:13 zeta7 kernel: device_node_string from pointer+0x3e8/0x5f0 Aug 20 16:57:13 zeta7 kernel: pointer from vsnprintf+0x114/0x3b0 Aug 20 16:57:13 zeta7 kernel: vsnprintf from vprintk_store+0x124/0x460 Aug 20 16:57:13 zeta7 kernel: vprintk_store from vprintk_emit+0x140/0x324 Aug 20 16:57:13 zeta7 kernel: vprintk_emit from vprintk_default+0x28/0x30 Aug 20 16:57:13 zeta7 kernel: vprintk_default from _printk+0x38/0x5c Aug 20 16:57:13 zeta7 kernel: _printk from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from platform_device_release+0x18/0x44 Aug 20 16:57:13 zeta7 kernel: platform_device_release from device_release+0x38/0xa8 Aug 20 16:57:13 zeta7 kernel: device_release from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from device_link_release_fn+0x4c/0xb0 Aug 20 16:57:13 zeta7 kernel: device_link_release_fn from process_one_work+0x244/0x508 Aug 20 16:57:13 zeta7 kernel: process_one_work from worker_thread+0x48/0x558 Aug 20 16:57:13 zeta7 kernel: worker_thread from kthread+0xe4/0x100 Aug 20 16:57:13 zeta7 kernel: kthread from ret_from_fork+0x14/0x30 Aug 20 16:57:13 zeta7 kernel: Exception stack(0xb89e5fb0 to 0xb89e5ff8) Aug 20 16:57:13 zeta7 kernel: 5fa0: 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Aug 20 16:57:13 zeta7 kernel: ---[ end trace 0000000000000000 ]--- Aug 20 16:57:13 zeta7 kernel: OF: ERROR: memory leak before free overlay changeset, /rotary@5 Aug 20 16:57:13 zeta7 kernel: ------------[ cut here ]------------ Aug 20 16:57:13 zeta7 kernel: WARNING: CPU: 1 PID: 453 at lib/refcount.c:22 kobject_get+0x8c/0xa4 Aug 20 16:57:13 zeta7 kernel: refcount_t: saturated; leaking memory. Aug 20 16:57:13 zeta7 kernel: Modules linked in: rotary_encoder nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables 8021q garp stp llc nfnetlink sha256_generic cfg80211 rfkill snd_bcm2835(C) snd_pcm joydev snd_seq snd_seq_device snd_timer bcm2835_isp(C) bcm2835_codec(C) raspberrypi_hwmon bcm2835_v4l2(C) v4l2_mem2mem bcm2835_mmal_vchiq(C) videobuf2_dma_contig videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_common videodev snd i2c_bcm2835 mc vc_sm_cma(C) raspberrypi_gpiomem uio_pdrv_genirq fixed uio i2c_dev ip_tables x_tables ipv6 uas squashfs overlay nls_iso8859_1 fuse drm drm_panel_orientation_quirks backlight Aug 20 16:57:13 zeta7 kernel: CPU: 1 PID: 453 Comm: kworker/1:3 Tainted: G WC 6.1.69-v7+ #1710 Aug 20 16:57:13 zeta7 kernel: Hardware name: BCM2835 Aug 20 16:57:13 zeta7 kernel: Workqueue: events_long device_link_release_fn Aug 20 16:57:13 zeta7 kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 20 16:57:13 zeta7 kernel: show_stack from dump_stack_lvl+0x68/0x8c Aug 20 16:57:13 zeta7 kernel: dump_stack_lvl from __warn+0x84/0x124 Aug 20 16:57:13 zeta7 kernel: __warn from warn_slowpath_fmt+0x11c/0x184 Aug 20 16:57:13 zeta7 kernel: warn_slowpath_fmt from kobject_get+0x8c/0xa4 Aug 20 16:57:13 zeta7 kernel: kobject_get from of_node_get+0x1c/0x24 Aug 20 16:57:13 zeta7 kernel: of_node_get from of_fwnode_get+0x3c/0x48 Aug 20 16:57:13 zeta7 kernel: of_fwnode_get from fwnode_full_name_string+0x34/0xa0 Aug 20 16:57:13 zeta7 kernel: fwnode_full_name_string from device_node_string+0x298/0x4f0 Aug 20 16:57:13 zeta7 kernel: device_node_string from pointer+0x3e8/0x5f0 Aug 20 16:57:13 zeta7 kernel: pointer from vsnprintf+0x114/0x3b0 Aug 20 16:57:13 zeta7 kernel: vsnprintf from vscnprintf+0x14/0x28 Aug 20 16:57:13 zeta7 kernel: vscnprintf from printk_sprint+0x24/0x164 Aug 20 16:57:13 zeta7 kernel: printk_sprint from vprintk_store+0x3c4/0x460 Aug 20 16:57:13 zeta7 kernel: vprintk_store from vprintk_emit+0x140/0x324 Aug 20 16:57:13 zeta7 kernel: vprintk_emit from vprintk_default+0x28/0x30 Aug 20 16:57:13 zeta7 kernel: vprintk_default from _printk+0x38/0x5c Aug 20 16:57:13 zeta7 kernel: _printk from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from platform_device_release+0x18/0x44 Aug 20 16:57:13 zeta7 kernel: platform_device_release from device_release+0x38/0xa8 Aug 20 16:57:13 zeta7 kernel: device_release from kobject_put+0x98/0x100 Aug 20 16:57:13 zeta7 kernel: kobject_put from device_link_release_fn+0x4c/0xb0 Aug 20 16:57:13 zeta7 kernel: device_link_release_fn from process_one_work+0x244/0x508 Aug 20 16:57:13 zeta7 kernel: process_one_work from worker_thread+0x48/0x558 Aug 20 16:57:13 zeta7 kernel: worker_thread from kthread+0xe4/0x100 Aug 20 16:57:13 zeta7 kernel: kthread from ret_from_fork+0x14/0x30 Aug 20 16:57:13 zeta7 kernel: Exception stack(0xb89e5fb0 to 0xb89e5ff8) Aug 20 16:57:13 zeta7 kernel: 5fa0: 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Aug 20 16:57:13 zeta7 kernel: 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Aug 20 16:57:13 zeta7 kernel: ---[ end trace 0000000000000000 ]--- Aug 20 16:57:13 zeta7 sudo[1833]: pam_unix(sudo:session): session closed for user root Aug 20 16:57:13 zeta7 sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 20 16:57:13 zeta7 sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:57:13 zeta7 sudo[1845]: pam_unix(sudo:session): session closed for user root Aug 20 16:57:13 zeta7 sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 Aug 20 16:57:13 zeta7 sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 16:57:13 zeta7 kernel: rotary-encoder rotary@5: gray Aug 20 16:57:13 zeta7 kernel: input: rotary@5 as /devices/platform/rotary@5/input/input6 Aug 20 16:57:13 zeta7 sudo[1848]: pam_unix(sudo:session): session closed for user root Aug 20 16:57:13 zeta7 systemd-udevd[1841]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::stPlaybackTimer Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::servicePause Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::servicePause Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::pause Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand pause Aug 20 16:57:24 zeta7 volumio[1529]: info: Aug 20 16:57:24 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand pause took 7 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:57:24 zeta7 volumio[1529]: info: Aug 20 16:57:24 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:57:24 zeta7 volumio[1529]: info: Aug 20 16:57:24 zeta7 volumio[1529]: ---------------------------- MPD announces state update: player Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::getState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand status took 13 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand status took 14 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand status took 11 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseState Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:57:24 zeta7 volumio[1529]: verbose: ControllerMpd::parseTrackInfo Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61851,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:57:24 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::stPlaybackTimer Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61851,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:57:24 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::stPlaybackTimer Aug 20 16:57:24 zeta7 volumio[1529]: info: ControllerMpd::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::servicePushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61851,"duration":569,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Halcyon & On & On - Orbital","artist":"Hackers Soundtrack","album":"Hackers Soundtrack","uri":"INTERNAL/MYMP31/Hacker (Orbital).mp3","trackType":"mp3"} Aug 20 16:57:24 zeta7 volumio[1529]: verbose: CURRENT POSITION 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState stateService pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::syncState currentStatus pause Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::pushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CorePlayQueue::getTrack 0 Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreCommandRouter::volumioPushState Aug 20 16:57:24 zeta7 volumio[1529]: info: CoreStateMachine::stPlaybackTimer Aug 20 16:57:24 zeta7 volumio[1529]: info: ------------------------------ 124ms Aug 20 16:57:24 zeta7 volumio[1529]: info: ------------------------------ 120ms Aug 20 16:57:24 zeta7 volumio[1529]: info: ------------------------------ 118ms Aug 20 16:57:27 zeta7 volumio[1529]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 16:57:27 zeta7 volumio[1529]: SyntaxError: Unexpected token + in JSON at position 0 Aug 20 16:57:27 zeta7 volumio[1529]: at JSON.parse () Aug 20 16:57:27 zeta7 volumio[1529]: at rotaryencoder2.emitDialCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:691:72) Aug 20 16:57:27 zeta7 volumio[1529]: at Socket. (/data/plugins/system_hardware/rotaryencoder2/index.js:645:10) Aug 20 16:57:27 zeta7 volumio[1529]: at Socket.emit (events.js:315:20) Aug 20 16:57:27 zeta7 volumio[1529]: at addChunk (internal/streams/readable.js:309:12) Aug 20 16:57:27 zeta7 volumio[1529]: at readableAddChunk (internal/streams/readable.js:284:9) Aug 20 16:57:27 zeta7 volumio[1529]: at Socket.Readable.push (internal/streams/readable.js:223:10) Aug 20 16:57:27 zeta7 volumio[1529]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 20 16:57:27 zeta7 volumio[1529]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 16:57:28 zeta7 sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 16:56 Aug 20 16:57:28 zeta7 sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"