-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Sat 2025-05-24 05:07:24 HKT. -- May 24 05:06:00 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 24 05:06:00 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:01 volumio volumio[1173]: info: MRS: Found cast device: Smart-TV-Pro-3c1c45da627053ae439e631fb8defce6 May 24 05:06:01 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 24 05:06:01 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root May 24 05:06:01 volumio volumio[1173]: info: Remote SSH Started May 24 05:06:01 volumio autossh[1672]: port set to 0, monitoring disabled May 24 05:06:01 volumio autossh[1672]: starting ssh (count 1) May 24 05:06:01 volumio autossh[1672]: ssh child pid is 1675 May 24 05:06:01 volumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 24 05:06:01 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:01 volumio volumiossh-tunnel[1670]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. May 24 05:06:01 volumio systemd[1]: Started Volumio Bluetooth Module. May 24 05:06:01 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root May 24 05:06:01 volumio volumio[1173]: info: Volumio BT Module successfully started May 24 05:06:01 volumio sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 24 05:06:01 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:01 volumio volumiobt[1679]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 24 05:06:01 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root May 24 05:06:01 volumio volumio[1173]: info: TidalConnect service stoped! May 24 05:06:01 volumio volumio[1173]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 24 05:06:01 volumio volumio[1173]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 24 05:06:01 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 24 05:06:01 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:01 volumio systemd[1]: Started Volumio Tidal Connect Service. May 24 05:06:01 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root May 24 05:06:01 volumio volumio[1173]: info: Executing endpoint tc_getconfig May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 24 05:06:01 volumio vtcs[1694]: STARTING TidalConnect services, version: 1.4.0.34 May 24 05:06:01 volumio vtcs[1694]: STARTED TidalConnect services. May 24 05:06:01 volumio volumio[1173]: info: Executing endpoint tc_connect May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 24 05:06:01 volumio volumio[1173]: info: Connecting to TidalConnect May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::servicePushState May 24 05:06:01 volumio volumio[1173]: info: CoreStateMachine::pushState May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState May 24 05:06:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device May 24 05:06:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::servicePushState May 24 05:06:01 volumio volumio[1173]: info: CoreStateMachine::pushState May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState May 24 05:06:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device May 24 05:06:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output May 24 05:06:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:01 volumio volumio[1173]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 24 05:06:01 volumio volumio[1173]: info: [ASDebug] CurState: stop PrevState: stop May 24 05:06:01 volumio volumio[1173]: info: [ASDebug] CurState: stop PrevState: stop May 24 05:06:02 volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 24 05:06:02 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:02 volumio volumiobt[1679]: pulseaudio: no process found May 24 05:06:02 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root May 24 05:06:02 volumio dbus-daemon[614]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=1717 comm="/usr/bin/pulseaudio --high-priority=true ") May 24 05:06:02 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 24 05:06:02 volumio volumio[1173]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 05:06:02 volumio dbus-daemon[614]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 24 05:06:02 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. May 24 05:06:02 volumio rtkit-daemon[1718]: Successfully called chroot. May 24 05:06:02 volumio rtkit-daemon[1718]: Successfully dropped privileges. May 24 05:06:02 volumio rtkit-daemon[1718]: Successfully limited resources. May 24 05:06:02 volumio rtkit-daemon[1718]: Running. May 24 05:06:02 volumio rtkit-daemon[1718]: Canary thread running. May 24 05:06:02 volumio rtkit-daemon[1718]: Watchdog thread running. May 24 05:06:02 volumio dbus-daemon[614]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=1718 comm="/usr/lib/rtkit/rtkit-daemon ") May 24 05:06:02 volumio systemd[1]: Starting Authorization Manager... May 24 05:06:02 volumio volumio[1173]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 05:06:02 volumio polkitd[1722]: started daemon version 0.105 using authority implementation `local' version `0.105' May 24 05:06:02 volumio dbus-daemon[614]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 24 05:06:02 volumio systemd[1]: Started Authorization Manager. May 24 05:06:02 volumio pulseaudio[1717]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 24 05:06:02 volumio pulseaudio[1717]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 24 05:06:02 volumio bluetoothd[777]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource May 24 05:06:02 volumio bluetoothd[777]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink May 24 05:06:02 volumio pulseaudio[1717]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 24 05:06:02 volumio volumiobt[1679]: Applying permissions May 24 05:06:02 volumio volumiobt[1679]: Setting BT discoverable and pairable May 24 05:06:02 volumio kernel: Bluetooth: RFCOMM TTY layer initialized May 24 05:06:02 volumio kernel: Bluetooth: RFCOMM socket layer initialized May 24 05:06:02 volumio kernel: Bluetooth: RFCOMM ver 1.11 May 24 05:06:02 volumio volumiobt[1679]: [CHG] Controller E4:5F:01:00:86:CF UUIDs: 00001112-0000-1000-8000-00805f9b34fb May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [117B blob data] May 24 05:06:02 volumio volumiobt[1679]: [55B blob data] May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# -e power on May 24 05:06:02 volumio volumiobt[1679]: Invalid command in menu main: -e May 24 05:06:02 volumio volumiobt[1679]: May 24 05:06:02 volumio volumiobt[1679]: Use "help" for a list of available commands in a menu. May 24 05:06:02 volumio volumiobt[1679]: Use "menu " if you want to enter any submenu. May 24 05:06:02 volumio volumiobt[1679]: Use "back" if you want to return to menu main. May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# agent on May 24 05:06:02 volumio volumiobt[1679]: Agent is already registered May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# discoverable on May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# pairable on May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# agent NoInputNoOutput May 24 05:06:02 volumio volumiobt[1679]: Agent is already registered May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# default-agent May 24 05:06:02 volumio volumiobt[1679]: [bluetooth]# quit May 24 05:06:02 volumio volumiobt[1679]: [59B blob data] May 24 05:06:03 volumio volumio[1173]: info: MRS: Getting audio outputs on start May 24 05:06:03 volumio volumio[1173]: info: MRS: Requesting all other devices output May 24 05:06:04 volumio volumio[1491]: .....................................................++++ May 24 05:06:04 volumio volumio[1491]: e is 65537 (0x010001) May 24 05:06:04 volumio volumio[1491]: writing RSA key May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:04 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:04 volumio volumio[1173]: info: TidalConnect service started! May 24 05:06:04 volumio volumio[1173]: [Metrics] CommandRouter: 28s 993.25ms May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::volumiosetStartupVolume May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::Close All Modals sent May 24 05:06:04 volumio volumio[1173]: info: CoreCommandRouter::Close All Modals sent May 24 05:06:04 volumio volumio[1173]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 24 05:06:04 volumio volumio[1173]: xcb_connection_has_error() returned true May 24 05:06:04 volumio volumio[1173]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 24 05:06:04 volumio volumio[1173]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 24 05:06:04 volumio volumio[1173]: aplay: main:828: audio open error: No such device May 24 05:06:05 volumio sudo[1750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 05:06:05 volumio sudo[1750]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:05 volumio sudo[1750]: pam_unix(sudo:session): session closed for user root May 24 05:06:05 volumio sudo[1753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 05:06:05 volumio sudo[1753]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:05 volumio sudo[1753]: pam_unix(sudo:session): session closed for user root May 24 05:06:05 volumio volumio[1173]: verbose: New Socket.io Connection to 192.168.68.57 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 24 05:06:05 volumio sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 05:06:05 volumio sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:05 volumio sudo[1756]: pam_unix(sudo:session): session closed for user root May 24 05:06:05 volumio sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 05:06:05 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:06:05 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 05:06:05 volumio volumio[1173]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 05:06:05 volumio volumio[1173]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 05:06:05 volumio volumio[1173]: info: Received Get System Info May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:05 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:05 volumio volumio[1173]: info: Listing playlists May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 05:06:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 05:06:07 volumio volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:07 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 05:06:07 volumio volumio[1173]: info: Received Get System Info May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:07 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:07 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:07 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:08 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 05:06:08 volumio volumio[1173]: info: Received Get System Info May 24 05:06:08 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:08 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:08 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:08 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:08 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:08 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:09 volumio volumio[1173]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 24 05:06:09 volumio volumio[1173]: info: Received Get System Info May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:09 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:09 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:09 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:09 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 05:06:09 volumio volumio[1173]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 05:06:09 volumio volumio[1173]: info: Received Get System Info May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:09 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:09 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:09 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:09 volumio volumio[1173]: info: Listing playlists May 24 05:06:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 05:06:11 volumio volumio[1173]: info: BOOT COMPLETED May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 05:06:12 volumio volumio-remote-updater[617]: No test mode May 24 05:06:12 volumio volumio-remote-updater[617]: No alpha test mode May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::volumioGetBrowseSources May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 05:06:12 volumio volumio[1173]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 24 05:06:12 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 24 05:06:13 volumio go-librespot[1378]: time="2025-05-24T05:06:13+08:00" level=trace msg="sent dealer ping" May 24 05:06:13 volumio go-librespot[1378]: time="2025-05-24T05:06:13+08:00" level=trace msg="received dealer pong" May 24 05:06:15 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 05:06:15 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 05:06:15 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 05:06:19 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 05:06:24 volumio volumio[1173]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 24 05:06:24 volumio volumio[1173]: info: Received Get System Version May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 05:06:24 volumio volumio[1173]: info: Received Get System Info May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:24 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:24 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:25 volumio volumio[1173]: info: Starting Uninstall of plugin system_controller - ampswitch May 24 05:06:25 volumio volumio[1173]: info: Uninstalling plugin ampswitch May 24 05:06:25 volumio volumio[1173]: info: [ASDebug] Port: 24 May 24 05:06:25 volumio volumio[1173]: info: [ASDebug] Inverted: false May 24 05:06:25 volumio volumio[1173]: info: [ASDebug] Delay: 720 May 24 05:06:27 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:27 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:27 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:27 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:27 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:27 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:06:29 volumio volumio[1173]: info: Starting Uninstall of plugin system_controller - ampswitch May 24 05:06:29 volumio volumio[1173]: info: Uninstalling plugin ampswitch May 24 05:06:29 volumio volumio[1173]: info: [ASDebug] Port: 24 May 24 05:06:29 volumio volumio[1173]: info: [ASDebug] Inverted: false May 24 05:06:29 volumio volumio[1173]: info: [ASDebug] Delay: 720 May 24 05:06:43 volumio go-librespot[1378]: time="2025-05-24T05:06:43+08:00" level=trace msg="sent dealer ping" May 24 05:06:43 volumio go-librespot[1378]: time="2025-05-24T05:06:43+08:00" level=trace msg="received dealer pong" May 24 05:06:57 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:06:57 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:06:57 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:06:57 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:06:57 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:06:57 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:01 volumio sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 05:07:01 volumio sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:07:01 volumio sudo[1901]: pam_unix(sudo:session): session closed for user root May 24 05:07:01 volumio sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 05:07:01 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:07:01 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root May 24 05:07:01 volumio volumio[1173]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 24 05:07:01 volumio volumio[1173]: info: Received Get System Info May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:01 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 05:07:01 volumio volumio[1173]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 05:07:01 volumio volumio[1173]: info: Received Get System Info May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:01 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: Listing playlists May 24 05:07:01 volumio volumio[1173]: info: VolumeController::SetAlsaVolume0 May 24 05:07:01 volumio volumio[1173]: info: CoreStateMachine::pushState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState May 24 05:07:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device May 24 05:07:01 volumio volumio[1173]: info: MRS: Pushing multiroomSync output May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:01 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:01 volumio volumio[1173]: info: [ASDebug] CurState: stop PrevState: stop May 24 05:07:01 volumio volumio[1173]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 24 05:07:01 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 05:07:03 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 05:07:03 volumio volumio[1173]: info: Received Get System Info May 24 05:07:03 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:03 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:03 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:03 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:03 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:03 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:04 volumio sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 05:07:04 volumio sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:07:04 volumio sudo[1908]: pam_unix(sudo:session): session closed for user root May 24 05:07:04 volumio volumio[1173]: verbose: New Socket.io Connection to wbecevccbjbhpuwbz4e5dg49snk2-11bea2f645a43db78d7bf097a445a96d.as1.myvolumio.org from 127.0.0.1 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 24 05:07:04 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 05:07:04 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 05:07:04 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root May 24 05:07:04 volumio volumio[1173]: verbose: New Socket.io Connection to 192.168.68.51 from 192.168.68.52 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 24 05:07:04 volumio volumio[1173]: info: Received Get System Info May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:04 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:04 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:04 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:04 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 05:07:05 volumio volumio[1173]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 05:07:05 volumio volumio[1173]: info: Received Get System Info May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:05 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:05 volumio volumio[1173]: info: Listing playlists May 24 05:07:05 volumio volumio[1173]: info: Received Get System Info May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 05:07:05 volumio volumio[1173]: info: Discovery: Getting this device information May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:05 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:05 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 05:07:06 volumio volumio[1173]: info: CoreCommandRouter::volumioGetQueue May 24 05:07:06 volumio volumio[1173]: info: CoreStateMachine::getQueue May 24 05:07:06 volumio volumio[1173]: info: CorePlayQueue::getQueue May 24 05:07:09 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 24 05:07:09 volumio volumio[1173]: info: browseTIDALUri took 170 milliseconds May 24 05:07:09 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:09 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:11 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 24 05:07:11 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 05:07:13 volumio volumio[1173]: info: browseTIDALUri took 1783 milliseconds May 24 05:07:13 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:13 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:13 volumio go-librespot[1378]: time="2025-05-24T05:07:13+08:00" level=trace msg="sent dealer ping" May 24 05:07:13 volumio go-librespot[1378]: time="2025-05-24T05:07:13+08:00" level=trace msg="received dealer pong" May 24 05:07:14 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 05:07:14 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 05:07:14 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 24 05:07:16 volumio volumio[1173]: info: browseTIDALUri took 1614 milliseconds May 24 05:07:16 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:16 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:16 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/4125126 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/202785858 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/202785857 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/325453513 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/62095775 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/62095773 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/13853799 May 24 05:07:16 volumio volumio[1173]: info: Preloading song: tidal://song/13852630 May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/4125126 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/202785858 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/202785857 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/325453513 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/62095775 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/62095773 in service tidal May 24 05:07:16 volumio volumio[1173]: info: Exploding uri tidal://song/13853799 in service tidal May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 298 milliseconds May 24 05:07:17 volumio volumio[1173]: info: Exploding uri tidal://song/13852630 in service tidal May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 304 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 297 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 519 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 828 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 769 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 803 milliseconds May 24 05:07:17 volumio volumio[1173]: info: explodeTIDALUri took 722 milliseconds May 24 05:07:23 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::ClearQueue May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::stop May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::clearPlayQueue May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::saveQueue May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::volumioPushQueue May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::addQueueItems May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::addQueueItems May 24 05:07:23 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/4125126 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/4125126 May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::volumioPushQueue May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::saveQueue May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::updateTrackBlock May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::getTrackBlock May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::volumioPlay May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::play index 0 May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::addQueueItems May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::addQueueItems May 24 05:07:23 volumio volumio[1173]: info: Preload queue cleared May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/202785858 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/202785858 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/202785857 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/202785857 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/325453513 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/325453513 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/62095775 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/62095775 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/62095773 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/62095773 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/13853799 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/13853799 May 24 05:07:23 volumio volumio[1173]: info: Adding Item to queue: tidal://song/13852630 May 24 05:07:23 volumio volumio[1173]: info: Using cached record of: tidal://song/13852630 May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::stop May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::volumioPushQueue May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::saveQueue May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::play index undefined May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::updateTrackBlock May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::getTrackBlock May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::startPlaybackTimer May 24 05:07:23 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:23 volumio volumio[1173]: info: [1748034443711] ControllerTidal::clearAddPlayTrack May 24 05:07:23 volumio volumio[1173]: info: Getting stream with soundQuality HI_RES May 24 05:07:23 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand stop May 24 05:07:23 volumio volumio[1173]: info: sendMpdCommand stop took 8 milliseconds May 24 05:07:23 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand clear May 24 05:07:23 volumio volumio[1173]: info: May 24 05:07:23 volumio volumio[1173]: ---------------------------- MPD announces system playlist update May 24 05:07:23 volumio volumio[1173]: info: Ignoring MPD Status Update May 24 05:07:23 volumio volumio[1173]: info: sendMpdCommand clear took 1 milliseconds May 24 05:07:23 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/4125126&soundQuality=HI_RES" May 24 05:07:23 volumio volumio[1173]: error: updateQueue error: null May 24 05:07:23 volumio volumio[1173]: info: May 24 05:07:23 volumio volumio[1173]: ---------------------------- MPD announces system playlist update May 24 05:07:23 volumio volumio[1173]: info: Ignoring MPD Status Update May 24 05:07:23 volumio volumio[1173]: info: ------------------------------ 4ms May 24 05:07:23 volumio volumio[1173]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/4125126&soundQuality=HI_RES" took 2 milliseconds May 24 05:07:23 volumio volumio[1173]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 05:07:23 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand play May 24 05:07:23 volumio volumio[1173]: info: ------------------------------ 4ms May 24 05:07:23 volumio volumio[1173]: info: sendMpdCommand play took 3 milliseconds May 24 05:07:23 volumio volumio[1173]: STREAMING PROXY: Handling url /?data=tidal://song/4125126&soundQuality=HI_RES May 24 05:07:23 volumio volumio[1173]: info: Executing endpoint getStreamUrltidal May 24 05:07:23 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 24 05:07:24 volumio volumio[1173]: info: getStreamUrl took 363 milliseconds May 24 05:07:24 volumio volumio[1173]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEidlYTE3Y2NjYjE4MDVhZWQ0MzNmZmRjNzE2YzAzNzhjY182MS5tcDQ/0.flac?Expires=1748038044&Signature=woO63WCsJQ0OzpnwjPpKHxDRDSrUwsHBxGKClqA-oGrr3axWAIgoI3J2PVxQnayBoJ8t2jYdVQR0wUiFmhowyN4EhbXT5~LnhEYmUNLMJAMPqVoMGFRhGI4~PTs4jbUu71OJAijzRk2NlPt3AlDR4unG7P4iQj4dXNfJcQ0PJ1n495rrdufY8dyjlm4I4eVQORwYIU2fcpnlaWw7UPqgk9uUEcncGOKgrRFWavf-h1CDxOZbNLItCGSUk8kYtlLGsj0lmuicRQFMHcrXvOf18J7S2s5Tz~F-A~rhYUsA-9FNI7j5ouRRbB1qMOL9x0v~wsXFQA4-dUChE7QLoLmeBA__&Key-Pair-Id=K14LZCZ9QUI4JL May 24 05:07:24 volumio volumio[1173]: STREAMING PROXY: Response: 200, length: 85467568 May 24 05:07:24 volumio volumio[1173]: info: May 24 05:07:24 volumio volumio[1173]: ---------------------------- MPD announces state update: player May 24 05:07:24 volumio volumio[1173]: info: ControllerMpd::getState May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand status May 24 05:07:24 volumio volumio[1173]: info: May 24 05:07:24 volumio volumio[1173]: ---------------------------- MPD announces state update: player May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand clearerror May 24 05:07:24 volumio volumio[1173]: info: sendMpdCommand status took 25 milliseconds May 24 05:07:24 volumio volumio[1173]: info: ControllerMpd::getState May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand status May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::parseState May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 05:07:24 volumio volumio[1173]: info: sendMpdCommand clearerror took 5 milliseconds May 24 05:07:24 volumio volumio[1173]: info: sendMpdCommand status took 8 milliseconds May 24 05:07:24 volumio volumio[1173]: info: sendMpdCommand playlistinfo took 7 milliseconds May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::parseState May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::parseTrackInfo May 24 05:07:24 volumio volumio[1173]: info: ControllerMpd::pushState May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::servicePushState May 24 05:07:24 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:24 volumio volumio[1173]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":783,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4125126&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4125126&soundQuality=HI_RES","trackType":"tidal"} May 24 05:07:24 volumio volumio[1173]: verbose: CURRENT POSITION 0 May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::syncState stateService pause May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus stop May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::pushState May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState May 24 05:07:24 volumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device May 24 05:07:24 volumio volumio[1173]: info: MRS: Pushing multiroomSync output May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:24 volumio volumio[1173]: info: ------------------------------ 81ms May 24 05:07:24 volumio volumio[1173]: info: sendMpdCommand playlistinfo took 44 milliseconds May 24 05:07:24 volumio volumio[1173]: verbose: ControllerMpd::parseTrackInfo May 24 05:07:24 volumio volumio[1173]: info: ControllerMpd::pushState May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::servicePushState May 24 05:07:24 volumio volumio[1173]: info: CorePlayQueue::getTrack 0 May 24 05:07:24 volumio volumio[1173]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":783,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4125126&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4125126&soundQuality=HI_RES","trackType":"tidal"} May 24 05:07:24 volumio volumio[1173]: verbose: CURRENT POSITION 0 May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::syncState stateService pause May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::syncState currentStatus stop May 24 05:07:24 volumio volumio[1173]: info: CoreStateMachine::pushState May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::volumioPushState May 24 05:07:24 volumio volumio[1173]: info: MRS: Pushing multiroomSync output update for this device May 24 05:07:24 volumio volumio[1173]: info: MRS: Pushing multiroomSync output May 24 05:07:24 volumio volumio[1173]: info: CoreCommandRouter::volumioGetState May 24 05:07:24 volumio volumio[1173]: info: ------------------------------ 99ms May 24 05:07:24 volumio volumio[1173]: info: [ASDebug] CurState: PrevState: stop May 24 05:07:24 volumio volumio[1173]: info: [ASDebug] Togle GPIO: ON May 24 05:07:24 volumio volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 05:07:24 volumio volumio[1173]: Error: ESPIPE: invalid seek, write May 24 05:07:24 volumio volumio[1173]: at Object.writeSync (fs.js:711:3) May 24 05:07:24 volumio volumio[1173]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 24 05:07:24 volumio volumio[1173]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) May 24 05:07:24 volumio volumio[1173]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) May 24 05:07:24 volumio volumio[1173]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 24 05:07:24 volumio volumio[1173]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) May 24 05:07:24 volumio volumio[1173]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) May 24 05:07:24 volumio volumio[1173]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 24 05:07:24 volumio volumio[1173]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 24 05:07:24 volumio volumio[1173]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) May 24 05:07:24 volumio volumio[1173]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 24 05:07:24 volumio volumio[1173]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 24 05:07:24 volumio volumio[1173]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) May 24 05:07:24 volumio volumio[1173]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) May 24 05:07:24 volumio volumio[1173]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 24 05:07:24 volumio volumio[1173]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { May 24 05:07:24 volumio volumio[1173]: errno: -29, May 24 05:07:24 volumio volumio[1173]: syscall: 'write', May 24 05:07:24 volumio volumio[1173]: code: 'ESPIPE' May 24 05:07:24 volumio volumio[1173]: } May 24 05:07:24 volumio volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 05:07:24 volumio sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 05:06 May 24 05:07:24 volumio sudo[1999]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"