-- Logs begin at Wed 2024-09-18 00:06:54 KST, end at Sun 2024-10-06 13:23:04 KST. -- Oct 06 13:22:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2690, instance 1, run: true Oct 06 13:22:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2698, instance 1, run: true Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 13:22:00 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2705, instance 1, run: true Oct 06 13:22:01 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:01 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:01 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:01 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2712, instance 1, run: true Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2720, instance 1, run: true Oct 06 13:22:01 volumioworking volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Engine version: 3 Transport: polling Total Clients: 16 Oct 06 13:22:01 volumioworking volumio[2645]: Traceback (most recent call last): Oct 06 13:22:01 volumioworking volumio[2645]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 13:22:01 volumioworking volumio[2645]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 13:22:01 volumioworking volumio[2645]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 13:22:01 volumioworking volumio[2645]: resource, hurry_interval_in_seconds, **kw) Oct 06 13:22:01 volumioworking volumio[2645]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 13:22:01 volumioworking volumio[2645]: self._transport Oct 06 13:22:01 volumioworking volumio[2645]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 13:22:01 volumioworking volumio[2645]: self._engineIO_session = self._get_engineIO_session() Oct 06 13:22:01 volumioworking volumio[2645]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 13:22:01 volumioworking volumio[2645]: transport.recv_packet()) Oct 06 13:22:01 volumioworking volumio[2645]: StopIteration Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2727, instance 1, run: true Oct 06 13:22:01 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:01 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2734, instance 1, run: true Oct 06 13:22:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2741, instance 1, run: true Oct 06 13:22:01 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:01 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 13. Oct 06 13:22:01 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:02 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2749, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:02 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:02 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:02 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2757, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2764, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2771, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2778, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2785, instance 1, run: true Oct 06 13:22:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2792, instance 1, run: true Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:03 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2799, instance 1, run: true Oct 06 13:22:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2806, instance 1, run: true Oct 06 13:22:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2813, instance 1, run: true Oct 06 13:22:03 volumioworking volumio[1137]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 06 13:22:03 volumioworking volumio[1137]: info: Completed starting MyVolumio Plugin Oct 06 13:22:03 volumioworking volumio[1137]: info: BOOT COMPLETED Oct 06 13:22:03 volumioworking volumio[1137]: [Metrics] CommandRouter: 93s 451.17ms Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumiosetStartupVolume Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 13:22:03 volumioworking volumio[1137]: info: VolumeController:: Setting startup Volume 75 Oct 06 13:22:03 volumioworking volumio[1137]: info: VolumeController::SetAlsaVolume75 Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::Close All Modals sent Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::Close All Modals sent Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:03 volumioworking volumio[1137]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 13:22:03 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 06 13:22:03 volumioworking volumio[1137]: SPOTIFY: SPOTIFY VOLUME undefined Oct 06 13:22:03 volumioworking volumio[1137]: SPOTIFY: VOLUMIO VOLUME 75 Oct 06 13:22:03 volumioworking volumio[1137]: info: Aligning Spotify Volume to Volumio Volume Oct 06 13:22:03 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:03 volumioworking volumio[1137]: info: Setting Spotify Volume from Volumio: 75 Oct 06 13:22:03 volumioworking volumio[1137]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 06 13:22:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2824, instance 1, run: true Oct 06 13:22:03 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 06 13:22:03 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 06 13:22:03 volumioworking volumio[1137]: info: camilladsp stopping service pid 2824... Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp service terminated, instance 1 Oct 06 13:22:04 volumioworking volumio[1137]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp service started and running in background, instance 1 Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2827, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:04 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2834, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2841, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2848, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2855, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[2748]: Traceback (most recent call last): Oct 06 13:22:04 volumioworking volumio[2748]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:04 volumioworking volumio[2748]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:04 volumioworking volumio[2748]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:04 volumioworking volumio[2748]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:04 volumioworking volumio[2748]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:04 volumioworking volumio[2748]: DISP.display(img4) Oct 06 13:22:04 volumioworking volumio[2748]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:04 volumioworking volumio[2748]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:04 volumioworking volumio[2748]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:04 volumioworking volumio[2748]: self.send(data, True) Oct 06 13:22:04 volumioworking volumio[2748]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:04 volumioworking volumio[2748]: self._spi.xfer(data[start:end]) Oct 06 13:22:04 volumioworking volumio[2748]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:04 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:04 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:04 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2862, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2869, instance 1, run: true Oct 06 13:22:04 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:04 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2876, instance 1, run: true Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 13:22:04 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 06 13:22:04 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2883, instance 1, run: true Oct 06 13:22:04 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:04 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 14. Oct 06 13:22:04 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:05 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:05 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2891, instance 1, run: true Oct 06 13:22:05 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2898, instance 1, run: true Oct 06 13:22:05 volumioworking volumio[1137]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Oct 06 13:22:05 volumioworking volumio[1137]: info: Sending Spotify command with payload to local API: /player/volume Oct 06 13:22:05 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2905, instance 1, run: true Oct 06 13:22:05 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2913, instance 1, run: true Oct 06 13:22:06 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2920, instance 1, run: true Oct 06 13:22:06 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2927, instance 1, run: true Oct 06 13:22:06 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2934, instance 1, run: true Oct 06 13:22:06 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2941, instance 1, run: true Oct 06 13:22:06 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2948, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2955, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2962, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2969, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2976, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:07 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:07 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:07 volumioworking volumio[2890]: Traceback (most recent call last): Oct 06 13:22:07 volumioworking volumio[2890]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 13:22:07 volumioworking volumio[2890]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 13:22:07 volumioworking volumio[2890]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 13:22:07 volumioworking volumio[2890]: resource, hurry_interval_in_seconds, **kw) Oct 06 13:22:07 volumioworking volumio[2890]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 13:22:07 volumioworking volumio[2890]: self._transport Oct 06 13:22:07 volumioworking volumio[2890]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 13:22:07 volumioworking volumio[2890]: self._engineIO_session = self._get_engineIO_session() Oct 06 13:22:07 volumioworking volumio[2890]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 13:22:07 volumioworking volumio[2890]: transport.recv_packet()) Oct 06 13:22:07 volumioworking volumio[2890]: StopIteration Oct 06 13:22:07 volumioworking volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2983, instance 1, run: true Oct 06 13:22:07 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:07 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2990, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 2999, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3006, instance 1, run: true Oct 06 13:22:07 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3013, instance 1, run: true Oct 06 13:22:07 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:07 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 15. Oct 06 13:22:07 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:08 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3021, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3028, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3035, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3042, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:08 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:08 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:08 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3049, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3056, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3063, instance 1, run: true Oct 06 13:22:08 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3070, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3077, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3084, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3091, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3098, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:09 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:09 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:09 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3105, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3112, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3119, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3126, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3133, instance 1, run: true Oct 06 13:22:09 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3140, instance 1, run: true Oct 06 13:22:10 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3147, instance 1, run: true Oct 06 13:22:10 volumioworking volumio[3020]: Traceback (most recent call last): Oct 06 13:22:10 volumioworking volumio[3020]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:10 volumioworking volumio[3020]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:10 volumioworking volumio[3020]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:10 volumioworking volumio[3020]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:10 volumioworking volumio[3020]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:10 volumioworking volumio[3020]: DISP.display(img4) Oct 06 13:22:10 volumioworking volumio[3020]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:10 volumioworking volumio[3020]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:10 volumioworking volumio[3020]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:10 volumioworking volumio[3020]: self.send(data, True) Oct 06 13:22:10 volumioworking volumio[3020]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:10 volumioworking volumio[3020]: self._spi.xfer(data[start:end]) Oct 06 13:22:10 volumioworking volumio[3020]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:10 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:10 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:10 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:10 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3154, instance 1, run: true Oct 06 13:22:10 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:10 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:10 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3161, instance 1, run: true Oct 06 13:22:10 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:10 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 16. Oct 06 13:22:10 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:10 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:10 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3169, instance 1, run: true Oct 06 13:22:10 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3189, instance 1, run: true Oct 06 13:22:11 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3217, instance 1, run: true Oct 06 13:22:11 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3231, instance 1, run: true Oct 06 13:22:11 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3238, instance 1, run: true Oct 06 13:22:11 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3245, instance 1, run: true Oct 06 13:22:11 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3252, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3259, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3266, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:12 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:12 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:12 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3273, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3280, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3287, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3294, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3301, instance 1, run: true Oct 06 13:22:12 volumioworking volumio[3168]: Traceback (most recent call last): Oct 06 13:22:12 volumioworking volumio[3168]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:12 volumioworking volumio[3168]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:12 volumioworking volumio[3168]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:12 volumioworking volumio[3168]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:12 volumioworking volumio[3168]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:12 volumioworking volumio[3168]: DISP.display(img4) Oct 06 13:22:12 volumioworking volumio[3168]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:12 volumioworking volumio[3168]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:12 volumioworking volumio[3168]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:12 volumioworking volumio[3168]: self.send(data, True) Oct 06 13:22:12 volumioworking volumio[3168]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:12 volumioworking volumio[3168]: self._spi.xfer(data[start:end]) Oct 06 13:22:12 volumioworking volumio[3168]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:12 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:12 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:12 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3308, instance 1, run: true Oct 06 13:22:13 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:13 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3315, instance 1, run: true Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3322, instance 1, run: true Oct 06 13:22:13 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:13 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 17. Oct 06 13:22:13 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3330, instance 1, run: true Oct 06 13:22:13 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3337, instance 1, run: true Oct 06 13:22:13 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3344, instance 1, run: true Oct 06 13:22:14 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3351, instance 1, run: true Oct 06 13:22:14 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3358, instance 1, run: true Oct 06 13:22:14 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3365, instance 1, run: true Oct 06 13:22:14 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3372, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3379, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3386, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:15 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:15 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:15 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3393, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3400, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1283]: ..........................................................................................................................................................................................................................................................................................++++ Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3407, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3414, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3421, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Engine version: 3 Transport: polling Total Clients: 18 Oct 06 13:22:15 volumioworking volumio[3329]: Traceback (most recent call last): Oct 06 13:22:15 volumioworking volumio[3329]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 13:22:15 volumioworking volumio[3329]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 13:22:15 volumioworking volumio[3329]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 13:22:15 volumioworking volumio[3329]: resource, hurry_interval_in_seconds, **kw) Oct 06 13:22:15 volumioworking volumio[3329]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 13:22:15 volumioworking volumio[3329]: self._transport Oct 06 13:22:15 volumioworking volumio[3329]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 13:22:15 volumioworking volumio[3329]: self._engineIO_session = self._get_engineIO_session() Oct 06 13:22:15 volumioworking volumio[3329]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 13:22:15 volumioworking volumio[3329]: transport.recv_packet()) Oct 06 13:22:15 volumioworking volumio[3329]: StopIteration Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3428, instance 1, run: true Oct 06 13:22:15 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3435, instance 1, run: true Oct 06 13:22:16 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:16 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3442, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3449, instance 1, run: true Oct 06 13:22:16 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:16 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 18. Oct 06 13:22:16 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3456, instance 1, run: true Oct 06 13:22:16 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3464, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:16 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:16 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:16 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3471, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3478, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3485, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3492, instance 1, run: true Oct 06 13:22:16 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3499, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3506, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3513, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3520, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3527, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:17 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:17 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:17 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3534, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3541, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3548, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3555, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3562, instance 1, run: true Oct 06 13:22:17 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3569, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3576, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3583, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3590, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3597, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[3457]: Traceback (most recent call last): Oct 06 13:22:18 volumioworking volumio[3457]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:18 volumioworking volumio[3457]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:18 volumioworking volumio[3457]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:18 volumioworking volumio[3457]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:18 volumioworking volumio[3457]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:18 volumioworking volumio[3457]: DISP.display(img4) Oct 06 13:22:18 volumioworking volumio[3457]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:18 volumioworking volumio[3457]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:18 volumioworking volumio[3457]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:18 volumioworking volumio[3457]: self.send(data, True) Oct 06 13:22:18 volumioworking volumio[3457]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:18 volumioworking volumio[3457]: self._spi.xfer(data[start:end]) Oct 06 13:22:18 volumioworking volumio[3457]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:18 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:18 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:18 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3604, instance 1, run: true Oct 06 13:22:18 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:18 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3611, instance 1, run: true Oct 06 13:22:18 volumioworking volumio[1137]: info: VolumeController::SetAlsaVolume82 Oct 06 13:22:18 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:18 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 19. Oct 06 13:22:18 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:18 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:18 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:18 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:18 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:18 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:18 volumioworking volumio[1137]: SPOTIFY: SPOTIFY VOLUME 75 Oct 06 13:22:18 volumioworking volumio[1137]: SPOTIFY: VOLUMIO VOLUME 82 Oct 06 13:22:18 volumioworking volumio[1137]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 06 13:22:18 volumioworking volumio[1137]: info: Setting Spotify Volume from Volumio: 82 Oct 06 13:22:18 volumioworking volumio[1137]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 06 13:22:18 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:18 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3620, instance 1, run: true Oct 06 13:22:19 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3627, instance 1, run: true Oct 06 13:22:19 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3634, instance 1, run: true Oct 06 13:22:19 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3641, instance 1, run: true Oct 06 13:22:19 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3648, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3655, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: SPOTIFY: SETTING SPOTIFY VOLUME 82 Oct 06 13:22:20 volumioworking volumio[1137]: info: Sending Spotify command with payload to local API: /player/volume Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3662, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3669, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:20 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:20 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:20 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3676, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3683, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3690, instance 1, run: true Oct 06 13:22:20 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3697, instance 1, run: true Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3704, instance 1, run: true Oct 06 13:22:21 volumioworking volumio[3619]: Traceback (most recent call last): Oct 06 13:22:21 volumioworking volumio[3619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:21 volumioworking volumio[3619]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:21 volumioworking volumio[3619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:21 volumioworking volumio[3619]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:21 volumioworking volumio[3619]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:21 volumioworking volumio[3619]: DISP.display(img4) Oct 06 13:22:21 volumioworking volumio[3619]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:21 volumioworking volumio[3619]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:21 volumioworking volumio[3619]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:21 volumioworking volumio[3619]: self.send(data, True) Oct 06 13:22:21 volumioworking volumio[3619]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:21 volumioworking volumio[3619]: self._spi.xfer(data[start:end]) Oct 06 13:22:21 volumioworking volumio[3619]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:21 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:21 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:21 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3711, instance 1, run: true Oct 06 13:22:21 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:21 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3718, instance 1, run: true Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3725, instance 1, run: true Oct 06 13:22:21 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:21 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 20. Oct 06 13:22:21 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3733, instance 1, run: true Oct 06 13:22:21 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:21 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3740, instance 1, run: true Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreCommandRouter::volumioStop Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::stop Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::stPlaybackTimer Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::updateTrackBlock Oct 06 13:22:21 volumioworking volumio[1137]: info: CorePlayQueue::getTrackBlock Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:21 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreStateMachine::serviceStop Oct 06 13:22:21 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:21 volumioworking volumio[1137]: info: CoreCommandRouter::serviceStop Oct 06 13:22:21 volumioworking volumio[1137]: info: [1728188541928] ControllerWebradio::stop Oct 06 13:22:21 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 13:22:21 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:21 volumioworking volumio[1137]: info: Oct 06 13:22:21 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:21 volumioworking volumio[1137]: info: sendMpdCommand stop took 56 milliseconds Oct 06 13:22:21 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:21 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:22 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3747, instance 1, run: true Oct 06 13:22:22 volumioworking volumio[1137]: info: sendMpdCommand status took 101 milliseconds Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:22 volumioworking volumio[1137]: info: Oct 06 13:22:22 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:22 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:22 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 06 13:22:22 volumioworking volumio[1137]: info: sendMpdCommand status took 2 milliseconds Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:22 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:22 volumioworking volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 06 13:22:22 volumioworking volumio[1137]: info: ------------------------------ 221ms Oct 06 13:22:22 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 90 milliseconds Oct 06 13:22:22 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:22 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:22 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:22 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:22 volumioworking volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 06 13:22:22 volumioworking volumio[1137]: info: ------------------------------ 151ms Oct 06 13:22:22 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:22 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:22 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3754, instance 1, run: true Oct 06 13:22:22 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3761, instance 1, run: true Oct 06 13:22:22 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3768, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3775, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3782, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3789, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3796, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3803, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3810, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3817, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3824, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3831, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3838, instance 1, run: true Oct 06 13:22:23 volumioworking volumio[3732]: Traceback (most recent call last): Oct 06 13:22:23 volumioworking volumio[3732]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:23 volumioworking volumio[3732]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:23 volumioworking volumio[3732]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:23 volumioworking volumio[3732]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:23 volumioworking volumio[3732]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:23 volumioworking volumio[3732]: DISP.display(img4) Oct 06 13:22:23 volumioworking volumio[3732]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:23 volumioworking volumio[3732]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:23 volumioworking volumio[3732]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:23 volumioworking volumio[3732]: self.send(data, True) Oct 06 13:22:23 volumioworking volumio[3732]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:23 volumioworking volumio[3732]: self._spi.xfer(data[start:end]) Oct 06 13:22:23 volumioworking volumio[3732]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:23 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:23 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:23 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3845, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3852, instance 1, run: true Oct 06 13:22:24 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:24 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3859, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3866, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3873, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3880, instance 1, run: true Oct 06 13:22:24 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:24 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 21. Oct 06 13:22:24 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:24 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3888, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3895, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3902, instance 1, run: true Oct 06 13:22:24 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3909, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3916, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3923, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3930, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3937, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3944, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3951, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3958, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3965, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3973, instance 1, run: true Oct 06 13:22:25 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3980, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3987, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 3994, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4001, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4008, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4015, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4022, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[3887]: Traceback (most recent call last): Oct 06 13:22:26 volumioworking volumio[3887]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:26 volumioworking volumio[3887]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:26 volumioworking volumio[3887]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:26 volumioworking volumio[3887]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:26 volumioworking volumio[3887]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:26 volumioworking volumio[3887]: DISP.display(img4) Oct 06 13:22:26 volumioworking volumio[3887]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:26 volumioworking volumio[3887]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:26 volumioworking volumio[3887]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:26 volumioworking volumio[3887]: self.send(data, True) Oct 06 13:22:26 volumioworking volumio[3887]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:26 volumioworking volumio[3887]: self._spi.xfer(data[start:end]) Oct 06 13:22:26 volumioworking volumio[3887]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:26 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:26 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:26 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4029, instance 1, run: true Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4036, instance 1, run: true Oct 06 13:22:26 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:26 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:26 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4043, instance 1, run: true Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4050, instance 1, run: true Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4057, instance 1, run: true Oct 06 13:22:27 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:27 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 22. Oct 06 13:22:27 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4064, instance 1, run: true Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPlay Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::play index undefined Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 13:22:27 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::startPlaybackTimer Oct 06 13:22:27 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:27 volumioworking volumio[1137]: info: [1728188547250] ControllerWebradio::clearAddPlayTrack Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand stop took 2 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:27 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand clear took 1 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:27 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:27 volumioworking volumio[1137]: error: updateQueue error: null Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 20ms Oct 06 13:22:27 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:27 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" took 58 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 42ms Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:27 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 25ms Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand play took 7 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 7ms Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4074, instance 1, run: true Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4081, instance 1, run: true Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:27 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Oct 06 13:22:27 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate from file: 192000 Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp stopping service pid 4081... Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp service terminated, instance 1 Oct 06 13:22:27 volumioworking volumio[1137]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 06 13:22:27 volumioworking volumio[1137]: info: camilladsp service started and running in background, instance 1 Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand status took 141 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand status took 137 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:27 volumioworking volumio[1137]: info: Oct 06 13:22:27 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand status took 26 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: info: sendMpdCommand status took 20 milliseconds Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:27 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:27 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"201 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:27 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Oct 06 13:22:27 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:27 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"201 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:27 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:27 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:27 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 256ms Oct 06 13:22:27 volumioworking volumio[1137]: info: ------------------------------ 268ms Oct 06 13:22:28 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4091, instance 1, run: true Oct 06 13:22:28 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 165 milliseconds Oct 06 13:22:28 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 164 milliseconds Oct 06 13:22:28 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:28 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:28 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"201 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:28 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:28 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:28 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:28 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"201 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:28 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:28 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:28 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:28 volumioworking volumio[1137]: info: ------------------------------ 408ms Oct 06 13:22:28 volumioworking volumio[1137]: info: ------------------------------ 376ms Oct 06 13:22:28 volumioworking volumio[1137]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Oct 06 13:22:28 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4098, instance 1, run: true Oct 06 13:22:28 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4105, instance 1, run: true Oct 06 13:22:28 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4113, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4120, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:29 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:29 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:29 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4127, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4134, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1283]: ...........................................................................................++++ Oct 06 13:22:29 volumioworking volumio[1283]: e is 65537 (0x010001) Oct 06 13:22:29 volumioworking volumio[1283]: writing RSA key Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4142, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4153, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4162, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4170, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[4065]: Traceback (most recent call last): Oct 06 13:22:29 volumioworking volumio[4065]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:29 volumioworking volumio[4065]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:29 volumioworking volumio[4065]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:29 volumioworking volumio[4065]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:29 volumioworking volumio[4065]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:29 volumioworking volumio[4065]: DISP.display(img4) Oct 06 13:22:29 volumioworking volumio[4065]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:29 volumioworking volumio[4065]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:29 volumioworking volumio[4065]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:29 volumioworking volumio[4065]: self.send(data, True) Oct 06 13:22:29 volumioworking volumio[4065]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:29 volumioworking volumio[4065]: self._spi.xfer(data[start:end]) Oct 06 13:22:29 volumioworking volumio[4065]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:29 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:29 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:29 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4177, instance 1, run: true Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4184, instance 1, run: true Oct 06 13:22:29 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:29 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:29 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4191, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4198, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4205, instance 1, run: true Oct 06 13:22:30 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4212, instance 1, run: true Oct 06 13:22:30 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 23. Oct 06 13:22:30 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:30 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4220, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4227, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4234, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4241, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4248, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4255, instance 1, run: true Oct 06 13:22:30 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4262, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4269, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4276, instance 1, run: true Oct 06 13:22:31 volumioworking ntpd[900]: Soliciting pool server 2001:678:8::123 Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4283, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4290, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4297, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4304, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4311, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4318, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4325, instance 1, run: true Oct 06 13:22:31 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:31 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:31 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:31 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4332, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4339, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4346, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4353, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4360, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4367, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[4213]: Traceback (most recent call last): Oct 06 13:22:32 volumioworking volumio[4213]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:32 volumioworking volumio[4213]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:32 volumioworking volumio[4213]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:32 volumioworking volumio[4213]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:32 volumioworking volumio[4213]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:32 volumioworking volumio[4213]: DISP.display(img4) Oct 06 13:22:32 volumioworking volumio[4213]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:32 volumioworking volumio[4213]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:32 volumioworking volumio[4213]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:32 volumioworking volumio[4213]: self.send(data, True) Oct 06 13:22:32 volumioworking volumio[4213]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:32 volumioworking volumio[4213]: self._spi.xfer(data[start:end]) Oct 06 13:22:32 volumioworking volumio[4213]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:32 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:32 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:32 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4374, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4381, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4388, instance 1, run: true Oct 06 13:22:32 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:32 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4395, instance 1, run: true Oct 06 13:22:32 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4402, instance 1, run: true Oct 06 13:22:32 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:32 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 24. Oct 06 13:22:32 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:33 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4410, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4417, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4424, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4431, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4438, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4445, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4452, instance 1, run: true Oct 06 13:22:33 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4459, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4466, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4473, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4480, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4487, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4494, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4501, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4508, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:34 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:34 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:34 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4515, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4522, instance 1, run: true Oct 06 13:22:34 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4529, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4536, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4543, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4550, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4557, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[4409]: Traceback (most recent call last): Oct 06 13:22:35 volumioworking volumio[4409]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:35 volumioworking volumio[4409]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:35 volumioworking volumio[4409]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:35 volumioworking volumio[4409]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:35 volumioworking volumio[4409]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:35 volumioworking volumio[4409]: DISP.display(img4) Oct 06 13:22:35 volumioworking volumio[4409]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:35 volumioworking volumio[4409]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:35 volumioworking volumio[4409]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:35 volumioworking volumio[4409]: self.send(data, True) Oct 06 13:22:35 volumioworking volumio[4409]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:35 volumioworking volumio[4409]: self._spi.xfer(data[start:end]) Oct 06 13:22:35 volumioworking volumio[4409]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:35 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:35 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:35 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4564, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4571, instance 1, run: true Oct 06 13:22:35 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:35 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4578, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4585, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4592, instance 1, run: true Oct 06 13:22:35 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:35 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 25. Oct 06 13:22:35 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:35 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:35 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:35 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:35 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:35 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4600, instance 1, run: true Oct 06 13:22:35 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4607, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4614, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4621, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4628, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4635, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4642, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4649, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4656, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4663, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4670, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:36 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:36 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4677, instance 1, run: true Oct 06 13:22:36 volumioworking volumio[1137]: info: VolumeController::SetAlsaVolume93 Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:36 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:36 volumioworking volumio[1137]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 13:22:36 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:36 volumioworking volumio[1137]: SPOTIFY: SPOTIFY VOLUME 82 Oct 06 13:22:36 volumioworking volumio[1137]: SPOTIFY: VOLUMIO VOLUME 93 Oct 06 13:22:36 volumioworking volumio[1137]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 06 13:22:36 volumioworking volumio[1137]: info: Setting Spotify Volume from Volumio: 93 Oct 06 13:22:36 volumioworking volumio[1137]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4685, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4692, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4699, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4706, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4713, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4720, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4727, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4734, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4741, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4748, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[4599]: Traceback (most recent call last): Oct 06 13:22:37 volumioworking volumio[4599]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:37 volumioworking volumio[4599]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:37 volumioworking volumio[4599]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:37 volumioworking volumio[4599]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:37 volumioworking volumio[4599]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:37 volumioworking volumio[4599]: DISP.display(img4) Oct 06 13:22:37 volumioworking volumio[4599]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:37 volumioworking volumio[4599]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:37 volumioworking volumio[4599]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:37 volumioworking volumio[4599]: self.send(data, True) Oct 06 13:22:37 volumioworking volumio[4599]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:37 volumioworking volumio[4599]: self._spi.xfer(data[start:end]) Oct 06 13:22:37 volumioworking volumio[4599]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:37 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:37 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:37 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4755, instance 1, run: true Oct 06 13:22:37 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4762, instance 1, run: true Oct 06 13:22:38 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:38 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4769, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4776, instance 1, run: true Oct 06 13:22:38 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:38 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 26. Oct 06 13:22:38 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4783, instance 1, run: true Oct 06 13:22:38 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4791, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4798, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: SPOTIFY: SETTING SPOTIFY VOLUME 93 Oct 06 13:22:38 volumioworking volumio[1137]: info: Sending Spotify command with payload to local API: /player/volume Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4805, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4812, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4819, instance 1, run: true Oct 06 13:22:38 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4826, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4833, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4840, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4847, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4854, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4861, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4868, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4875, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4882, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4889, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4896, instance 1, run: true Oct 06 13:22:39 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:39 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:39 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:39 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4903, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4910, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4917, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4924, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4931, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4938, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[4784]: Traceback (most recent call last): Oct 06 13:22:40 volumioworking volumio[4784]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:40 volumioworking volumio[4784]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:40 volumioworking volumio[4784]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:40 volumioworking volumio[4784]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:40 volumioworking volumio[4784]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:40 volumioworking volumio[4784]: DISP.display(img4) Oct 06 13:22:40 volumioworking volumio[4784]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:40 volumioworking volumio[4784]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:40 volumioworking volumio[4784]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:40 volumioworking volumio[4784]: self.send(data, True) Oct 06 13:22:40 volumioworking volumio[4784]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:40 volumioworking volumio[4784]: self._spi.xfer(data[start:end]) Oct 06 13:22:40 volumioworking volumio[4784]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:40 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:40 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:40 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4945, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4952, instance 1, run: true Oct 06 13:22:40 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:40 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4959, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4966, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4973, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4980, instance 1, run: true Oct 06 13:22:40 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:40 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:40 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:40 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:40 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:40 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 27. Oct 06 13:22:40 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4988, instance 1, run: true Oct 06 13:22:41 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 4995, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5002, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5009, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5016, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5023, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5030, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5037, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5044, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5051, instance 1, run: true Oct 06 13:22:41 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:41 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:41 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:41 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:41 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5058, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5065, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5072, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5079, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5086, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5093, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5100, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5107, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5114, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5121, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5128, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5135, instance 1, run: true Oct 06 13:22:42 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:42 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:42 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:42 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5142, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 06 13:22:43 volumioworking volumio[4987]: Traceback (most recent call last): Oct 06 13:22:43 volumioworking volumio[4987]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 13:22:43 volumioworking volumio[4987]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 13:22:43 volumioworking volumio[4987]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 13:22:43 volumioworking volumio[4987]: resource, hurry_interval_in_seconds, **kw) Oct 06 13:22:43 volumioworking volumio[4987]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 13:22:43 volumioworking volumio[4987]: self._transport Oct 06 13:22:43 volumioworking volumio[4987]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 13:22:43 volumioworking volumio[4987]: self._engineIO_session = self._get_engineIO_session() Oct 06 13:22:43 volumioworking volumio[4987]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 13:22:43 volumioworking volumio[4987]: transport.recv_packet()) Oct 06 13:22:43 volumioworking volumio[4987]: StopIteration Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5149, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5156, instance 1, run: true Oct 06 13:22:43 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:43 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5163, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5170, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5177, instance 1, run: true Oct 06 13:22:43 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:43 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 28. Oct 06 13:22:43 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5185, instance 1, run: true Oct 06 13:22:43 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5192, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5199, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5206, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5213, instance 1, run: true Oct 06 13:22:43 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:43 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:43 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:43 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:43 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5220, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5227, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5234, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5241, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5248, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5255, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5262, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5269, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5276, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5283, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5290, instance 1, run: true Oct 06 13:22:44 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:44 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:44 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:44 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:44 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5297, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5304, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5311, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5318, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5325, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5332, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5339, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5346, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[5184]: Traceback (most recent call last): Oct 06 13:22:45 volumioworking volumio[5184]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:45 volumioworking volumio[5184]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:45 volumioworking volumio[5184]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:45 volumioworking volumio[5184]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:45 volumioworking volumio[5184]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:45 volumioworking volumio[5184]: DISP.display(img4) Oct 06 13:22:45 volumioworking volumio[5184]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:45 volumioworking volumio[5184]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:45 volumioworking volumio[5184]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:45 volumioworking volumio[5184]: self.send(data, True) Oct 06 13:22:45 volumioworking volumio[5184]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:45 volumioworking volumio[5184]: self._spi.xfer(data[start:end]) Oct 06 13:22:45 volumioworking volumio[5184]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:45 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:45 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:45 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5353, instance 1, run: true Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5360, instance 1, run: true Oct 06 13:22:45 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:45 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:45 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5368, instance 1, run: true Oct 06 13:22:45 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:45 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 29. Oct 06 13:22:45 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5375, instance 1, run: true Oct 06 13:22:46 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5383, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5390, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5397, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5404, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5411, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5418, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5425, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5432, instance 1, run: true Oct 06 13:22:46 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5439, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5446, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5453, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5460, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5467, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5474, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5481, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5488, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:47 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5495, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5502, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5509, instance 1, run: true Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::volumioStop Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::stop Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::stPlaybackTimer Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::updateTrackBlock Oct 06 13:22:47 volumioworking volumio[1137]: info: CorePlayQueue::getTrackBlock Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:47 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreStateMachine::serviceStop Oct 06 13:22:47 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:47 volumioworking volumio[1137]: info: CoreCommandRouter::serviceStop Oct 06 13:22:47 volumioworking volumio[1137]: info: [1728188567953] ControllerWebradio::stop Oct 06 13:22:47 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 13:22:48 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:48 volumioworking volumio[1137]: info: Oct 06 13:22:48 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:48 volumioworking volumio[1137]: info: sendMpdCommand stop took 63 milliseconds Oct 06 13:22:48 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:48 volumioworking volumio[1137]: info: sendMpdCommand status took 1 milliseconds Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:48 volumioworking volumio[1137]: info: Oct 06 13:22:48 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:48 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5516, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 62 milliseconds Oct 06 13:22:48 volumioworking volumio[1137]: info: sendMpdCommand status took 51 milliseconds Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:48 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:48 volumioworking volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 06 13:22:48 volumioworking volumio[1137]: info: ------------------------------ 91ms Oct 06 13:22:48 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 06 13:22:48 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:48 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:48 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:48 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:48 volumioworking volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 06 13:22:48 volumioworking volumio[1137]: info: ------------------------------ 116ms Oct 06 13:22:48 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:48 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5523, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[5376]: Traceback (most recent call last): Oct 06 13:22:48 volumioworking volumio[5376]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:48 volumioworking volumio[5376]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:48 volumioworking volumio[5376]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:48 volumioworking volumio[5376]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:48 volumioworking volumio[5376]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:48 volumioworking volumio[5376]: DISP.display(img4) Oct 06 13:22:48 volumioworking volumio[5376]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:48 volumioworking volumio[5376]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:48 volumioworking volumio[5376]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:48 volumioworking volumio[5376]: self.send(data, True) Oct 06 13:22:48 volumioworking volumio[5376]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:48 volumioworking volumio[5376]: self._spi.xfer(data[start:end]) Oct 06 13:22:48 volumioworking volumio[5376]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:48 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:48 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:48 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5530, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5537, instance 1, run: true Oct 06 13:22:48 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:48 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5544, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5551, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5558, instance 1, run: true Oct 06 13:22:48 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:48 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 30. Oct 06 13:22:48 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5566, instance 1, run: true Oct 06 13:22:48 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5573, instance 1, run: true Oct 06 13:22:48 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5580, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5587, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5594, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5601, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5608, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5615, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5622, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5629, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5636, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5643, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPlay Oct 06 13:22:49 volumioworking volumio[1137]: info: CoreStateMachine::play index undefined Oct 06 13:22:49 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 13:22:49 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:49 volumioworking volumio[1137]: info: CoreStateMachine::startPlaybackTimer Oct 06 13:22:49 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:49 volumioworking volumio[1137]: info: [1728188569800] ControllerWebradio::clearAddPlayTrack Oct 06 13:22:49 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 13:22:49 volumioworking volumio[1137]: info: sendMpdCommand stop took 1 milliseconds Oct 06 13:22:49 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 13:22:49 volumioworking volumio[1137]: info: Oct 06 13:22:49 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:49 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:49 volumioworking volumio[1137]: info: sendMpdCommand clear took 2 milliseconds Oct 06 13:22:49 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" Oct 06 13:22:49 volumioworking volumio[1137]: info: Oct 06 13:22:49 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:49 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:49 volumioworking volumio[1137]: error: updateQueue error: null Oct 06 13:22:49 volumioworking volumio[1137]: info: ------------------------------ 5ms Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5651, instance 1, run: true Oct 06 13:22:49 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5658, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5665, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5672, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5679, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5686, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5693, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5701, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5708, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: Oct 06 13:22:50 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:50 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:50 volumioworking volumio[1137]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" took 787 milliseconds Oct 06 13:22:50 volumioworking volumio[1137]: info: ------------------------------ 786ms Oct 06 13:22:50 volumioworking volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 13:22:50 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Oct 06 13:22:50 volumioworking volumio[1137]: info: Oct 06 13:22:50 volumioworking volumio[1137]: ---------------------------- MPD announces system playlist update Oct 06 13:22:50 volumioworking volumio[1137]: info: Ignoring MPD Status Update Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5717, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: ------------------------------ 62ms Oct 06 13:22:50 volumioworking volumio[1137]: info: sendMpdCommand play took 57 milliseconds Oct 06 13:22:50 volumioworking volumio[1137]: info: ------------------------------ 57ms Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5724, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5731, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[5565]: Traceback (most recent call last): Oct 06 13:22:50 volumioworking volumio[5565]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:50 volumioworking volumio[5565]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:50 volumioworking volumio[5565]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:50 volumioworking volumio[5565]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:50 volumioworking volumio[5565]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:50 volumioworking volumio[5565]: DISP.display(img4) Oct 06 13:22:50 volumioworking volumio[5565]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:50 volumioworking volumio[5565]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:50 volumioworking volumio[5565]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:50 volumioworking volumio[5565]: self.send(data, True) Oct 06 13:22:50 volumioworking volumio[5565]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:50 volumioworking volumio[5565]: self._spi.xfer(data[start:end]) Oct 06 13:22:50 volumioworking volumio[5565]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:50 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:50 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:50 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:50 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5738, instance 1, run: true Oct 06 13:22:50 volumioworking volumio[1137]: info: Oct 06 13:22:50 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:50 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:50 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:50 volumioworking volumio[1137]: info: Oct 06 13:22:50 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:50 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:50 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:51 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Oct 06 13:22:51 volumioworking volumio[1137]: info: FusionDsp - ---- read samplerate from file: 192000 Oct 06 13:22:51 volumioworking volumio[1137]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 06 13:22:51 volumioworking volumio[1137]: info: Oct 06 13:22:51 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand status took 64 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand status took 61 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:51 volumioworking volumio[1137]: info: Oct 06 13:22:51 volumioworking volumio[1137]: ---------------------------- MPD announces state update: player Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::getState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand status took 19 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand status took 7 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseState Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:51 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:51 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:51 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:51 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:51 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: ------------------------------ 166ms Oct 06 13:22:51 volumioworking volumio[1137]: info: ------------------------------ 186ms Oct 06 13:22:51 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:51 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 100 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: info: sendMpdCommand playlistinfo took 94 milliseconds Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:51 volumioworking volumio[1137]: verbose: ControllerMpd::parseTrackInfo Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:51 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:51 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:51 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: ControllerMpd::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::servicePushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CorePlayQueue::getTrack 0 Oct 06 13:22:51 volumioworking volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":346,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"196 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728361147&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzYxMTQ3fX19XX0_&Signature=QUxtXsV0X-tDiq~GlO68ZhHVkhXJbm-IpV-m1KTQSxVad3TGvxDs81oI-4XOl6AFOynjm4sJJXNSmFq5XWyQJtIJ-9ZF8UCccx-tBjiHJdR7TDBsTkaJADhiDRCGfPIaYMEK3SvqICm2a1kNQ1DixMm5SUtRz4JS4Hxiff16jiNr5O5mGtJsOaXni8vho5TewhmdnRiEyIEhqfR~qjMzofcW2aOywHQdoBk6mwqnyGQt6IMbyP3Sf37aXMBybIHedisbY8HgR~aprfKv2amg9rTuIJlH6-LbKYDwO89n1s8n0YM097e2kBncs9n6QGH6Cp8PZtplaGIitm4hNbok3w__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 13:22:51 volumioworking volumio[1137]: verbose: CURRENT POSITION 0 Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState stateService play Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::syncState currentStatus play Oct 06 13:22:51 volumioworking volumio[1137]: info: Received an update from plugin. extracting info from payload Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreStateMachine::pushState Oct 06 13:22:51 volumioworking volumio[1137]: info: CoreCommandRouter::volumioPushState Oct 06 13:22:51 volumioworking volumio[1137]: info: ------------------------------ 199ms Oct 06 13:22:51 volumioworking volumio[1137]: info: ------------------------------ 175ms Oct 06 13:22:51 volumioworking volumio[1137]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93 Oct 06 13:22:51 volumioworking volumio[1137]: error: FusionDsp - WebSocket error: [object Object] Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5746, instance 1, run: true Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5753, instance 1, run: true Oct 06 13:22:51 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:51 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 31. Oct 06 13:22:51 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:51 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5761, instance 1, run: true Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5768, instance 1, run: true Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5775, instance 1, run: true Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5782, instance 1, run: true Oct 06 13:22:51 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5789, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5796, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5803, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5810, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5817, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5824, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5831, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5838, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5845, instance 1, run: true Oct 06 13:22:52 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5852, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5859, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5866, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:53 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:53 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:53 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5873, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5880, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5887, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5894, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5901, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5908, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[5760]: Traceback (most recent call last): Oct 06 13:22:53 volumioworking volumio[5760]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:53 volumioworking volumio[5760]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:53 volumioworking volumio[5760]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:53 volumioworking volumio[5760]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:53 volumioworking volumio[5760]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:53 volumioworking volumio[5760]: DISP.display(img4) Oct 06 13:22:53 volumioworking volumio[5760]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:53 volumioworking volumio[5760]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:53 volumioworking volumio[5760]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:53 volumioworking volumio[5760]: self.send(data, True) Oct 06 13:22:53 volumioworking volumio[5760]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:53 volumioworking volumio[5760]: self._spi.xfer(data[start:end]) Oct 06 13:22:53 volumioworking volumio[5760]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:53 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:53 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:53 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5915, instance 1, run: true Oct 06 13:22:53 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5922, instance 1, run: true Oct 06 13:22:53 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:53 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5929, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5936, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5943, instance 1, run: true Oct 06 13:22:54 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:54 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 32. Oct 06 13:22:54 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:54 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5951, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5958, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5965, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5972, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5979, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5986, instance 1, run: true Oct 06 13:22:54 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 5993, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6000, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6007, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6014, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6021, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6028, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6035, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6042, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6049, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6056, instance 1, run: true Oct 06 13:22:55 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:55 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:55 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:55 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6063, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6070, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6077, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6084, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.31.0 Engine version: 3 Transport: polling Total Clients: 17 Oct 06 13:22:56 volumioworking volumio[5950]: Traceback (most recent call last): Oct 06 13:22:56 volumioworking volumio[5950]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 13:22:56 volumioworking volumio[5950]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 13:22:56 volumioworking volumio[5950]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 13:22:56 volumioworking volumio[5950]: resource, hurry_interval_in_seconds, **kw) Oct 06 13:22:56 volumioworking volumio[5950]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 13:22:56 volumioworking volumio[5950]: self._transport Oct 06 13:22:56 volumioworking volumio[5950]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 13:22:56 volumioworking volumio[5950]: self._engineIO_session = self._get_engineIO_session() Oct 06 13:22:56 volumioworking volumio[5950]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 13:22:56 volumioworking volumio[5950]: transport.recv_packet()) Oct 06 13:22:56 volumioworking volumio[5950]: StopIteration Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6091, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6098, instance 1, run: true Oct 06 13:22:56 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:56 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6105, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6112, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6119, instance 1, run: true Oct 06 13:22:56 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:56 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 33. Oct 06 13:22:56 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:56 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6127, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6134, instance 1, run: true Oct 06 13:22:56 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:56 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:56 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:56 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6141, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6148, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6155, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6162, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6169, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6176, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6183, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6190, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6197, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6204, instance 1, run: true Oct 06 13:22:57 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:22:57 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:22:57 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:22:57 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:22:57 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6211, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6218, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6225, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6232, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6239, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6246, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6253, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6260, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6267, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6274, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6281, instance 1, run: true Oct 06 13:22:58 volumioworking volumio[6126]: Traceback (most recent call last): Oct 06 13:22:58 volumioworking volumio[6126]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:22:58 volumioworking volumio[6126]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:22:58 volumioworking volumio[6126]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:22:58 volumioworking volumio[6126]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:22:58 volumioworking volumio[6126]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:22:58 volumioworking volumio[6126]: DISP.display(img4) Oct 06 13:22:58 volumioworking volumio[6126]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:22:58 volumioworking volumio[6126]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:22:58 volumioworking volumio[6126]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:22:58 volumioworking volumio[6126]: self.send(data, True) Oct 06 13:22:58 volumioworking volumio[6126]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:22:58 volumioworking volumio[6126]: self._spi.xfer(data[start:end]) Oct 06 13:22:58 volumioworking volumio[6126]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:22:58 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:22:58 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:22:58 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:22:58 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6288, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6295, instance 1, run: true Oct 06 13:22:59 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:22:59 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6302, instance 1, run: true Oct 06 13:22:59 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6309, instance 1, run: true Oct 06 13:22:59 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 34. Oct 06 13:22:59 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:22:59 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6317, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6324, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6331, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6338, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6345, instance 1, run: true Oct 06 13:22:59 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6352, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6359, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6366, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6373, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6380, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6387, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6394, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6401, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6408, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6415, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6422, instance 1, run: true Oct 06 13:23:00 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:23:00 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:23:00 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:23:00 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:23:00 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6429, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6436, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6443, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6450, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6457, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6464, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[6310]: Traceback (most recent call last): Oct 06 13:23:01 volumioworking volumio[6310]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:23:01 volumioworking volumio[6310]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:23:01 volumioworking volumio[6310]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:23:01 volumioworking volumio[6310]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:23:01 volumioworking volumio[6310]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:23:01 volumioworking volumio[6310]: DISP.display(img4) Oct 06 13:23:01 volumioworking volumio[6310]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:23:01 volumioworking volumio[6310]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:23:01 volumioworking volumio[6310]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:23:01 volumioworking volumio[6310]: self.send(data, True) Oct 06 13:23:01 volumioworking volumio[6310]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:23:01 volumioworking volumio[6310]: self._spi.xfer(data[start:end]) Oct 06 13:23:01 volumioworking volumio[6310]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:23:01 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:23:01 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:23:01 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6471, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6478, instance 1, run: true Oct 06 13:23:01 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:23:01 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6485, instance 1, run: true Oct 06 13:23:01 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:23:01 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 35. Oct 06 13:23:01 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6493, instance 1, run: true Oct 06 13:23:01 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6500, instance 1, run: true Oct 06 13:23:01 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:23:01 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:23:01 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:23:01 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:23:01 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6507, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6514, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6521, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6528, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6535, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6542, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6549, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6556, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6563, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6570, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6577, instance 1, run: true Oct 06 13:23:02 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetState Oct 06 13:23:02 volumioworking volumio[1137]: info: CoreCommandRouter::volumioGetQueue Oct 06 13:23:02 volumioworking volumio[1137]: info: CoreStateMachine::getQueue Oct 06 13:23:02 volumioworking volumio[1137]: info: CorePlayQueue::getQueue Oct 06 13:23:02 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6584, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6591, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6598, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6605, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6612, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6619, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6626, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6633, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: camilladsp spawned new process with pid 6640, instance 1, run: true Oct 06 13:23:03 volumioworking volumio[1137]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Oct 06 13:23:03 volumioworking volumio[1137]: info: PLUGIN onShutdown : networkfs Oct 06 13:23:03 volumioworking volumio[1137]: info: PLUGIN onShutdown : audiophonicsonoff Oct 06 13:23:03 volumioworking volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 13:23:03 volumioworking volumio[1137]: TypeError: Cannot read property 'writeSync' of undefined Oct 06 13:23:03 volumioworking volumio[1137]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Oct 06 13:23:03 volumioworking volumio[1137]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Oct 06 13:23:03 volumioworking volumio[1137]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Oct 06 13:23:03 volumioworking volumio[1137]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 06 13:23:03 volumioworking volumio[1137]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 06 13:23:03 volumioworking volumio[1137]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Oct 06 13:23:03 volumioworking volumio[1137]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1319:24) Oct 06 13:23:03 volumioworking volumio[1137]: at /data/plugins/system_hardware/remotepi/index.js:91:35 Oct 06 13:23:03 volumioworking volumio[1137]: at /data/plugins/system_hardware/remotepi/node_modules/onoff/onoff.js:135:9 Oct 06 13:23:03 volumioworking volumio[1137]: at Array.forEach () Oct 06 13:23:03 volumioworking volumio[1137]: at pollerEventHandler (/data/plugins/system_hardware/remotepi/node_modules/onoff/onoff.js:134:32) Oct 06 13:23:03 volumioworking volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 13:23:03 volumioworking volumio[6492]: Traceback (most recent call last): Oct 06 13:23:03 volumioworking volumio[6492]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 13:23:03 volumioworking volumio[6492]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 13:23:03 volumioworking volumio[6492]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 13:23:03 volumioworking volumio[6492]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 13:23:03 volumioworking volumio[6492]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 13:23:03 volumioworking volumio[6492]: DISP.display(img4) Oct 06 13:23:03 volumioworking volumio[6492]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 13:23:03 volumioworking volumio[6492]: self.data(pixelbytes[i:i + 4096]) Oct 06 13:23:03 volumioworking volumio[6492]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 13:23:03 volumioworking volumio[6492]: self.send(data, True) Oct 06 13:23:03 volumioworking volumio[6492]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 13:23:03 volumioworking volumio[6492]: self._spi.xfer(data[start:end]) Oct 06 13:23:03 volumioworking volumio[6492]: TimeoutError: [Errno 110] Connection timed out Oct 06 13:23:03 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 13:23:03 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 13:23:03 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 13:23:04 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 13:23:04 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 13:23:04 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 13:23:04 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 36. Oct 06 13:23:04 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 13:23:04 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 13:23:04 volumioworking sudo[6658]: volumio : unable to resolve host volumioworking Oct 06 13:23:04 volumioworking sudo[6658]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 13:23:04 volumioworking sudo[6658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 13:22 Oct 06 13:23:04 volumioworking sudo[6658]: 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"