-- Logs begin at Thu 2024-10-31 09:29:26 CET, end at Sat 2024-11-02 16:26:53 CET. -- Nov 02 16:26:30 volumio volumio[1038]: info: Volumio BT Module successfully started Nov 02 16:26:30 volumio ntpd[865]: receive: Unexpected origin timestamp 0xeacdbeb6.b69b439b does not match aorg 0000000000.00000000 from server@93.94.88.51 xmt 0xead0c326.f034e191 Nov 02 16:26:30 volumio ntpd[865]: receive: Unexpected origin timestamp 0xeacdbeb6.b6a36cc8 does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xead0c326.f0d18b8d Nov 02 16:26:30 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 02 16:26:31 volumio systemd[1]: Starting Daily apt download activities... Nov 02 16:26:31 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 16:26:31 volumio volumio[1038]: info: Discovery: A device disappeared from network Nov 02 16:26:31 volumio volumio[1038]: info: Discovery: Device volumio disappeared from network Nov 02 16:26:31 volumio volumiobt[1573]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 02 16:26:31 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Nov 02 16:26:31 volumio volumio[1038]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 16:26:31 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 16:26:31 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Nov 02 16:26:31 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Nov 02 16:26:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:31 volumio volumio[1038]: info: Discovery: adding 5b9e0645-cab6-43c8-bba5-26296f453eec Nov 02 16:26:31 volumio volumio[1038]: info: Discovery: Found device Volumio Nov 02 16:26:31 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Nov 02 16:26:31 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:31 volumio volumio[1038]: info: TidalConnect service stoped! Nov 02 16:26:31 volumio volumio[1038]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 02 16:26:31 volumio volumio[1038]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 02 16:26:31 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 02 16:26:31 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 16:26:31 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 02 16:26:31 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Nov 02 16:26:31 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:31 volumio volumio[1564]: Traceback (most recent call last): Nov 02 16:26:31 volumio volumio[1564]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:31 volumio volumio[1564]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:31 volumio volumio[1564]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:31 volumio volumio[1564]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:31 volumio volumio[1564]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:31 volumio volumio[1564]: self._transport Nov 02 16:26:31 volumio volumio[1564]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:31 volumio volumio[1564]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:31 volumio volumio[1564]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:31 volumio volumio[1564]: transport.recv_packet()) Nov 02 16:26:31 volumio volumio[1564]: StopIteration Nov 02 16:26:32 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:32 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:32 volumio sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 02 16:26:32 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 16:26:32 volumio volumiobt[1573]: pulseaudio: no process found Nov 02 16:26:32 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Nov 02 16:26:32 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:32 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 13. Nov 02 16:26:32 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:32 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:32 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1628 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 02 16:26:32 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 02 16:26:32 volumio volumio[1038]: info: Executing endpoint tc_getconfig Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 02 16:26:32 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 02 16:26:32 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Successfully called chroot. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Successfully dropped privileges. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Successfully limited resources. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Running. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Canary thread running. Nov 02 16:26:32 volumio rtkit-daemon[1629]: Watchdog thread running. Nov 02 16:26:32 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1629 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 02 16:26:32 volumio vtcs[1617]: STARTING TidalConnect services, version: 1.4.0.34 Nov 02 16:26:32 volumio volumio[1038]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 16:26:32 volumio systemd[1]: Starting Authorization Manager... Nov 02 16:26:32 volumio volumio[1038]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 16:26:32 volumio vtcs[1617]: STARTED TidalConnect services. Nov 02 16:26:32 volumio volumio[1038]: info: Executing endpoint tc_connect Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 02 16:26:32 volumio volumio[1038]: info: Connecting to TidalConnect Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Nov 02 16:26:32 volumio volumio[1038]: info: CoreStateMachine::pushState Nov 02 16:26:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Nov 02 16:26:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:32 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Nov 02 16:26:32 volumio volumio[1038]: info: CoreStateMachine::pushState Nov 02 16:26:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:32 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Nov 02 16:26:32 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:32 volumio volumio[1038]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Nov 02 16:26:32 volumio polkitd[1632]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 02 16:26:32 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 02 16:26:32 volumio systemd[1]: Started Authorization Manager. Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Getting audio outputs on start Nov 02 16:26:32 volumio volumio[1038]: info: MRS: Requesting all other devices output Nov 02 16:26:32 volumio volumio[1038]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Nov 02 16:26:32 volumio volumio[1038]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Nov 02 16:26:32 volumio volumio[1038]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.EkEofo69nJWbMEgbZn59cFo1ZiH2.7c6ffadb7fe4ecde34b1590c5a216f95.state.track' Nov 02 16:26:33 volumio pulseaudio[1628]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 02 16:26:33 volumio pulseaudio[1628]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 02 16:26:33 volumio volumiobt[1573]: Applying permissions Nov 02 16:26:33 volumio volumiobt[1573]: Setting BT discoverable and pairable Nov 02 16:26:33 volumio bluetoothd[841]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Nov 02 16:26:33 volumio bluetoothd[841]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Nov 02 16:26:33 volumio bluetoothd[841]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Nov 02 16:26:33 volumio bluetoothd[841]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Nov 02 16:26:33 volumio pulseaudio[1628]: 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 Nov 02 16:26:33 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Nov 02 16:26:33 volumio kernel: Bluetooth: RFCOMM socket layer initialized Nov 02 16:26:33 volumio kernel: Bluetooth: RFCOMM ver 1.11 Nov 02 16:26:33 volumio volumiobt[1573]: [CHG] Controller DC:A6:32:6F:0D:D6 Class: 0x0008041c Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [91B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [117B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [55B blob data] Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# -e power on Nov 02 16:26:33 volumio volumiobt[1573]: Invalid command in menu main: -e Nov 02 16:26:33 volumio volumiobt[1573]: Nov 02 16:26:33 volumio volumiobt[1573]: Use "help" for a list of available commands in a menu. Nov 02 16:26:33 volumio volumiobt[1573]: Use "menu " if you want to enter any submenu. Nov 02 16:26:33 volumio volumiobt[1573]: Use "back" if you want to return to menu main. Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# agent on Nov 02 16:26:33 volumio volumiobt[1573]: Agent is already registered Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# discoverable on Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# pairable on Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# agent NoInputNoOutput Nov 02 16:26:33 volumio volumiobt[1573]: Agent is already registered Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# default-agent Nov 02 16:26:33 volumio volumiobt[1573]: [bluetooth]# quit Nov 02 16:26:33 volumio volumiobt[1573]: [59B blob data] Nov 02 16:26:34 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:34 volumio volumio[1625]: Traceback (most recent call last): Nov 02 16:26:34 volumio volumio[1625]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:34 volumio volumio[1625]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:34 volumio volumio[1625]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:34 volumio volumio[1625]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:34 volumio volumio[1625]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:34 volumio volumio[1625]: self._transport Nov 02 16:26:34 volumio volumio[1625]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:34 volumio volumio[1625]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:34 volumio volumio[1625]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:34 volumio volumio[1625]: transport.recv_packet()) Nov 02 16:26:34 volumio volumio[1625]: StopIteration Nov 02 16:26:34 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:34 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:34 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:34 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 14. Nov 02 16:26:34 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:34 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:34 volumio volumio[1038]: info: TidalConnect service started! Nov 02 16:26:34 volumio volumio[1038]: info: BOOT COMPLETED Nov 02 16:26:34 volumio volumio[1038]: [Metrics] CommandRouter: 56s 511.10ms Nov 02 16:26:34 volumio volumio[1038]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 16:26:34 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:26:34 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 16:26:34 volumio volumio[1038]: info: CoreCommandRouter::Close All Modals sent Nov 02 16:26:34 volumio volumio[1038]: info: CoreCommandRouter::Close All Modals sent Nov 02 16:26:34 volumio systemd[1]: apt-daily.service: Succeeded. Nov 02 16:26:34 volumio systemd[1]: Started Daily apt download activities. Nov 02 16:26:34 volumio volumio[1038]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 16:26:34 volumio volumio[1038]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 16:26:34 volumio volumio[1038]: info: camilladsp stopping service pid 1311... Nov 02 16:26:35 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Nov 02 16:26:35 volumio volumio[1038]: info: camilladsp service terminated, instance 1 Nov 02 16:26:35 volumio volumio[1038]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 16:26:35 volumio volumio[1038]: info: camilladsp service started and running in background, instance 1 Nov 02 16:26:35 volumio volumio[1038]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 16:26:35 volumio volumio[1038]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 16:26:35 volumio volumio[1038]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 16:26:35 volumio volumio[1038]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 02 16:26:35 volumio volumio[1038]: error: FusionDsp - WebSocket error: [object Object] Nov 02 16:26:35 volumio volumio[1038]: info: camilladsp spawned new process with pid 1699, instance 1, run: true Nov 02 16:26:35 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 16:26:35 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 16:26:35 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 16:26:36 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 16:26:36 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:36 volumio volumio[1683]: Traceback (most recent call last): Nov 02 16:26:36 volumio volumio[1683]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:36 volumio volumio[1683]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:36 volumio volumio[1683]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:36 volumio volumio[1683]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:36 volumio volumio[1683]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:36 volumio volumio[1683]: self._transport Nov 02 16:26:36 volumio volumio[1683]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:36 volumio volumio[1683]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:36 volumio volumio[1683]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:36 volumio volumio[1683]: transport.recv_packet()) Nov 02 16:26:36 volumio volumio[1683]: StopIteration Nov 02 16:26:36 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:36 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:36 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:36 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 15. Nov 02 16:26:36 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:37 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 02 16:26:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Nov 02 16:26:38 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:38 volumio volumio[1726]: Traceback (most recent call last): Nov 02 16:26:38 volumio volumio[1726]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:38 volumio volumio[1726]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:38 volumio volumio[1726]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:38 volumio volumio[1726]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:38 volumio volumio[1726]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:38 volumio volumio[1726]: self._transport Nov 02 16:26:38 volumio volumio[1726]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:38 volumio volumio[1726]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:38 volumio volumio[1726]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:38 volumio volumio[1726]: transport.recv_packet()) Nov 02 16:26:38 volumio volumio[1726]: StopIteration Nov 02 16:26:38 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:38 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:39 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:39 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 16. Nov 02 16:26:39 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:39 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:40 volumio volumio[1038]: info: camilladsp spawned new process with pid 1753, instance 1, run: true Nov 02 16:26:40 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:40 volumio volumio[1752]: Traceback (most recent call last): Nov 02 16:26:40 volumio volumio[1752]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:40 volumio volumio[1752]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:40 volumio volumio[1752]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:40 volumio volumio[1752]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:40 volumio volumio[1752]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:40 volumio volumio[1752]: self._transport Nov 02 16:26:40 volumio volumio[1752]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:40 volumio volumio[1752]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:40 volumio volumio[1752]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:40 volumio volumio[1752]: transport.recv_packet()) Nov 02 16:26:40 volumio volumio[1752]: StopIteration Nov 02 16:26:41 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:41 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:41 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:41 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 17. Nov 02 16:26:41 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:41 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:42 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 19 Nov 02 16:26:42 volumio volumio[1760]: Traceback (most recent call last): Nov 02 16:26:42 volumio volumio[1760]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:42 volumio volumio[1760]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:42 volumio volumio[1760]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:42 volumio volumio[1760]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:42 volumio volumio[1760]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:42 volumio volumio[1760]: self._transport Nov 02 16:26:42 volumio volumio[1760]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:42 volumio volumio[1760]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:42 volumio volumio[1760]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:42 volumio volumio[1760]: transport.recv_packet()) Nov 02 16:26:42 volumio volumio[1760]: StopIteration Nov 02 16:26:43 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:43 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:43 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:43 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 18. Nov 02 16:26:43 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:43 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:44 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 20 Nov 02 16:26:44 volumio volumio[1761]: Traceback (most recent call last): Nov 02 16:26:44 volumio volumio[1761]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:44 volumio volumio[1761]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:44 volumio volumio[1761]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:44 volumio volumio[1761]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:44 volumio volumio[1761]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:44 volumio volumio[1761]: self._transport Nov 02 16:26:44 volumio volumio[1761]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:44 volumio volumio[1761]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:44 volumio volumio[1761]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:44 volumio volumio[1761]: transport.recv_packet()) Nov 02 16:26:44 volumio volumio[1761]: StopIteration Nov 02 16:26:45 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:45 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:45 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:45 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 19. Nov 02 16:26:45 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:45 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:46 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 21 Nov 02 16:26:46 volumio volumio[1762]: Traceback (most recent call last): Nov 02 16:26:46 volumio volumio[1762]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:46 volumio volumio[1762]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:46 volumio volumio[1762]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:46 volumio volumio[1762]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:46 volumio volumio[1762]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:46 volumio volumio[1762]: self._transport Nov 02 16:26:46 volumio volumio[1762]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:46 volumio volumio[1762]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:46 volumio volumio[1762]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:46 volumio volumio[1762]: transport.recv_packet()) Nov 02 16:26:46 volumio volumio[1762]: StopIteration Nov 02 16:26:47 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:47 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:47 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:47 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 20. Nov 02 16:26:47 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:47 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:48 volumio volumio[1763]: Traceback (most recent call last): Nov 02 16:26:48 volumio volumio[1763]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:48 volumio volumio[1763]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:48 volumio volumio[1763]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:48 volumio volumio[1763]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:48 volumio volumio[1763]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:48 volumio volumio[1763]: self._transport Nov 02 16:26:48 volumio volumio[1763]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:48 volumio volumio[1763]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:48 volumio volumio[1763]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:48 volumio volumio[1763]: transport.recv_packet()) Nov 02 16:26:48 volumio volumio[1763]: StopIteration Nov 02 16:26:48 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 21 Nov 02 16:26:49 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:49 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:49 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:49 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 21. Nov 02 16:26:49 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:49 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:49 volumio volumio[1038]: info: CoreCommandRouter::volumioPlay Nov 02 16:26:49 volumio volumio[1038]: info: CoreStateMachine::play index undefined Nov 02 16:26:49 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 16:26:49 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:49 volumio volumio[1038]: info: CoreStateMachine::startPlaybackTimer Nov 02 16:26:49 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:49 volumio volumio[1038]: info: [1730561209316] ControllerQobuz::clearAddPlayTrack Nov 02 16:26:49 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 16:26:49 volumio volumio[1038]: info: sendMpdCommand stop took 3 milliseconds Nov 02 16:26:49 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 16:26:49 volumio volumio[1038]: info: Nov 02 16:26:49 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Nov 02 16:26:49 volumio volumio[1038]: info: Ignoring MPD Status Update Nov 02 16:26:49 volumio volumio[1038]: info: sendMpdCommand clear took 4 milliseconds Nov 02 16:26:49 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/4108279" Nov 02 16:26:49 volumio volumio[1038]: error: updateQueue error: null Nov 02 16:26:49 volumio volumio[1038]: info: ------------------------------ 9ms Nov 02 16:26:49 volumio volumio[1038]: STREAMING PROXY: Handling url /?data=qobuz://song/4108279 Nov 02 16:26:49 volumio volumio[1038]: info: Executing endpoint getStreamUrlqobuz Nov 02 16:26:49 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 02 16:26:50 volumio volumio[1038]: info: getStreamUrl took 1011 milliseconds Nov 02 16:26:50 volumio volumio[1038]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1179872&eid=4108279&fmt=7&profile=raw&app_id=539451548&cid=2364769&etsp=1730564810&hmac=jgNuwkgY68n-ao4pNCfN-i4QLNs Nov 02 16:26:50 volumio volumio[1038]: STREAMING PROXY: Response: 200, length: 19866384 Nov 02 16:26:50 volumio volumio[1038]: STREAMING PROXY: Client dropped request, destroying Nov 02 16:26:50 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4108279" Nov 02 16:26:50 volumio volumio[1038]: info: Nov 02 16:26:50 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Nov 02 16:26:50 volumio volumio[1038]: info: Ignoring MPD Status Update Nov 02 16:26:50 volumio volumio[1038]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/4108279" took 3 milliseconds Nov 02 16:26:50 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 16:26:50 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand play Nov 02 16:26:50 volumio volumio[1038]: info: ------------------------------ 8ms Nov 02 16:26:50 volumio volumio[1038]: info: sendMpdCommand play took 4 milliseconds Nov 02 16:26:50 volumio volumio[1038]: STREAMING PROXY: Handling url /?data=qobuz://song/4108279 Nov 02 16:26:50 volumio volumio[1038]: info: Executing endpoint getStreamUrlqobuz Nov 02 16:26:50 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Nov 02 16:26:50 volumio volumio[1038]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 21 Nov 02 16:26:50 volumio volumio[1764]: Traceback (most recent call last): Nov 02 16:26:50 volumio volumio[1764]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Nov 02 16:26:50 volumio volumio[1764]: SOCKETIO = SocketIO('localhost', 3000) Nov 02 16:26:50 volumio volumio[1764]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Nov 02 16:26:50 volumio volumio[1764]: resource, hurry_interval_in_seconds, **kw) Nov 02 16:26:50 volumio volumio[1764]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Nov 02 16:26:50 volumio volumio[1764]: self._transport Nov 02 16:26:50 volumio volumio[1764]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Nov 02 16:26:50 volumio volumio[1764]: self._engineIO_session = self._get_engineIO_session() Nov 02 16:26:50 volumio volumio[1764]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Nov 02 16:26:50 volumio volumio[1764]: transport.recv_packet()) Nov 02 16:26:50 volumio volumio[1764]: StopIteration Nov 02 16:26:51 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 16:26:51 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Nov 02 16:26:51 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 16:26:51 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 22. Nov 02 16:26:51 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Nov 02 16:26:51 volumio systemd[1]: Started Service for using pirate audio display in volumio. Nov 02 16:26:51 volumio volumio[1038]: info: getStreamUrl took 813 milliseconds Nov 02 16:26:51 volumio volumio[1038]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1179872&eid=4108279&fmt=7&profile=raw&app_id=539451548&cid=2364769&etsp=1730564811&hmac=tuN3a45D21WL8LyjsJXL9YbxYpo Nov 02 16:26:51 volumio volumio[1038]: STREAMING PROXY: Response: 200, length: 19866384 Nov 02 16:26:51 volumio volumio[1038]: info: Nov 02 16:26:51 volumio volumio[1038]: ---------------------------- MPD announces state update: player Nov 02 16:26:51 volumio volumio[1038]: info: ControllerMpd::getState Nov 02 16:26:51 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - ---- read samplerate from file: 96000 Nov 02 16:26:51 volumio volumio[1038]: info: camilladsp stopping service pid 1753... Nov 02 16:26:51 volumio volumio[1038]: info: camilladsp service terminated, instance 1 Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 16:26:51 volumio volumio[1038]: info: camilladsp service started and running in background, instance 1 Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - ---- read samplerate from file: 96000 Nov 02 16:26:51 volumio volumio[1038]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 16:26:51 volumio volumio[1038]: info: Nov 02 16:26:51 volumio volumio[1038]: ---------------------------- MPD announces state update: player Nov 02 16:26:51 volumio volumio[1038]: info: sendMpdCommand status took 164 milliseconds Nov 02 16:26:51 volumio volumio[1038]: info: ControllerMpd::getState Nov 02 16:26:51 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand status Nov 02 16:26:51 volumio volumio[1038]: verbose: ControllerMpd::parseState Nov 02 16:26:51 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 16:26:52 volumio volumio[1038]: info: camilladsp spawned new process with pid 1778, instance 1, run: true Nov 02 16:26:52 volumio volumio[1038]: info: sendMpdCommand status took 61 milliseconds Nov 02 16:26:52 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 58 milliseconds Nov 02 16:26:52 volumio volumio[1038]: verbose: ControllerMpd::parseState Nov 02 16:26:52 volumio volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 16:26:52 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Nov 02 16:26:52 volumio volumio[1038]: info: ControllerMpd::pushState Nov 02 16:26:52 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Nov 02 16:26:52 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:52 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":64,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4108279","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4108279","trackType":"qobuz"} Nov 02 16:26:52 volumio volumio[1038]: verbose: CURRENT POSITION 0 Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Nov 02 16:26:52 volumio volumio[1038]: info: ------------------------------ 250ms Nov 02 16:26:52 volumio volumio[1038]: error: FusionDsp - WebSocket error: [object Object] Nov 02 16:26:52 volumio volumio[1038]: info: sendMpdCommand playlistinfo took 36 milliseconds Nov 02 16:26:52 volumio volumio[1038]: verbose: ControllerMpd::parseTrackInfo Nov 02 16:26:52 volumio volumio[1038]: info: ControllerMpd::pushState Nov 02 16:26:52 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Nov 02 16:26:52 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Nov 02 16:26:52 volumio volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":64,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4108279","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/4108279","trackType":"qobuz"} Nov 02 16:26:52 volumio volumio[1038]: verbose: CURRENT POSITION 0 Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Nov 02 16:26:52 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::pushState Nov 02 16:26:52 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 16:26:52 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Nov 02 16:26:52 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Nov 02 16:26:52 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Nov 02 16:26:52 volumio volumio[1038]: info: CoreStateMachine::pushState Nov 02 16:26:52 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Nov 02 16:26:52 volumio volumio[1038]: info: MRS: Pushing multiroomSync output update for this device Nov 02 16:26:52 volumio volumio[1038]: info: MRS: Pushing multiroomSync output Nov 02 16:26:52 volumio volumio[1038]: info: ------------------------------ 225ms Nov 02 16:26:52 volumio volumio[1038]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Nov 02 16:26:52 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 16:26:52 volumio volumio[1038]: TypeError: Cannot read property 'indexOf' of undefined Nov 02 16:26:52 volumio volumio[1038]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) Nov 02 16:26:52 volumio volumio[1038]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) Nov 02 16:26:52 volumio volumio[1038]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) Nov 02 16:26:52 volumio volumio[1038]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Nov 02 16:26:52 volumio volumio[1038]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) Nov 02 16:26:52 volumio volumio[1038]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) Nov 02 16:26:52 volumio volumio[1038]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Nov 02 16:26:52 volumio volumio[1038]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Nov 02 16:26:52 volumio volumio[1038]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) Nov 02 16:26:52 volumio volumio[1038]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Nov 02 16:26:52 volumio volumio[1038]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) Nov 02 16:26:52 volumio volumio[1038]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) Nov 02 16:26:52 volumio volumio[1038]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) Nov 02 16:26:52 volumio volumio[1038]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) Nov 02 16:26:52 volumio volumio[1038]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) Nov 02 16:26:52 volumio volumio[1038]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) Nov 02 16:26:52 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 16:26:53 volumio sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 16:25 Nov 02 16:26:53 volumio sudo[1794]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"