-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Sat 2025-09-06 04:37:32 BST. -- Sep 06 04:36:08 volumio70 go-librespot[1413]: time="2025-09-06T04:36:08+01:00" level=trace msg="sent dealer ping" Sep 06 04:36:08 volumio70 go-librespot[1413]: time="2025-09-06T04:36:08+01:00" level=trace msg="received dealer pong" Sep 06 04:36:20 volumio70 sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 04:36:20 volumio70 sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:20 volumio70 sudo[3048]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:20 volumio70 sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 04:36:20 volumio70 sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:20 volumio70 sudo[3051]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:20 volumio70 volumio[1165]: verbose: New Socket.io Connection to 192.168.0.79 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Sep 06 04:36:20 volumio70 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Sep 06 04:36:20 volumio70 volumio[1165]: info: Received Get System Info Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 04:36:20 volumio70 volumio[1165]: info: Discovery: Getting this device information Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:20 volumio70 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 04:36:20 volumio70 volumio-remote-updater[675]: No test mode Sep 06 04:36:20 volumio70 volumio-remote-updater[675]: No alpha test mode Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 04:36:20 volumio70 volumio[1165]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:20 volumio70 volumio[1165]: info: Retrieving Cloud Streaming UI Sep 06 04:36:20 volumio70 volumio[1165]: info: Getting Tidal Cloud Configuration Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:20 volumio70 volumio[1165]: info: Getting Qobuz Cloud Configuration Sep 06 04:36:20 volumio70 volumio[1165]: info: Asking plugin for UI Config Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:20 volumio70 volumio[1165]: info: Getting Spotify Cloud Configuration Sep 06 04:36:20 volumio70 volumio[1165]: info: Asking plugin for UI Config Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:20 volumio70 volumio[1165]: info: Saving Spotify Acccount Sep 06 04:36:20 volumio70 volumio[1165]: info: Got it Sep 06 04:36:20 volumio70 volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 04:36:20 volumio70 volumio[1165]: info: Got Tidal Cloud Configuration Sep 06 04:36:20 volumio70 volumio[1165]: info: Got it Sep 06 04:36:20 volumio70 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:22 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 04:36:22 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 06 04:36:22 volumio70 volumio[1165]: info: Loading i18n strings for locale en Sep 06 04:36:22 volumio70 volumio[1165]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Sep 06 04:36:22 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 06 04:36:22 volumio70 volumio[1165]: info: Setting timezone to Europe/London Sep 06 04:36:22 volumio70 sudo[3072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 06 04:36:22 volumio70 sudo[3072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:22 volumio70 sudo[3072]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:22 volumio70 sudo[3075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 06 04:36:22 volumio70 sudo[3075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:22 volumio70 sudo[3075]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:22 volumio70 sudo[3078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 06 04:36:22 volumio70 sudo[3078]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:22 volumio70 sudo[3078]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:22 volumio70 sudo[3081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 06 04:36:22 volumio70 sudo[3081]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:22 volumio70 dbus-daemon[668]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.53' (uid=0 pid=3082 comm="/usr/bin/timedatectl set-timezone Europe/London ") Sep 06 04:36:22 volumio70 systemd[1]: Starting Time & Date Service... Sep 06 04:36:22 volumio70 dbus-daemon[668]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 06 04:36:22 volumio70 systemd[1]: Started Time & Date Service. Sep 06 04:36:22 volumio70 sudo[3081]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:22 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 06 04:36:23 volumio70 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:23 volumio70 volumio[1165]: Updating browse sources language Sep 06 04:36:23 volumio70 volumio[1165]: Cannot find translation for source 80s80s Radio Sep 06 04:36:23 volumio70 volumio[1165]: Cannot find translation for source Spotify Sep 06 04:36:23 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 04:36:23 volumio70 volumio[1165]: Cannot find translation for source 80s80s Radio Sep 06 04:36:23 volumio70 volumio[1165]: Cannot find translation for source Spotify Sep 06 04:36:23 volumio70 volumio[1165]: info: Fetching Streaming Services browse cache Sep 06 04:36:26 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 04:36:28 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Sep 06 04:36:28 volumio70 volumio[1165]: info: Loading i18n strings for locale en Sep 06 04:36:28 volumio70 volumio[1165]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Sep 06 04:36:28 volumio70 volumio[1165]: Updating browse sources language Sep 06 04:36:28 volumio70 volumio[1165]: Cannot find translation for source 80s80s Radio Sep 06 04:36:28 volumio70 volumio[1165]: Cannot find translation for source Spotify Sep 06 04:36:28 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 04:36:28 volumio70 volumio[1165]: Cannot find translation for source 80s80s Radio Sep 06 04:36:28 volumio70 volumio[1165]: Cannot find translation for source Spotify Sep 06 04:36:28 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Sep 06 04:36:28 volumio70 volumio[1165]: info: Setting timezone to Europe/London Sep 06 04:36:28 volumio70 sudo[3085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Sep 06 04:36:28 volumio70 sudo[3085]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3085]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 sudo[3088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime Sep 06 04:36:28 volumio70 sudo[3088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3088]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 sudo[3091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Sep 06 04:36:28 volumio70 sudo[3091]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3091]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 sudo[3094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London Sep 06 04:36:28 volumio70 sudo[3094]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3094]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 06 04:36:28 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 06 04:36:28 volumio70 sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 06 04:36:28 volumio70 sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3097]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 volumio[1165]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 06 04:36:28 volumio70 volumio[1165]: wlan0 Interface doesn't support scanning : Network is down Sep 06 04:36:28 volumio70 volumio[1165]: info: Cannot use regular scanning, forcing with ap-force Sep 06 04:36:28 volumio70 sudo[3100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 06 04:36:28 volumio70 sudo[3100]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:28 volumio70 sudo[3100]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:28 volumio70 volumio[1165]: command failed: Network is down (-100) Sep 06 04:36:28 volumio70 volumio[1165]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 06 04:36:28 volumio70 volumio[1165]: command failed: Network is down (-100) Sep 06 04:36:28 volumio70 volumio[1165]: info: Fetching Streaming Services browse cache Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 04:36:38 volumio70 volumio[1165]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 04:36:38 volumio70 volumio[1165]: info: Received Get System Version Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 04:36:38 volumio70 volumio[1165]: info: Received Get System Info Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 04:36:38 volumio70 volumio[1165]: info: Discovery: Getting this device information Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:38 volumio70 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:38 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 04:36:38 volumio70 go-librespot[1413]: time="2025-09-06T04:36:38+01:00" level=trace msg="sent dealer ping" Sep 06 04:36:38 volumio70 go-librespot[1413]: time="2025-09-06T04:36:38+01:00" level=trace msg="received dealer pong" Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Sep 06 04:36:42 volumio70 volumio[1165]: info: System name has changed, restarting Shairport Sync Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:42 volumio70 volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 04:36:42 volumio70 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:42 volumio70 volumio[1165]: info: Updating MyVolumio device info Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: info: QobuzConnect: setDeactiveState invoked Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:42 volumio70 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:42 volumio70 vtcs[2719]: [2025-09-06 04:36:42.067] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 06 04:36:42 volumio70 vtcs[2719]: [2025-09-06 04:36:42.067] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 06 04:36:42 volumio70 vtcs[2719]: [2025-09-06 04:36:42.067] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Sep 06 04:36:42 volumio70 sudo[3176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 06 04:36:42 volumio70 sudo[3176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 volumio[1165]: info: Discovery: Restarting Advertising due to device name change Sep 06 04:36:42 volumio70 sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 06 04:36:42 volumio70 sudo[3179]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 systemd[1]: Stopping Volumio Tidal Connect Service... Sep 06 04:36:42 volumio70 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 06 04:36:42 volumio70 systemd[1]: vtcs.service: Succeeded. Sep 06 04:36:42 volumio70 systemd[1]: Stopped Volumio Tidal Connect Service. Sep 06 04:36:42 volumio70 sudo[3176]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 sudo[3179]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 systemd[1]: upmpdcli.service: Succeeded. Sep 06 04:36:42 volumio70 sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio66 Sep 06 04:36:42 volumio70 sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 sudo[3182]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 06 04:36:42 volumio70 sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 sudo[3186]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 sudo[3193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 06 04:36:42 volumio70 sudo[3193]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Sep 06 04:36:42 volumio70 sudo[3193]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 06 04:36:42 volumio70 sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 04:36:42 volumio70 systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 06 04:36:42 volumio70 qobuz-connect[2635]: 20250906 04:36:42.271 [2635.2635] INFO SampleApp: Stopping Local configuration server Sep 06 04:36:42 volumio70 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:42 volumio70 volumio[1165]: info: Bluetooth name changed to Volumio66 Sep 06 04:36:42 volumio70 volumio[1165]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 06 04:36:42 volumio70 volumio[1165]: info: QobuzConnect: setDeactiveState invoked Sep 06 04:36:42 volumio70 qobuz-connect[2635]: 20250906 04:36:42.281 [2635.2635] INFO SampleApp: shat down connection on UNIX socket Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:42 volumio70 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:42 volumio70 systemd[1]: qobuz-connect.service: Succeeded. Sep 06 04:36:42 volumio70 systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 06 04:36:42 volumio70 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:42 volumio70 volumio[1165]: info: Starting Shairport Sync Sep 06 04:36:42 volumio70 sudo[3202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Sep 06 04:36:42 volumio70 sudo[3202]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 systemd[1]: Started Volumio Qobuz Connect Service. Sep 06 04:36:42 volumio70 volumio[1165]: info: Permissions for /etc/hosts set Sep 06 04:36:42 volumio70 volumio[1165]: info: Hostname now is volumio66 Sep 06 04:36:42 volumio70 sudo[3202]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 sudo[3205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 04:36:42 volumio70 sudo[3205]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio70 sudo[3197]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 04:36:42 volumio70 systemd[1]: shairport-sync.service: Succeeded. Sep 06 04:36:42 volumio70 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 04:36:42 volumio70 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 04:36:42 volumio70 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:42 volumio70 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:42 volumio70 sudo[3205]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio70 volumio[1165]: info: Executing endpoint qc_getconfig Sep 06 04:36:42 volumio70 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 06 04:36:42 volumio70 volumio[1165]: info: Shairport-Sync Started Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.431 [3200.3200] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 06 04:36:42 volumio70 volumio[1165]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 06 04:36:42 volumio70 volumio[1165]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.435 [3200.3200] INFO VolumeManager: [0x71ec78]: Setting new playback volume: 75 Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.435 [3200.3200] INFO VolumeManager: [0x71ec78]: Setting new mute state: 0 Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.435 [3200.3200] INFO QobuzConnect: [0x71f648]: Client initialized! Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.435 [3200.3200] INFO SampleApp: Starting Avahi advertising, name: Volumio66, service name: _qobuz-connect._tcp Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.443 [3200.3200] INFO LocalConfigManager: [0x71e558]: Starting Local Configuration server Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.443 [3200.3200] INFO SampleApp: Starting Local configuration server Sep 06 04:36:42 volumio70 qobuz-connect[3200]: 20250906 04:36:42.444 [3200.3200] INFO SampleApp: Connected to UNIX socket client 0x713f60 Sep 06 04:36:42 volumio70 sudo[3208]: volumio : unable to resolve host volumio70 Sep 06 04:36:42 volumio70 sudo[3208]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 06 04:36:42 volumio70 sudo[3208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio66 Sep 06 04:36:42 volumio70 sudo[3208]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio66 sudo[3208]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio66 volumio[1165]: info: New hostname set Sep 06 04:36:42 volumio66 sudo[3210]: volumio : unable to resolve host volumio70 Sep 06 04:36:42 volumio66 sudo[3210]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 06 04:36:42 volumio66 sudo[3210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Sep 06 04:36:42 volumio66 sudo[3210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:42 volumio66 sudo[3210]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:42 volumio66 volumio[1165]: info: Permissions for /etc/avahi/services/volumio.service Sep 06 04:36:42 volumio66 avahi-daemon[682]: Files changed, reloading. Sep 06 04:36:42 volumio66 avahi-daemon[682]: Service group file /services/volumio.service changed, reloading. Sep 06 04:36:42 volumio66 volumio[1165]: info: Avahi name changed to volumio66 Sep 06 04:36:42 volumio66 qobuz-connect[3200]: 20250906 04:36:42.582 [3200.3200] INFO SampleApp: Playback volume changed: 75 Sep 06 04:36:42 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:42 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:42 volumio66 volumio[1165]: info: Successfully Updated MyVolumio device Sep 06 04:36:43 volumio66 volumio[1165]: info: Discovery: A device disappeared from network Sep 06 04:36:43 volumio66 volumio[1165]: info: Discovery: Device volumio70 disappeared from network Sep 06 04:36:43 volumio66 avahi-daemon[682]: Service "Volumio66" (/services/volumio.service) successfully established. Sep 06 04:36:45 volumio66 volumio[1165]: info: TidalConnect service stoped! Sep 06 04:36:45 volumio66 volumio[1165]: info: TidalConnect service stoped! Sep 06 04:36:45 volumio66 volumio[1165]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 06 04:36:45 volumio66 volumio[1165]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 06 04:36:45 volumio66 sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 06 04:36:45 volumio66 sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:45 volumio66 systemd[1]: Started Volumio Tidal Connect Service. Sep 06 04:36:45 volumio66 sudo[3227]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:45 volumio66 volumio[1165]: info: Executing endpoint tc_getconfig Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 06 04:36:45 volumio66 vtcs[3229]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 06 04:36:45 volumio66 vtcs[3229]: STARTED TidalConnect services. Sep 06 04:36:45 volumio66 volumio[1165]: info: Executing endpoint tc_connect Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 06 04:36:45 volumio66 volumio[1165]: info: Connecting to TidalConnect Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:45 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:45 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:45 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:45 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Sep 06 04:36:45 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:36:45 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:45 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 06 04:36:45 volumio66 volumio[1165]: info: Preparing to save Alsa Options, stopping services first Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:45 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPause Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreStateMachine::pause Sep 06 04:36:45 volumio66 volumio[1165]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"Innomaker Amp"},"output_device":{"value":1,"label":"Innomaker Amp"},"disallowPush":true} Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:45 volumio66 volumio[1165]: info: Found match in i2s Card Database: setting mixer Master for card Innomaker Amp Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 06 04:36:45 volumio66 volumio[1165]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Master Max Vol: 30 Vol Curve; logarithmic Vol Steps: 1 Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 06 04:36:45 volumio66 volumio[1165]: info: Disabling external Volume Control Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:45 volumio66 volumio[1165]: info: Enable softmixer device for audio device undefined Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:45 volumio66 volumio[1165]: info: Retrieving Cloud Streaming UI Sep 06 04:36:45 volumio66 volumio[1165]: info: Software Volume ALSA configuration written Sep 06 04:36:45 volumio66 volumio[1165]: info: Preparing to generate the ALSA configuration file Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 06 04:36:45 volumio66 volumio[1165]: info: Getting Alsa Cards List without I2S DAC Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Sep 06 04:36:45 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:45 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:46 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 06 04:36:46 volumio66 volumio[1165]: info: Getting Tidal Cloud Configuration Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:46 volumio66 volumio[1165]: info: Getting Qobuz Cloud Configuration Sep 06 04:36:46 volumio66 volumio[1165]: info: Asking plugin for UI Config Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:46 volumio66 volumio[1165]: info: Getting Spotify Cloud Configuration Sep 06 04:36:46 volumio66 volumio[1165]: info: Asking plugin for UI Config Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:46 volumio66 volumio[1165]: info: Saving Spotify Acccount Sep 06 04:36:46 volumio66 volumio[1165]: info: Got it Sep 06 04:36:46 volumio66 volumio[1165]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 06 04:36:46 volumio66 volumio[1165]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 06 04:36:46 volumio66 volumio[1165]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 06 04:36:46 volumio66 volumio[1165]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 06 04:36:46 volumio66 volumio[1165]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Sep 06 04:36:46 volumio66 volumio[1165]: info: Reading ALSA contributions from plugins. Sep 06 04:36:46 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:46 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:46 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:46 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:46 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:46 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:46 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:46 volumio66 volumio[1165]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 06 04:36:46 volumio66 volumio[1165]: info: Got Tidal Cloud Configuration Sep 06 04:36:46 volumio66 volumio[1165]: info: Got it Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 06 04:36:46 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:46 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:46 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:46 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:46 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:46 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:46 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:46 volumio66 volumio[1165]: info: VolumeController:: Volume=undefined Mute =false Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:46 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:46 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:46 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:36:46 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:46 volumio66 volumio[1165]: info: Asound.conf file unchanged, so no further update is needed Sep 06 04:36:46 volumio66 volumio[1165]: info: Output device has changed, restarting MPD Sep 06 04:36:46 volumio66 sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 06 04:36:46 volumio66 sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 sudo[3265]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 volumio[1165]: info: Output device has changed, restarting Shairport Sync Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:46 volumio66 sudo[3268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 04:36:46 volumio66 sudo[3268]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 systemd[1]: musicservicesshield.service: Succeeded. Sep 06 04:36:46 volumio66 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 06 04:36:46 volumio66 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 06 04:36:46 volumio66 systemd[1]: Stopping Music Player Daemon... Sep 06 04:36:46 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:46 volumio66 volumio[1165]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 06 04:36:46 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:46 volumio66 systemd[1]: mpd.service: Succeeded. Sep 06 04:36:46 volumio66 systemd[1]: Stopped Music Player Daemon. Sep 06 04:36:46 volumio66 volumio[1165]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: QobuzConnect: setDeactiveState invoked Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:46 volumio66 vtcs[3229]: [2025-09-06 04:36:46.199] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 06 04:36:46 volumio66 vtcs[3229]: [2025-09-06 04:36:46.200] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 06 04:36:46 volumio66 sudo[3277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 06 04:36:46 volumio66 sudo[3277]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 systemd[1]: Starting Music Player Daemon... Sep 06 04:36:46 volumio66 sudo[3280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 06 04:36:46 volumio66 systemd[1]: Stopping Volumio Tidal Connect Service... Sep 06 04:36:46 volumio66 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 06 04:36:46 volumio66 systemd[1]: vtcs.service: Succeeded. Sep 06 04:36:46 volumio66 systemd[1]: Stopped Volumio Tidal Connect Service. Sep 06 04:36:46 volumio66 sudo[3280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 sudo[3277]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 sudo[3280]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 sudo[3273]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 04:36:46 volumio66 sudo[3273]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 sudo[3287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:46 volumio66 sudo[3287]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:46 volumio66 sudo[3273]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:46 volumio66 volumio[1165]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 06 04:36:46 volumio66 volumio[1165]: info: MPD Permissions set Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 sudo[3287]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 sudo[3293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 06 04:36:46 volumio66 sudo[3293]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:36:46 volumio66 volumio[1165]: info: Starting Shairport Sync Sep 06 04:36:46 volumio66 systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 06 04:36:46 volumio66 qobuz-connect[3200]: 20250906 04:36:46.411 [3200.3200] INFO SampleApp: Stopping Local configuration server Sep 06 04:36:46 volumio66 qobuz-connect[3200]: 20250906 04:36:46.421 [3200.3200] INFO SampleApp: shat down connection on UNIX socket Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:46 volumio66 volumio[1165]: info: mpdhttpout --- Volumio set on pause Sep 06 04:36:46 volumio66 volumio[1165]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Sep 06 04:36:46 volumio66 systemd[1]: qobuz-connect.service: Succeeded. Sep 06 04:36:46 volumio66 systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:46 volumio66 sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 06 04:36:46 volumio66 sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:46 volumio66 systemd[1]: Started Volumio Qobuz Connect Service. Sep 06 04:36:46 volumio66 volumio[1165]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 06 04:36:46 volumio66 volumio[1165]: info: QobuzConnect: setDeactiveState invoked Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:46 volumio66 sudo[3293]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 volumio[1165]: info: Executing endpoint qc_getconfig Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.537 [3300.3300] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 06 04:36:46 volumio66 volumio[1165]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 06 04:36:46 volumio66 volumio[1165]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 06 04:36:46 volumio66 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 06 04:36:46 volumio66 systemd[1]: shairport-sync.service: Succeeded. Sep 06 04:36:46 volumio66 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.542 [3300.3300] INFO VolumeManager: [0x2104c78]: Setting new playback volume: 75 Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.542 [3300.3300] INFO VolumeManager: [0x2104c78]: Setting new mute state: 0 Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.542 [3300.3300] INFO QobuzConnect: [0x2105648]: Client initialized! Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.542 [3300.3300] INFO SampleApp: Starting Avahi advertising, name: Volumio66, service name: _qobuz-connect._tcp Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:46 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:46 volumio66 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 06 04:36:46 volumio66 sudo[3299]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:46 volumio66 volumio[1165]: info: Shairport-Sync Started Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.566 [3300.3300] INFO LocalConfigManager: [0x2104558]: Starting Local Configuration server Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.566 [3300.3300] INFO SampleApp: Starting Local configuration server Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.567 [3300.3300] INFO SampleApp: Connected to UNIX socket client 0x20f9f60 Sep 06 04:36:46 volumio66 qobuz-connect[3300]: 20250906 04:36:46.687 [3300.3300] INFO SampleApp: Playback volume changed: 75 Sep 06 04:36:46 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:46 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:47 volumio66 mpd[3291]: Sep 06 04:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 04:36:47 volumio66 systemd[1]: Started Music Player Daemon. Sep 06 04:36:47 volumio66 sudo[3268]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:47 volumio66 volumio[1165]: error: updateQueue error: null Sep 06 04:36:47 volumio66 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 06 04:36:47 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:47 volumio66 volumio[1165]: info: Discovery: Started advertising with name: Volumio66 Sep 06 04:36:47 volumio66 volumio[3309]: cset: --> shielding system active with Sep 06 04:36:47 volumio66 volumio[3309]: cset: "system" cpuset of CPUSPEC(1-3) with 173 tasks running Sep 06 04:36:47 volumio66 volumio[3309]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 06 04:36:47 volumio66 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 06 04:36:48 volumio66 volumio[1165]: info: Discovery: adding 05c35d14-3742-4175-998b-afac97394f45 Sep 06 04:36:48 volumio66 volumio[1165]: info: Discovery: Found device Volumio66 Sep 06 04:36:48 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:48 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:48 volumio66 volumio[1165]: info: TidalConnect service started! Sep 06 04:36:48 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Sep 06 04:36:48 volumio66 sudo[3327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 06 04:36:48 volumio66 sudo[3327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:48 volumio66 systemd[1]: musicservicesshield.service: Succeeded. Sep 06 04:36:48 volumio66 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 06 04:36:48 volumio66 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 06 04:36:48 volumio66 systemd[1]: Stopping Music Player Daemon... Sep 06 04:36:48 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:48 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:48 volumio66 systemd[1]: mpd.service: Succeeded. Sep 06 04:36:48 volumio66 systemd[1]: Stopped Music Player Daemon. Sep 06 04:36:48 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:48 volumio66 systemd[1]: Starting Music Player Daemon... Sep 06 04:36:48 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:48 volumio66 sudo[3329]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 06 04:36:48 volumio66 sudo[3329]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:48 volumio66 sudo[3329]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:49 volumio66 volumio[1165]: info: TidalConnect service stoped! Sep 06 04:36:49 volumio66 volumio[1165]: info: TidalConnect service stoped! Sep 06 04:36:49 volumio66 mpd[3331]: Sep 06 04:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 06 04:36:49 volumio66 systemd[1]: Started Music Player Daemon. Sep 06 04:36:49 volumio66 sudo[3327]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:49 volumio66 volumio[1165]: error: updateQueue error: null Sep 06 04:36:49 volumio66 volumio[1165]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 06 04:36:49 volumio66 volumio[1165]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 06 04:36:49 volumio66 sudo[3344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 06 04:36:49 volumio66 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 06 04:36:49 volumio66 sudo[3344]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:49 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:49 volumio66 systemd[1]: Started Volumio Tidal Connect Service. Sep 06 04:36:49 volumio66 sudo[3344]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:49 volumio66 volumio[1165]: info: Executing endpoint tc_getconfig Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 06 04:36:49 volumio66 vtcs[3351]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 06 04:36:49 volumio66 vtcs[3351]: STARTED TidalConnect services. Sep 06 04:36:49 volumio66 volumio[1165]: info: Executing endpoint tc_connect Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 06 04:36:49 volumio66 volumio[1165]: info: Connecting to TidalConnect Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect Sep 06 04:36:49 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:36:49 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:49 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:49 volumio66 volumio[3338]: cset: --> shielding system active with Sep 06 04:36:49 volumio66 volumio[3338]: cset: "system" cpuset of CPUSPEC(1-3) with 184 tasks running Sep 06 04:36:49 volumio66 volumio[3338]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 06 04:36:49 volumio66 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::getcurrentVolume Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 04:36:49 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:49 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:49 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:49 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:49 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:49 volumio66 volumio[1165]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 06 04:36:49 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:49 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:49 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:49 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:49 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:49 volumio66 volumio[1165]: info: VolumeController:: Volume=undefined Mute =false Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::updateTrackBlock Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrackBlock Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioRetrievevolume Sep 06 04:36:49 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:36:49 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:49 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:49 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:49 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:49 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:49 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:49 volumio66 volumio[1165]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 06 04:36:49 volumio66 volumio[1165]: Unable to parse: Sep 06 04:36:49 volumio66 volumio[1165]: Simple mixer control 'Master',0 Sep 06 04:36:49 volumio66 volumio[1165]: Capabilities: volume volume-joined Sep 06 04:36:49 volumio66 volumio[1165]: Playback channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Capture channels: Mono Sep 06 04:36:49 volumio66 volumio[1165]: Limits: 0 - 248 Sep 06 04:36:49 volumio66 volumio[1165]: Mono: 74 [30%] Sep 06 04:36:49 volumio66 volumio[1165]: info: VolumeController:: Volume=undefined Mute =false Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:36:49 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:36:49 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:36:49 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:36:49 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:36:49 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Sep 06 04:36:52 volumio66 sudo[3398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Sep 06 04:36:52 volumio66 sudo[3398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 sudo[3398]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 volumio[1165]: info: smb.conf Permissions set Sep 06 04:36:52 volumio66 sudo[3401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Sep 06 04:36:52 volumio66 sudo[3401]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 sudo[3401]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 volumio[1165]: info: Internal perms successfully set Sep 06 04:36:52 volumio66 sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Sep 06 04:36:52 volumio66 sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 systemd[1]: Stopping Samba NMB Daemon... Sep 06 04:36:52 volumio66 nmbd[764]: [2025/09/06 04:36:52.171978, 0] ../source3/nmbd/nmbd.c:58(terminate) Sep 06 04:36:52 volumio66 nmbd[764]: Got SIGTERM: going down... Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:52 volumio66 systemd[1]: nmbd.service: Succeeded. Sep 06 04:36:52 volumio66 systemd[1]: Stopped Samba NMB Daemon. Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:52 volumio66 systemd[1]: Starting Samba NMB Daemon... Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:52 volumio66 sudo[3410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 04:36:52 volumio66 sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 sudo[3410]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 sudo[3413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 04:36:52 volumio66 sudo[3413]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 sudo[3413]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 nmbd[3408]: [2025/09/06 04:36:52.356359, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 06 04:36:52 volumio66 nmbd[3408]: started asyncdns process 3417 Sep 06 04:36:52 volumio66 nmbd[3408]: [2025/09/06 04:36:52.359487, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 06 04:36:52 volumio66 nmbd[3408]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 06 04:36:52 volumio66 systemd[1]: Started Samba NMB Daemon. Sep 06 04:36:52 volumio66 sudo[3416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 06 04:36:52 volumio66 sudo[3416]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 sudo[3404]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 sudo[3420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Sep 06 04:36:52 volumio66 sudo[3420]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:36:52 volumio66 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 06 04:36:52 volumio66 sudo[3416]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 systemd[1]: Stopping Samba SMB Daemon... Sep 06 04:36:52 volumio66 volumio[1165]: info: Upmpdcli Daemon Started Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:52 volumio66 systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Sep 06 04:36:52 volumio66 systemd[1]: smbd.service: Succeeded. Sep 06 04:36:52 volumio66 systemd[1]: Stopped Samba SMB Daemon. Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 06 04:36:52 volumio66 systemd[1]: Starting Samba SMB Daemon... Sep 06 04:36:52 volumio66 volumio[1165]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 04:36:52 volumio66 volumio[1165]: info: TidalConnect service started! Sep 06 04:36:52 volumio66 volumio[3422]: writing RSA key Sep 06 04:36:52 volumio66 winbindd[1090]: [2025/09/06 04:36:52.678622, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Sep 06 04:36:52 volumio66 winbindd[1090]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2863677428-3605554769-4251959379-501 Sep 06 04:36:52 volumio66 winbindd[1090]: [2025/09/06 04:36:52.679810, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Sep 06 04:36:52 volumio66 winbindd[1090]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-2863677428-3605554769-4251959379-514 Sep 06 04:36:52 volumio66 smbd[3425]: [2025/09/06 04:36:52.690576, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 06 04:36:52 volumio66 systemd[1]: Started Samba SMB Daemon. Sep 06 04:36:52 volumio66 smbd[3425]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 06 04:36:52 volumio66 sudo[3420]: pam_unix(sudo:session): session closed for user root Sep 06 04:36:52 volumio66 volumio[1165]: info: SAMBA Restarted Sep 06 04:36:52 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 06 04:36:52 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 04:36:53 volumio66 volumio-remote-updater[675]: No test mode Sep 06 04:36:53 volumio66 volumio-remote-updater[675]: No alpha test mode Sep 06 04:36:53 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 06 04:36:53 volumio66 volumio[1165]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 04:36:53 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 04:36:56 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Sep 06 04:36:56 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 06 04:36:56 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:56 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Sep 06 04:36:56 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Sep 06 04:36:56 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Sep 06 04:36:56 volumio66 volumio[1165]: xcb_connection_has_error() returned true Sep 06 04:36:58 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Sep 06 04:36:58 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 06 04:36:58 volumio66 systemd[1]: systemd-timedated.service: Succeeded. Sep 06 04:36:58 volumio66 volumio-remote-updater[675]: No test mode Sep 06 04:36:58 volumio66 volumio-remote-updater[675]: No alpha test mode Sep 06 04:36:58 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 06 04:36:58 volumio66 volumio[1165]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 06 04:36:58 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 06 04:36:58 volumio66 volumio[1165]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Sep 06 04:36:58 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Sep 06 04:36:58 volumio66 volumio[1165]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::Reload Ui Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Sep 06 04:37:00 volumio66 volumio[1165]: info: Wizard terminated Successfully Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::volumiosetStartupVolume Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 06 04:37:00 volumio66 volumio[1165]: info: VolumeController:: Setting startup Volume 5 Sep 06 04:37:00 volumio66 volumio[1165]: info: VolumeController::SetAlsaVolume5 Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:00 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:00 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:00 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:00 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:00 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:00 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:37:00 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:00 volumio66 volumio[1165]: SPOTIFY: SPOTIFY VOLUME 30 Sep 06 04:37:00 volumio66 volumio[1165]: SPOTIFY: VOLUMIO VOLUME 5 Sep 06 04:37:00 volumio66 volumio[1165]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 06 04:37:00 volumio66 volumio[1165]: info: Setting Spotify Volume from Volumio: 5 Sep 06 04:37:01 volumio66 sudo[3491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 04:37:01 volumio66 sudo[3491]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:37:01 volumio66 sudo[3491]: pam_unix(sudo:session): session closed for user root Sep 06 04:37:01 volumio66 sudo[3494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 04:37:01 volumio66 sudo[3494]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:37:01 volumio66 sudo[3494]: pam_unix(sudo:session): session closed for user root Sep 06 04:37:01 volumio66 volumio[1165]: verbose: New Socket.io Connection to 192.168.0.79 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 06 04:37:01 volumio66 sudo[3497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 06 04:37:01 volumio66 sudo[3497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:37:01 volumio66 sudo[3497]: pam_unix(sudo:session): session closed for user root Sep 06 04:37:01 volumio66 sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 06 04:37:01 volumio66 sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 04:37:01 volumio66 sudo[3500]: pam_unix(sudo:session): session closed for user root Sep 06 04:37:01 volumio66 volumio[1165]: verbose: New Socket.io Connection to 192.168.0.79 from 192.168.0.192 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 06 04:37:01 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:01 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:02 volumio66 volumio[1165]: SPOTIFY: SETTING SPOTIFY VOLUME 5 Sep 06 04:37:02 volumio66 volumio[1165]: info: Sending Spotify command with payload to local API: /player/volume Sep 06 04:37:02 volumio66 go-librespot[1413]: time="2025-09-06T04:37:02+01:00" level=debug msg="update volume requested to 3276/65535" Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 06 04:37:02 volumio66 volumio[1165]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetVisibleSources Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 06 04:37:02 volumio66 volumio[1165]: info: Listing playlists Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 06 04:37:02 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 04:37:02 volumio66 go-librespot[1413]: time="2025-09-06T04:37:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 06 04:37:02 volumio66 go-librespot[1413]: time="2025-09-06T04:37:02+01:00" level=trace msg="emitting websocket event: volume" Sep 06 04:37:02 volumio66 volumio[1165]: SPOTIFY: received: {"type":"volume","data":{"value":5,"max":100}} Sep 06 04:37:02 volumio66 volumio[1165]: SPOTIFY: RECEIVED SPOTIFY VOLUME 5 Sep 06 04:37:04 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 04:37:04 volumio66 volumio[1165]: info: Received Get System Info Sep 06 04:37:04 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 04:37:04 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 04:37:04 volumio66 volumio[1165]: info: Discovery: Getting this device information Sep 06 04:37:04 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:04 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:04 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 04:37:05 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 06 04:37:05 volumio66 volumio[1165]: info: Received Get System Info Sep 06 04:37:05 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 04:37:05 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 04:37:05 volumio66 volumio[1165]: info: Discovery: Getting this device information Sep 06 04:37:05 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:05 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:05 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 04:37:06 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Sep 06 04:37:06 volumio66 volumio[1165]: info: In handleBrowseUri, curUri=spotify Sep 06 04:37:06 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetQueue Sep 06 04:37:06 volumio66 volumio[1165]: info: CoreStateMachine::getQueue Sep 06 04:37:06 volumio66 volumio[1165]: info: CorePlayQueue::getQueue Sep 06 04:37:06 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:06 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:06 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:06 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:08 volumio66 go-librespot[1413]: time="2025-09-06T04:37:08+01:00" level=trace msg="received accesspoint ping" Sep 06 04:37:08 volumio66 go-librespot[1413]: time="2025-09-06T04:37:08+01:00" level=trace msg="received accesspoint pong ack" Sep 06 04:37:08 volumio66 go-librespot[1413]: time="2025-09-06T04:37:08+01:00" level=trace msg="sent dealer ping" Sep 06 04:37:08 volumio66 go-librespot[1413]: time="2025-09-06T04:37:08+01:00" level=trace msg="received dealer pong" Sep 06 04:37:12 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Sep 06 04:37:12 volumio66 volumio[1165]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Sep 06 04:37:12 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6FbYf4iWkfpFlX6rFpyI7K Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6puVnqnAJZ3Nvt89xSEazZ Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:2165iBxdBgBHWHfPUTklNx Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:2O5tca1sLjsDo8C6PgeApA Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0Aa9rwFRKHZjy6jswdLjmu Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3D22nEXsYnoi0XwyNLLig3 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:57dpUsFjeLNsoPCKG2jmB6 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3FNTf1qU8FAIeSqqnZuswG Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0qwd7ACQNthZmfLKIEEN1q Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3cFywu53NrOfGiqDg5DRZy Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:20H8ijJOwQaWA0p4KX9m3T Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:1QXGDHadzECcysKNMZvOrN Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:72zmIVptVJjjyNevKEMtLb Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:4zMSFxd5AjYArw8yafIwhT Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0AHZtMbyjHWhtWmqE8Egv7 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6eUeYDbA32fZbS2sz9g4RT Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6glj1F8ktbB9dGPAy9qi16 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:70quUuqiAx6XH0BjZUW3Gd Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:4ga2tUghWJYFNVYjo9NhBH Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3urnjdJxUeRACwMeAH9SvI Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3qwJjnF4TuLF2kHuOntIUi Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:7oCKrpWiz8QIn0Jkr0G6sR Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3fK6Zo929DOzAWDgQ9PpIo Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3RazGWRaVqk7zBms8c42ZW Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:42dqgyAJNFTnlgfIIfNghx Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0CTm5xFhPMpWP3yiuaPQy6 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:1h8t1qWZE8UEyQ8c5T1fRL Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3RwF44zv3o7QeuC9MGVq23 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:43hnjJayMstXuSeAx1BWPz Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:5jMOUETQVVwNWTEeEH5N3i Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:20Fp2qTbAw1u2pWjWpPUTe Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:66KQ8hIWI85u0IxUybwn6v Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3fR2GE5f0CiAX7HHyl98S2 Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:5ELXSGNF1LZXdJVUvwvdTm Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:1Xe14jc7cae4RFLUtH55YE Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:2yp0gxqKE9I9WnemfoXesC Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0E8kv9QhCvPg6jS881pHwb Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:71rmZugeBxhTJpyRta7Egr Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:3AbB1wq3rFaAtlj2QwYGHc Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:6Dokq83006VVSh3mj23VsJ Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:7yMYRFn1b6dJhNpLS3TKNa Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:4Gy64kPK6SVwKdb4rs1emJ Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:0EfMMQXeEJnuB8em6WPEoB Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:64gSL5LBN0ULbvg5OUansN Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:5baUzaryxi8EfsQQdjW6wD Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:4sjYK1CGjM95Y72qIJJQ8t Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:12yrAJr9DEtGFR30KQVK0w Sep 06 04:37:12 volumio66 volumio[1165]: info: Preloading song: spotify:track:7xFyYJ9ifCuJMJzOupVrJj Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:6FbYf4iWkfpFlX6rFpyI7K in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6FbYf4iWkfpFlX6rFpyI7K Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:6puVnqnAJZ3Nvt89xSEazZ in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6puVnqnAJZ3Nvt89xSEazZ Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:2165iBxdBgBHWHfPUTklNx in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:2165iBxdBgBHWHfPUTklNx Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6FbYf4iWkfpFlX6rFpyI7K","service":"spop","name":"639 Hz Manifest Love & Miracles","artist":"Miracle Tones","album":"639 Hz Heart Chakra Healing","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff498177c7f64b163d4622fc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:2O5tca1sLjsDo8C6PgeApA in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:2O5tca1sLjsDo8C6PgeApA Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6puVnqnAJZ3Nvt89xSEazZ","service":"spop","name":"417 Hz Manifest Positive Energy","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:0Aa9rwFRKHZjy6jswdLjmu in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0Aa9rwFRKHZjy6jswdLjmu Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2165iBxdBgBHWHfPUTklNx","service":"spop","name":"417 Hz Find Inner Balance","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:3D22nEXsYnoi0XwyNLLig3 in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3D22nEXsYnoi0XwyNLLig3 Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:57dpUsFjeLNsoPCKG2jmB6 in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:57dpUsFjeLNsoPCKG2jmB6 Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2O5tca1sLjsDo8C6PgeApA","service":"spop","name":"417 Hz Transmutation","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":144,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Aa9rwFRKHZjy6jswdLjmu","service":"spop","name":"417 Hz Positive Change","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:3FNTf1qU8FAIeSqqnZuswG in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3FNTf1qU8FAIeSqqnZuswG Sep 06 04:37:12 volumio66 volumio[1165]: info: Exploding uri spotify:track:0qwd7ACQNthZmfLKIEEN1q in service spop Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0qwd7ACQNthZmfLKIEEN1q Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3D22nEXsYnoi0XwyNLLig3","service":"spop","name":"417 Hz Sacral Chakra","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:12 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:57dpUsFjeLNsoPCKG2jmB6","service":"spop","name":"417 Hz Undo Damage","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":129,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3FNTf1qU8FAIeSqqnZuswG","service":"spop","name":"417 Hz Cleanse Trauma","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":125,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3cFywu53NrOfGiqDg5DRZy in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3cFywu53NrOfGiqDg5DRZy Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:20H8ijJOwQaWA0p4KX9m3T in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:20H8ijJOwQaWA0p4KX9m3T Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qwd7ACQNthZmfLKIEEN1q","service":"spop","name":"417 Hz Let Go of Mental Blockages","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":133,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cFywu53NrOfGiqDg5DRZy","service":"spop","name":"417 Hz Remove Negative Energy","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":126,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:1QXGDHadzECcysKNMZvOrN in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:1QXGDHadzECcysKNMZvOrN Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","service":"spop","name":"417 Hz New Beginnings","artist":"Miracle Tones","album":"417 Hz Mindfulness","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:72zmIVptVJjjyNevKEMtLb in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:72zmIVptVJjjyNevKEMtLb Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a","service":"spop","name":"417","artist":"Timo Krantz","album":"Ambient mt. 174-963","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273a6abbc30288e0ac3d5b6bf3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:4zMSFxd5AjYArw8yafIwhT in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:4zMSFxd5AjYArw8yafIwhT Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QXGDHadzECcysKNMZvOrN","service":"spop","name":"Solfeggio Frequencies 417 Hz","artist":"Miracle Tones","album":"417 Hz Undoing Situations and Facilitating Change","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b27302caca8d56aa040855ee487b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:0AHZtMbyjHWhtWmqE8Egv7 in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0AHZtMbyjHWhtWmqE8Egv7 Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:6eUeYDbA32fZbS2sz9g4RT in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6eUeYDbA32fZbS2sz9g4RT Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:72zmIVptVJjjyNevKEMtLb","service":"spop","name":"417 Hz Remove Negative Blocks","artist":"Miracle Tones","album":"417 Hz Undoing Situations and Facilitating Change","type":"song","duration":127,"albumart":"https://i.scdn.co/image/ab67616d0000b27302caca8d56aa040855ee487b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:6glj1F8ktbB9dGPAy9qi16 in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6glj1F8ktbB9dGPAy9qi16 Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4zMSFxd5AjYArw8yafIwhT","service":"spop","name":"417 Hz Undo Emotional Patterns","artist":"Miracle Tones","album":"417 Hz Undoing Situations and Facilitating Change","type":"song","duration":136,"albumart":"https://i.scdn.co/image/ab67616d0000b27302caca8d56aa040855ee487b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0AHZtMbyjHWhtWmqE8Egv7","service":"spop","name":"417 Hz Clear Negative Energy","artist":"Miracle Tones","album":"417 Hz Undoing Situations and Facilitating Change","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b27302caca8d56aa040855ee487b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:70quUuqiAx6XH0BjZUW3Gd in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:70quUuqiAx6XH0BjZUW3Gd Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6eUeYDbA32fZbS2sz9g4RT","service":"spop","name":"417 Hz Facilitate Change","artist":"Miracle Tones","album":"417 Hz Undoing Situations and Facilitating Change","type":"song","duration":126,"albumart":"https://i.scdn.co/image/ab67616d0000b27302caca8d56aa040855ee487b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:4ga2tUghWJYFNVYjo9NhBH in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:4ga2tUghWJYFNVYjo9NhBH Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6glj1F8ktbB9dGPAy9qi16","service":"spop","name":"396","artist":"Timo Krantz","album":"Ambient mt. 174-963","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273a6abbc30288e0ac3d5b6bf3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3urnjdJxUeRACwMeAH9SvI in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3urnjdJxUeRACwMeAH9SvI Sep 06 04:37:13 volumio66 ntpd[821]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70quUuqiAx6XH0BjZUW3Gd","service":"spop","name":"Solfeggio Frequencies 396 Hz","artist":"Miracle Tones","album":"396 Hz Liberating Guilt and Fear","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27397d1d5bfeb99ef1f4b0b19b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3qwJjnF4TuLF2kHuOntIUi in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3qwJjnF4TuLF2kHuOntIUi Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ga2tUghWJYFNVYjo9NhBH","service":"spop","name":"396 Hz Cleanse Fear & Negative Blocks","artist":"Miracle Tones","album":"396 Hz Liberating Guilt and Fear","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b27397d1d5bfeb99ef1f4b0b19b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:7oCKrpWiz8QIn0Jkr0G6sR in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:7oCKrpWiz8QIn0Jkr0G6sR Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3urnjdJxUeRACwMeAH9SvI","service":"spop","name":"396 Hz Liberation from Fear","artist":"Miracle Tones","album":"396 Hz Liberating Guilt and Fear","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b27397d1d5bfeb99ef1f4b0b19b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3fK6Zo929DOzAWDgQ9PpIo in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3fK6Zo929DOzAWDgQ9PpIo Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qwJjnF4TuLF2kHuOntIUi","service":"spop","name":"396 Hz Dissolve Negativity","artist":"Miracle Tones","album":"396 Hz Liberating Guilt and Fear","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b27397d1d5bfeb99ef1f4b0b19b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7oCKrpWiz8QIn0Jkr0G6sR","service":"spop","name":"396 Hz Let Go of Fear & Guilt","artist":"Miracle Tones","album":"396 Hz Liberating Guilt and Fear","type":"song","duration":129,"albumart":"https://i.scdn.co/image/ab67616d0000b27397d1d5bfeb99ef1f4b0b19b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3RazGWRaVqk7zBms8c42ZW in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3RazGWRaVqk7zBms8c42ZW Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:42dqgyAJNFTnlgfIIfNghx in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:42dqgyAJNFTnlgfIIfNghx Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:0CTm5xFhPMpWP3yiuaPQy6 in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0CTm5xFhPMpWP3yiuaPQy6 Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fK6Zo929DOzAWDgQ9PpIo","service":"spop","name":"285","artist":"Timo Krantz","album":"Ambient mt. 174-963","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b273a6abbc30288e0ac3d5b6bf3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:1h8t1qWZE8UEyQ8c5T1fRL in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:1h8t1qWZE8UEyQ8c5T1fRL Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42dqgyAJNFTnlgfIIfNghx","service":"spop","name":"285 Hz Heals & Regenerates Tissues","artist":"Miracle Tones","album":"285 Hz Rejuvenated Energy Fields","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b27325c5d95b96d7002da9dc86d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3RazGWRaVqk7zBms8c42ZW","service":"spop","name":"Solfeggio Frequencies 285 Hz","artist":"Miracle Tones","album":"285 Hz Rejuvenated Energy Fields","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b27325c5d95b96d7002da9dc86d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:13 volumio66 volumio[1165]: info: Exploding uri spotify:track:3RwF44zv3o7QeuC9MGVq23 in service spop Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3RwF44zv3o7QeuC9MGVq23 Sep 06 04:37:13 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0CTm5xFhPMpWP3yiuaPQy6","service":"spop","name":"285 Hz Powerful Om Mantra Meditation","artist":"Miracle Tones","album":"285 Hz Rejuvenated Energy Fields","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b27325c5d95b96d7002da9dc86d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:43hnjJayMstXuSeAx1BWPz in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:43hnjJayMstXuSeAx1BWPz Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1h8t1qWZE8UEyQ8c5T1fRL","service":"spop","name":"285 Hz Rejuvenated Energy Fields","artist":"Miracle Tones","album":"285 Hz Rejuvenated Energy Fields","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b27325c5d95b96d7002da9dc86d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:5jMOUETQVVwNWTEeEH5N3i in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:5jMOUETQVVwNWTEeEH5N3i Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3RwF44zv3o7QeuC9MGVq23","service":"spop","name":"285 Hz Quantum Cognition","artist":"Miracle Tones","album":"285 Hz Rejuvenated Energy Fields","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b27325c5d95b96d7002da9dc86d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:20Fp2qTbAw1u2pWjWpPUTe in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:20Fp2qTbAw1u2pWjWpPUTe Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:43hnjJayMstXuSeAx1BWPz","service":"spop","name":"174","artist":"Timo Krantz","album":"Ambient mt. 174-963","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273a6abbc30288e0ac3d5b6bf3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:66KQ8hIWI85u0IxUybwn6v in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:66KQ8hIWI85u0IxUybwn6v Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5jMOUETQVVwNWTEeEH5N3i","service":"spop","name":"Solfeggio Frequencies 174 Hz","artist":"Miracle Tones","album":"174 Hz Pain Reduction","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273c689a609e42c64c248f19a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:3fR2GE5f0CiAX7HHyl98S2 in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3fR2GE5f0CiAX7HHyl98S2 Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:20Fp2qTbAw1u2pWjWpPUTe","service":"spop","name":"174 Hz Healing Tone for Sleep","artist":"Miracle Tones","album":"174 Hz Pain Reduction","type":"song","duration":120,"albumart":"https://i.scdn.co/image/ab67616d0000b273c689a609e42c64c248f19a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:5ELXSGNF1LZXdJVUvwvdTm in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:5ELXSGNF1LZXdJVUvwvdTm Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:1Xe14jc7cae4RFLUtH55YE in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:1Xe14jc7cae4RFLUtH55YE Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:66KQ8hIWI85u0IxUybwn6v","service":"spop","name":"174 Hz Deep Healing","artist":"Miracle Tones","album":"174 Hz Pain Reduction","type":"song","duration":121,"albumart":"https://i.scdn.co/image/ab67616d0000b273c689a609e42c64c248f19a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:2yp0gxqKE9I9WnemfoXesC in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:2yp0gxqKE9I9WnemfoXesC Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:0E8kv9QhCvPg6jS881pHwb in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0E8kv9QhCvPg6jS881pHwb Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Xe14jc7cae4RFLUtH55YE","service":"spop","name":"Enough","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ELXSGNF1LZXdJVUvwvdTm","service":"spop","name":"174 Hz Foundation","artist":"Miracle Tones","album":"174 Hz Pain Reduction","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273c689a609e42c64c248f19a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:71rmZugeBxhTJpyRta7Egr in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:71rmZugeBxhTJpyRta7Egr Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fR2GE5f0CiAX7HHyl98S2","service":"spop","name":"174 Hz Pain Relief & Healing","artist":"Miracle Tones","album":"174 Hz Pain Reduction","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273c689a609e42c64c248f19a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yp0gxqKE9I9WnemfoXesC","service":"spop","name":"Infinite","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:3AbB1wq3rFaAtlj2QwYGHc in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:3AbB1wq3rFaAtlj2QwYGHc Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:6Dokq83006VVSh3mj23VsJ in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:6Dokq83006VVSh3mj23VsJ Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:71rmZugeBxhTJpyRta7Egr","service":"spop","name":"Whole","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0E8kv9QhCvPg6jS881pHwb","service":"spop","name":"Eternal","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AbB1wq3rFaAtlj2QwYGHc","service":"spop","name":"Grounded","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:7yMYRFn1b6dJhNpLS3TKNa in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:7yMYRFn1b6dJhNpLS3TKNa Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Dokq83006VVSh3mj23VsJ","service":"spop","name":"Present","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:4Gy64kPK6SVwKdb4rs1emJ in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:4Gy64kPK6SVwKdb4rs1emJ Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ","service":"spop","name":"Peaceful","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:0EfMMQXeEJnuB8em6WPEoB in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:0EfMMQXeEJnuB8em6WPEoB Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7yMYRFn1b6dJhNpLS3TKNa","service":"spop","name":"Calm","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:64gSL5LBN0ULbvg5OUansN in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:64gSL5LBN0ULbvg5OUansN Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Gy64kPK6SVwKdb4rs1emJ","service":"spop","name":"Free","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":143,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:5baUzaryxi8EfsQQdjW6wD in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:5baUzaryxi8EfsQQdjW6wD Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0EfMMQXeEJnuB8em6WPEoB","service":"spop","name":"In This Moment, I Am","artist":"Miracle Tones","album":"Anxiety Relief","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27365ff7009e9cd275ac8fddafc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:4sjYK1CGjM95Y72qIJJQ8t in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:4sjYK1CGjM95Y72qIJJQ8t Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:64gSL5LBN0ULbvg5OUansN","service":"spop","name":"528","artist":"Timo Krantz","album":"Ambient mt. 174-963","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273a6abbc30288e0ac3d5b6bf3f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:14 volumio66 volumio[1165]: info: Exploding uri spotify:track:12yrAJr9DEtGFR30KQVK0w in service spop Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:12yrAJr9DEtGFR30KQVK0w Sep 06 04:37:14 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5baUzaryxi8EfsQQdjW6wD","service":"spop","name":"528 Hz Meditation Music","artist":"Miracle Tones","album":"528 Hz Meditation Music","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7505de85c5312dbbf97e97e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:15 volumio66 volumio[1165]: info: Exploding uri spotify:track:7xFyYJ9ifCuJMJzOupVrJj in service spop Sep 06 04:37:15 volumio66 volumio[1165]: SPOTIFY: EXPLODING URI:spotify:track:7xFyYJ9ifCuJMJzOupVrJj Sep 06 04:37:15 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4sjYK1CGjM95Y72qIJJQ8t","service":"spop","name":"528 Hz Dreamscape for Positive Transformation","artist":"Miracle Tones","album":"528 Hz Meditation Music","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7505de85c5312dbbf97e97e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:15 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:12yrAJr9DEtGFR30KQVK0w","service":"spop","name":"528 Hz Manifest Love","artist":"Miracle Tones","album":"528 Hz Meditation Music","type":"song","duration":121,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7505de85c5312dbbf97e97e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:15 volumio66 volumio[1165]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7xFyYJ9ifCuJMJzOupVrJj","service":"spop","name":"528 Hz Raise Positive Vibrations","artist":"Miracle Tones","album":"528 Hz Meditation Music","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7505de85c5312dbbf97e97e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 06 04:37:18 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::ClearQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::stop Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::updateTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::stPlaybackTimer Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::serviceStop Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 0 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::serviceStop Sep 06 04:37:18 volumio66 volumio[1165]: info: Spotify Stop Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: SPOTIFY STOP Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: {"status":"pause","position":0,"title":"Die With A Smile","artist":"Lady Gaga","album":"MAYHEM","albumart":"https://i.scdn.co/image/ab67616d0000b273b0860cf0a98e09663c82290c","uri":"spotify:track:7so0lgd0zP2Sbgs2d7a1SZ","trackType":"spotify","codec":"ogg","seek":10266,"duration":251,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":5,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 06 04:37:18 volumio66 volumio[1165]: info: Sending Spotify command to local API: /player/pause Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::clearPlayQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::saveQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::addQueueItems Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::addQueueItems Sep 06 04:37:18 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6FbYf4iWkfpFlX6rFpyI7K Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6FbYf4iWkfpFlX6rFpyI7K Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6puVnqnAJZ3Nvt89xSEazZ Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6puVnqnAJZ3Nvt89xSEazZ Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:2165iBxdBgBHWHfPUTklNx Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:2165iBxdBgBHWHfPUTklNx Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:2O5tca1sLjsDo8C6PgeApA Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:2O5tca1sLjsDo8C6PgeApA Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0Aa9rwFRKHZjy6jswdLjmu Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0Aa9rwFRKHZjy6jswdLjmu Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3D22nEXsYnoi0XwyNLLig3 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3D22nEXsYnoi0XwyNLLig3 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:57dpUsFjeLNsoPCKG2jmB6 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:57dpUsFjeLNsoPCKG2jmB6 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3FNTf1qU8FAIeSqqnZuswG Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3FNTf1qU8FAIeSqqnZuswG Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0qwd7ACQNthZmfLKIEEN1q Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0qwd7ACQNthZmfLKIEEN1q Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3cFywu53NrOfGiqDg5DRZy Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3cFywu53NrOfGiqDg5DRZy Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:20H8ijJOwQaWA0p4KX9m3T Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:20H8ijJOwQaWA0p4KX9m3T Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::saveQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::updateTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPlay Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::play index 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::addQueueItems Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::addQueueItems Sep 06 04:37:18 volumio66 volumio[1165]: info: Preload queue cleared Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6gixZv6Fa3Fg4BKnY5mZ4a Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:1QXGDHadzECcysKNMZvOrN Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:1QXGDHadzECcysKNMZvOrN Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:72zmIVptVJjjyNevKEMtLb Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:72zmIVptVJjjyNevKEMtLb Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:4zMSFxd5AjYArw8yafIwhT Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:4zMSFxd5AjYArw8yafIwhT Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0AHZtMbyjHWhtWmqE8Egv7 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0AHZtMbyjHWhtWmqE8Egv7 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6eUeYDbA32fZbS2sz9g4RT Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6eUeYDbA32fZbS2sz9g4RT Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6glj1F8ktbB9dGPAy9qi16 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6glj1F8ktbB9dGPAy9qi16 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:70quUuqiAx6XH0BjZUW3Gd Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:70quUuqiAx6XH0BjZUW3Gd Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:4ga2tUghWJYFNVYjo9NhBH Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:4ga2tUghWJYFNVYjo9NhBH Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3urnjdJxUeRACwMeAH9SvI Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3urnjdJxUeRACwMeAH9SvI Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3qwJjnF4TuLF2kHuOntIUi Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3qwJjnF4TuLF2kHuOntIUi Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:7oCKrpWiz8QIn0Jkr0G6sR Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:7oCKrpWiz8QIn0Jkr0G6sR Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3fK6Zo929DOzAWDgQ9PpIo Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3fK6Zo929DOzAWDgQ9PpIo Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3RazGWRaVqk7zBms8c42ZW Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3RazGWRaVqk7zBms8c42ZW Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:42dqgyAJNFTnlgfIIfNghx Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:42dqgyAJNFTnlgfIIfNghx Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0CTm5xFhPMpWP3yiuaPQy6 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0CTm5xFhPMpWP3yiuaPQy6 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:1h8t1qWZE8UEyQ8c5T1fRL Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:1h8t1qWZE8UEyQ8c5T1fRL Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3RwF44zv3o7QeuC9MGVq23 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3RwF44zv3o7QeuC9MGVq23 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:43hnjJayMstXuSeAx1BWPz Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:43hnjJayMstXuSeAx1BWPz Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:5jMOUETQVVwNWTEeEH5N3i Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:5jMOUETQVVwNWTEeEH5N3i Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:20Fp2qTbAw1u2pWjWpPUTe Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:20Fp2qTbAw1u2pWjWpPUTe Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:66KQ8hIWI85u0IxUybwn6v Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:66KQ8hIWI85u0IxUybwn6v Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3fR2GE5f0CiAX7HHyl98S2 Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3fR2GE5f0CiAX7HHyl98S2 Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:5ELXSGNF1LZXdJVUvwvdTm Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:5ELXSGNF1LZXdJVUvwvdTm Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:1Xe14jc7cae4RFLUtH55YE Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:1Xe14jc7cae4RFLUtH55YE Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:2yp0gxqKE9I9WnemfoXesC Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:2yp0gxqKE9I9WnemfoXesC Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0E8kv9QhCvPg6jS881pHwb Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0E8kv9QhCvPg6jS881pHwb Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:71rmZugeBxhTJpyRta7Egr Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:71rmZugeBxhTJpyRta7Egr Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:3AbB1wq3rFaAtlj2QwYGHc Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:3AbB1wq3rFaAtlj2QwYGHc Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:6Dokq83006VVSh3mj23VsJ Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:6Dokq83006VVSh3mj23VsJ Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:1CJ7CdeVKlMucpQ2mgUqCZ Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:7yMYRFn1b6dJhNpLS3TKNa Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:7yMYRFn1b6dJhNpLS3TKNa Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:4Gy64kPK6SVwKdb4rs1emJ Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:4Gy64kPK6SVwKdb4rs1emJ Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:0EfMMQXeEJnuB8em6WPEoB Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:0EfMMQXeEJnuB8em6WPEoB Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:64gSL5LBN0ULbvg5OUansN Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:64gSL5LBN0ULbvg5OUansN Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:5baUzaryxi8EfsQQdjW6wD Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:5baUzaryxi8EfsQQdjW6wD Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:4sjYK1CGjM95Y72qIJJQ8t Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:4sjYK1CGjM95Y72qIJJQ8t Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:12yrAJr9DEtGFR30KQVK0w Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:12yrAJr9DEtGFR30KQVK0w Sep 06 04:37:18 volumio66 volumio[1165]: info: Adding Item to queue: spotify:track:7xFyYJ9ifCuJMJzOupVrJj Sep 06 04:37:18 volumio66 volumio[1165]: info: Using cached record of: spotify:track:7xFyYJ9ifCuJMJzOupVrJj Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::stop Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::saveQueue Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::play index undefined Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::updateTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrackBlock Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::startPlaybackTimer Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: [1757129838113] ControllerSpotify::clearAddPlayTrack Sep 06 04:37:18 volumio66 volumio[1165]: info: Sending Spotify command with payload to local API: /player/play Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: stop Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Handling event: musicStop Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="pause track at 19125ms" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="emitting websocket event: paused" Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7so0lgd0zP2Sbgs2d7a1SZ","play_origin":"go-librespot"}} Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService pause Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: stop Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="resolved context of track" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="emitting websocket event: will_play" Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","play_origin":"go-librespot"}} Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="selected format OGG_VORBIS_320 (afc4b0f948050c2af42941c2ea170fb76a31a2bd)" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="requested aes key for file afc4b0f948050c2af42941c2ea170fb76a31a2bd, gid: 20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="fetched first chunk of 10, total size is 5078388 bytes" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 volumio[1165]: info: FusionDsp - ---- read samplerate, raw: Sep 06 04:37:18 volumio66 volumio[1165]: error: FusionDsp - invalid sample rate Sep 06 04:37:18 volumio66 volumio[1165]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=info msg="loaded track \"417 Hz New Beginnings\" (paused: false, position: 0ms, duration: 142000ms, prefetched: false)" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 volumio[1165]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 06 04:37:18 volumio66 volumio[1165]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 04:37:18 volumio66 volumio[1165]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="fetched chunk 3/9, size: 524288" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="scheduling prefetch in 112s" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="emitting websocket event: metadata" Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","name":"417 Hz New Beginnings","artist_names":["Miracle Tones","Solfeggio Healing Frequencies MT"],"album_name":"417 Hz Mindfulness","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","position":0,"duration":142000,"release_date":"year:2021 month:4 day:23","track_number":1,"disc_number":1}} Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=trace msg="emitting websocket event: playing" Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","resume":false,"play_origin":"go-librespot"}} Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":0,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":0,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService play Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus stop Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="fetched chunk 1/9, size: 524288" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 go-librespot[1413]: time="2025-09-06T04:37:18+01:00" level=debug msg="fetched chunk 2/9, size: 524288" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:18 volumio66 volumio[1165]: SPOTIFY: {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":0,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":0,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:18 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService play Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus play Sep 06 04:37:18 volumio66 volumio[1165]: info: Received an update from plugin. extracting info from payload Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:18 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:18 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:18 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: play Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Handling event: musicPlay Sep 06 04:37:18 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: play Sep 06 04:37:19 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:19 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 06 04:37:19 volumio66 volumio[1165]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 06 04:37:19 volumio66 volumio[1165]: info: Received Get System Version Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 06 04:37:19 volumio66 volumio[1165]: info: Received Get System Info Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 06 04:37:19 volumio66 volumio[1165]: info: Discovery: Getting this device information Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:19 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:19 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 06 04:37:26 volumio66 go-librespot[1413]: time="2025-09-06T04:37:26+01:00" level=debug msg="fetched chunk 4/9, size: 524288" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 06 04:37:27 volumio66 volumio[1165]: info: Pausing Remote Device: http://192.168.0.79 Sep 06 04:37:27 volumio66 volumio[1165]: info: Sep 06 04:37:27 volumio66 volumio[1165]: ---------------------------- Client requests Volumio pause Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPause Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::pause Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::stPlaybackTimer Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::servicePause Sep 06 04:37:27 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::servicePause Sep 06 04:37:27 volumio66 volumio[1165]: info: Spotify Received pause Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: SPOTIFY PAUSE Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: {"status":"play","position":10,"title":"417 Hz New Beginnings","artist":"Miracle Tones","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d0000b273bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","codec":"ogg","seek":0,"duration":142,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":5,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 06 04:37:27 volumio66 volumio[1165]: info: Sending Spotify command to local API: /player/pause Sep 06 04:37:27 volumio66 volumio[1165]: info: Done pausing: Sep 06 04:37:27 volumio66 go-librespot[1413]: time="2025-09-06T04:37:27+01:00" level=debug msg="pause track at 15503ms" Sep 06 04:37:27 volumio66 go-librespot[1413]: time="2025-09-06T04:37:27+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:27 volumio66 go-librespot[1413]: time="2025-09-06T04:37:27+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:27 volumio66 go-librespot[1413]: time="2025-09-06T04:37:27+01:00" level=trace msg="emitting websocket event: paused" Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","play_origin":"go-librespot"}} Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: {"status":"pause","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:27 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:27 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:27 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService pause Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus pause Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:27 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:27 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:27 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:27 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:27 volumio66 volumio[1165]: info: CoreStateMachine::stPlaybackTimer Sep 06 04:37:27 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: pause Sep 06 04:37:27 volumio66 volumio[1165]: info: [GPIO_Control] Handling event: musicPause Sep 06 04:37:27 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:28 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 06 04:37:28 volumio66 volumio[1165]: info: Playing Remote Device: http://192.168.0.79 Sep 06 04:37:28 volumio66 volumio[1165]: info: Sep 06 04:37:28 volumio66 volumio[1165]: ---------------------------- Client requests Volumio play Sep 06 04:37:28 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPlay Sep 06 04:37:28 volumio66 volumio[1165]: info: CoreStateMachine::play index undefined Sep 06 04:37:28 volumio66 volumio[1165]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 06 04:37:28 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:28 volumio66 volumio[1165]: info: CoreStateMachine::startPlaybackTimer Sep 06 04:37:28 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:28 volumio66 volumio[1165]: info: Spotify Resume Sep 06 04:37:28 volumio66 volumio[1165]: info: Sending Spotify command to local API: /player/resume Sep 06 04:37:28 volumio66 volumio[1165]: info: Done playing: Sep 06 04:37:28 volumio66 go-librespot[1413]: time="2025-09-06T04:37:28+01:00" level=trace msg="seek to 15503ms (diff: 72ms, samples: 683682, bytes: 553043)" uri="spotify:track:20H8ijJOwQaWA0p4KX9m3T" Sep 06 04:37:28 volumio66 go-librespot[1413]: time="2025-09-06T04:37:28+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Sep 06 04:37:28 volumio66 go-librespot[1413]: time="2025-09-06T04:37:28+01:00" level=debug msg="resume track at 15431ms" Sep 06 04:37:28 volumio66 volumio[1165]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 06 04:37:28 volumio66 volumio[1165]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 06 04:37:28 volumio66 volumio[1165]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 06 04:37:28 volumio66 volumio[1165]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 06 04:37:28 volumio66 go-librespot[1413]: time="2025-09-06T04:37:28+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:28 volumio66 go-librespot[1413]: time="2025-09-06T04:37:28+01:00" level=trace msg="scheduling prefetch in 97s" Sep 06 04:37:29 volumio66 go-librespot[1413]: time="2025-09-06T04:37:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 06 04:37:29 volumio66 go-librespot[1413]: time="2025-09-06T04:37:29+01:00" level=trace msg="emitting websocket event: playing" Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","resume":true,"play_origin":"go-librespot"}} Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:29 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService play Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus pause Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: play Sep 06 04:37:29 volumio66 volumio[1165]: info: [GPIO_Control] Handling event: musicPlay Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: PUSH STATE SPOTIFY Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::servicePushState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"417 Hz New Beginnings","artist":"Miracle Tones, Solfeggio Healing Frequencies MT","album":"417 Hz Mindfulness","albumart":"https://i.scdn.co/image/ab67616d00001e02bf09bf7968bd78d314fd934a","uri":"spotify:track:20H8ijJOwQaWA0p4KX9m3T","trackType":"spotify","seek":8000,"duration":142,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 06 04:37:29 volumio66 volumio[1165]: verbose: CURRENT POSITION 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::syncState stateService play Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::syncState currentStatus play Sep 06 04:37:29 volumio66 volumio[1165]: info: Received an update from plugin. extracting info from payload Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreStateMachine::pushState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioPushState Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output update for this device Sep 06 04:37:29 volumio66 volumio[1165]: info: MRS: Pushing multiroomSync output Sep 06 04:37:29 volumio66 volumio[1165]: info: CoreCommandRouter::volumioGetState Sep 06 04:37:29 volumio66 volumio[1165]: info: CorePlayQueue::getTrack 10 Sep 06 04:37:29 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: play Sep 06 04:37:29 volumio66 volumio[1165]: info: [GPIO_Control] Status changed: play Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:29 volumio66 volumio[1165]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Sep 06 04:37:31 volumio66 volumio[1165]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Sep 06 04:37:31 volumio66 volumio[1165]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Sep 06 04:37:31 volumio66 volumio[1165]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 04:37:31 volumio66 volumio[1165]: TypeError: Cannot read property 'then' of undefined Sep 06 04:37:31 volumio66 volumio[1165]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Sep 06 04:37:31 volumio66 volumio[1165]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Sep 06 04:37:31 volumio66 volumio[1165]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Sep 06 04:37:31 volumio66 volumio[1165]: at Socket.emit (events.js:315:20) Sep 06 04:37:31 volumio66 volumio[1165]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 06 04:37:31 volumio66 volumio[1165]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 06 04:37:31 volumio66 volumio[1165]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 04:37:32 volumio66 sudo[3609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-06 04:36 Sep 06 04:37:32 volumio66 sudo[3609]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"