-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2026-02-07 17:53:40 CET. -- Feb 07 17:52:00 volumio RoonBridge[1468]: 00:00:44.984 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Feb 07 17:52:00 volumio RoonBridge[1468]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in :0 Feb 07 17:52:00 volumio RoonBridge[1468]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in :0 Feb 07 17:52:00 volumio RoonBridge[1468]: 00:00:45.020 Debug: PathForResource, filename: ../.update Feb 07 17:52:00 volumio RoonBridge[1468]: 00:00:45.020 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 07 17:52:00 volumio RoonBridge[1468]: Not Running (.o) Feb 07 17:52:01 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:01 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:01 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:01 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:01 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:01 volumio volumio[1029]: info: Setting Geolocation for MyVolumio to eu4 Feb 07 17:52:01 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:52:01 volumio volumio[1408]: .................................................................................++++ Feb 07 17:52:01 volumio volumio[1029]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 07 17:52:01 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:01 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:01 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:01 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:01 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:02 volumio RoonBridge[1468]: 00:00:47.020 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 07 17:52:02 volumio RoonBridge[1468]: Running Feb 07 17:52:02 volumio volumio[1029]: info: Updating MyVolumio device info Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetBrowseSources Feb 07 17:52:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:52:02 volumio kernel: spidev spi0.1: SPI transfer timed out Feb 07 17:52:02 volumio kernel: spi_master spi0: failed to transfer one message from queue Feb 07 17:52:02 volumio kernel: spi_master spi0: noqueue transfer failed Feb 07 17:52:02 volumio volumio[1971]: Traceback (most recent call last): Feb 07 17:52:02 volumio volumio[1971]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Feb 07 17:52:02 volumio volumio[1971]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Feb 07 17:52:02 volumio volumio[1971]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Feb 07 17:52:02 volumio volumio[1971]: sendtodisplay(IMAGE_DICT['IMG3']) Feb 07 17:52:02 volumio volumio[1971]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Feb 07 17:52:02 volumio volumio[1971]: DISP.display(img4) Feb 07 17:52:02 volumio volumio[1971]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Feb 07 17:52:02 volumio volumio[1971]: self.data(pixelbytes[i:i + 4096]) Feb 07 17:52:02 volumio volumio[1971]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Feb 07 17:52:02 volumio volumio[1971]: self.send(data, True) Feb 07 17:52:02 volumio volumio[1971]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Feb 07 17:52:02 volumio volumio[1971]: self._spi.xfer(data[start:end]) Feb 07 17:52:02 volumio volumio[1971]: TimeoutError: [Errno 110] Connection timed out Feb 07 17:52:02 volumio volumio[1029]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 07 17:52:03 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:03 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:03 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:03 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 10. Feb 07 17:52:03 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:03 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:06 volumio volumio[1029]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 07 17:52:06 volumio volumio[1029]: info: Completed starting MyVolumio Plugin Feb 07 17:52:06 volumio volumio[1029]: [Metrics] CommandRouter: 126s 375.42ms Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::volumiosetStartupVolume Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::Close All Modals sent Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::Close All Modals sent Feb 07 17:52:06 volumio volumio[1029]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 07 17:52:06 volumio volumio[1029]: aplay: main:828: audio open error: Device or resource busy Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:06 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:06 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:06 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:06 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:07 volumio kernel: spidev spi0.1: SPI transfer timed out Feb 07 17:52:07 volumio kernel: spi_master spi0: failed to transfer one message from queue Feb 07 17:52:07 volumio kernel: spi_master spi0: noqueue transfer failed Feb 07 17:52:07 volumio volumio[2004]: Traceback (most recent call last): Feb 07 17:52:07 volumio volumio[2004]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Feb 07 17:52:07 volumio volumio[2004]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Feb 07 17:52:07 volumio volumio[2004]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Feb 07 17:52:07 volumio volumio[2004]: sendtodisplay(IMAGE_DICT['IMG3']) Feb 07 17:52:07 volumio volumio[2004]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Feb 07 17:52:07 volumio volumio[2004]: DISP.display(img4) Feb 07 17:52:07 volumio volumio[2004]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Feb 07 17:52:07 volumio volumio[2004]: self.data(pixelbytes[i:i + 4096]) Feb 07 17:52:07 volumio volumio[2004]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Feb 07 17:52:07 volumio volumio[2004]: self.send(data, True) Feb 07 17:52:07 volumio volumio[2004]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Feb 07 17:52:07 volumio volumio[2004]: self._spi.xfer(data[start:end]) Feb 07 17:52:07 volumio volumio[2004]: TimeoutError: [Errno 110] Connection timed out Feb 07 17:52:07 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 07 17:52:07 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 07 17:52:07 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 07 17:52:07 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:07 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:07 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:07 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 11. Feb 07 17:52:07 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:08 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:08 volumio RoonBridge[1468]: 00:00:53.052 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Feb 07 17:52:08 volumio RoonBridge[1468]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in :0 Feb 07 17:52:08 volumio RoonBridge[1468]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in :0 Feb 07 17:52:08 volumio RoonBridge[1468]: Not Running (.o) Feb 07 17:52:08 volumio RoonBridge[1468]: 00:00:53.060 Debug: PathForResource, filename: ../.update Feb 07 17:52:08 volumio RoonBridge[1468]: 00:00:53.061 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Feb 07 17:52:10 volumio RoonBridge[1468]: 00:00:55.070 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Feb 07 17:52:10 volumio RoonBridge[1468]: Running Feb 07 17:52:11 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:11 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces system playlist update Feb 07 17:52:11 volumio volumio[1029]: info: Ignoring MPD Status Update Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces system playlist update Feb 07 17:52:11 volumio volumio[1029]: info: Ignoring MPD Status Update Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces system playlist update Feb 07 17:52:11 volumio volumio[1029]: info: Ignoring MPD Status Update Feb 07 17:52:11 volumio volumio[1029]: info: Feb 07 17:52:11 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:11 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:11 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:11 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 73ms Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand status took 70 milliseconds Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 69ms Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand status took 55 milliseconds Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 54ms Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand status took 51 milliseconds Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 07 17:52:11 volumio volumio[1029]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseTrackInfo Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseTrackInfo Feb 07 17:52:11 volumio volumio[1029]: verbose: ControllerMpd::parseTrackInfo Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:11 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:11 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:11 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:11 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:11 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:11 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:11 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:11 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:11 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:11 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 132ms Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 115ms Feb 07 17:52:11 volumio volumio[1029]: info: ------------------------------ 110ms Feb 07 17:52:11 volumio kernel: spidev spi0.1: SPI transfer timed out Feb 07 17:52:11 volumio kernel: spi_master spi0: failed to transfer one message from queue Feb 07 17:52:11 volumio kernel: spi_master spi0: noqueue transfer failed Feb 07 17:52:11 volumio volumio[2041]: Traceback (most recent call last): Feb 07 17:52:11 volumio volumio[2041]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Feb 07 17:52:11 volumio volumio[2041]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Feb 07 17:52:11 volumio volumio[2041]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Feb 07 17:52:11 volumio volumio[2041]: sendtodisplay(IMAGE_DICT['IMG3']) Feb 07 17:52:11 volumio volumio[2041]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Feb 07 17:52:11 volumio volumio[2041]: DISP.display(img4) Feb 07 17:52:11 volumio volumio[2041]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Feb 07 17:52:11 volumio volumio[2041]: self.data(pixelbytes[i:i + 4096]) Feb 07 17:52:11 volumio volumio[2041]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Feb 07 17:52:11 volumio volumio[2041]: self.send(data, True) Feb 07 17:52:11 volumio volumio[2041]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Feb 07 17:52:11 volumio volumio[2041]: self._spi.xfer(data[start:end]) Feb 07 17:52:11 volumio volumio[2041]: TimeoutError: [Errno 110] Connection timed out Feb 07 17:52:12 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:12 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:12 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:12 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:12 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:12 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:12 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:12 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:12 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 12. Feb 07 17:52:12 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:12 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:13 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:13 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:13 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:13 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:13 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:13 volumio volumio[1029]: info: BOOT COMPLETED Feb 07 17:52:13 volumio volumio[1029]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::volumioPause Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::pause Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::stPlaybackTimer Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::servicePause Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::servicePause Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand pause Feb 07 17:52:14 volumio volumio[1029]: info: mpdhttpout --- Volumio set on pause Feb 07 17:52:14 volumio volumio[1029]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand pause took 4 milliseconds Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music125/v4/ac/2c/c8/ac2cc8c0-7d59-1b0b-8c99-ec96595ced94/source/600x600bb.jpg","name":"Nena - 99 Luftballons","title":"99 Luftballons","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":42451,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 07 17:52:14 volumio volumio[1029]: verbose: CURRENT POSITION 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::syncState stateService pause Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::syncState currentStatus pause Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::pushState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::volumioPushState Feb 07 17:52:14 volumio volumio[1029]: info: Feb 07 17:52:14 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:14 volumio volumio[1029]: info: Feb 07 17:52:14 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:14 volumio volumio[1029]: info: Feb 07 17:52:14 volumio volumio[1029]: ---------------------------- MPD announces state update: player Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::getState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Feb 07 17:52:14 volumio volumio[1408]: ......................................++++ Feb 07 17:52:14 volumio volumio[1408]: e is 65537 (0x010001) Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:52:14 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:52:14 volumio volumio[1029]: info: [ASDebug] CurState: pause PrevState: stop Feb 07 17:52:14 volumio volumio[1029]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand status took 93 milliseconds Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand status took 78 milliseconds Feb 07 17:52:14 volumio volumio[1408]: writing RSA key Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand status took 93 milliseconds Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::parseState Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 07 17:52:14 volumio volumio[1029]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::parseTrackInfo Feb 07 17:52:14 volumio volumio[1029]: verbose: ControllerMpd::parseTrackInfo Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:14 volumio volumio[1029]: info: ControllerMpd::pushState Feb 07 17:52:14 volumio volumio[1029]: info: CoreCommandRouter::servicePushState Feb 07 17:52:14 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:14 volumio volumio[1029]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 07 17:52:14 volumio volumio[1029]: info: ------------------------------ 158ms Feb 07 17:52:14 volumio volumio[1029]: info: ------------------------------ 142ms Feb 07 17:52:14 volumio volumio[1029]: info: ------------------------------ 139ms Feb 07 17:52:16 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 07 17:52:16 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 07 17:52:16 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:16 volumio systemd[1]: musicservicesshield.service: Succeeded. Feb 07 17:52:16 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Feb 07 17:52:16 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Feb 07 17:52:16 volumio systemd[1]: Stopping Music Player Daemon... Feb 07 17:52:16 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:16 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:16 volumio kernel: spidev spi0.1: SPI transfer timed out Feb 07 17:52:16 volumio kernel: spi_master spi0: failed to transfer one message from queue Feb 07 17:52:16 volumio kernel: spi_master spi0: noqueue transfer failed Feb 07 17:52:16 volumio volumio[2064]: Traceback (most recent call last): Feb 07 17:52:16 volumio volumio[2064]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Feb 07 17:52:16 volumio volumio[2064]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Feb 07 17:52:16 volumio volumio[2064]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Feb 07 17:52:16 volumio volumio[2064]: sendtodisplay(IMAGE_DICT['IMG3']) Feb 07 17:52:16 volumio volumio[2064]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Feb 07 17:52:16 volumio volumio[2064]: DISP.display(img4) Feb 07 17:52:16 volumio volumio[2064]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Feb 07 17:52:16 volumio volumio[2064]: self.data(pixelbytes[i:i + 4096]) Feb 07 17:52:16 volumio volumio[2064]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Feb 07 17:52:16 volumio volumio[2064]: self.send(data, True) Feb 07 17:52:16 volumio volumio[2064]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Feb 07 17:52:16 volumio volumio[2064]: self._spi.xfer(data[start:end]) Feb 07 17:52:16 volumio volumio[2064]: TimeoutError: [Errno 110] Connection timed out Feb 07 17:52:16 volumio systemd[1]: mpd.service: Succeeded. Feb 07 17:52:16 volumio systemd[1]: Stopped Music Player Daemon. Feb 07 17:52:16 volumio systemd[1]: Starting Music Player Daemon... Feb 07 17:52:16 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:16 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:17 volumio sudo[2091]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 07 17:52:17 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:17 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:17 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:17 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 13. Feb 07 17:52:17 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:17 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:20 volumio volumio[1029]: 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: 9 Feb 07 17:52:20 volumio volumio[2095]: Traceback (most recent call last): Feb 07 17:52:20 volumio volumio[2095]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:20 volumio volumio[2095]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:20 volumio volumio[2095]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:20 volumio volumio[2095]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:20 volumio volumio[2095]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:20 volumio volumio[2095]: self._transport Feb 07 17:52:20 volumio volumio[2095]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:20 volumio volumio[2095]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:20 volumio volumio[2095]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:20 volumio volumio[2095]: transport.recv_packet()) Feb 07 17:52:20 volumio volumio[2095]: StopIteration Feb 07 17:52:20 volumio mpd[2094]: Feb 07 17:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 07 17:52:20 volumio systemd[1]: Started Music Player Daemon. Feb 07 17:52:20 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:20 volumio volumio[1029]: error: updateQueue error: null Feb 07 17:52:21 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Feb 07 17:52:21 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:21 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:21 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:21 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 14. Feb 07 17:52:21 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:21 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:22 volumio volumio[2119]: cset: --> shielding system active with Feb 07 17:52:22 volumio volumio[2119]: cset: "system" cpuset of CPUSPEC(1-3) with 193 tasks running Feb 07 17:52:22 volumio volumio[2119]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 07 17:52:22 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Feb 07 17:52:22 volumio go-librespot[1603]: time="2026-02-07T17:52:22+01:00" level=trace msg="sent dealer ping" Feb 07 17:52:22 volumio go-librespot[1603]: time="2026-02-07T17:52:22+01:00" level=trace msg="received dealer pong" Feb 07 17:52:24 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 07 17:52:24 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:24 volumio systemd[1]: Stopping MPD Monitor Service... Feb 07 17:52:24 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 07 17:52:24 volumio systemd[1]: mpd_monitor.service: Succeeded. Feb 07 17:52:24 volumio systemd[1]: Stopped MPD Monitor Service. Feb 07 17:52:24 volumio systemd[1]: Started MPD Monitor Service. Feb 07 17:52:24 volumio mpd_monitor.sh[2140]: MPD Monitor Service: Starting MPD Monitor Service Feb 07 17:52:24 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:24 volumio volumio[1029]: info: Successfully started MPD Monitor Feb 07 17:52:24 volumio kernel: spidev spi0.1: SPI transfer timed out Feb 07 17:52:24 volumio kernel: spi_master spi0: failed to transfer one message from queue Feb 07 17:52:24 volumio kernel: spi_master spi0: noqueue transfer failed Feb 07 17:52:24 volumio volumio[2128]: Traceback (most recent call last): Feb 07 17:52:24 volumio volumio[2128]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Feb 07 17:52:24 volumio volumio[2128]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Feb 07 17:52:24 volumio volumio[2128]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Feb 07 17:52:24 volumio volumio[2128]: sendtodisplay(IMAGE_DICT['IMG3']) Feb 07 17:52:24 volumio volumio[2128]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Feb 07 17:52:24 volumio volumio[2128]: DISP.display(img4) Feb 07 17:52:24 volumio volumio[2128]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Feb 07 17:52:24 volumio volumio[2128]: self.data(pixelbytes[i:i + 4096]) Feb 07 17:52:24 volumio volumio[2128]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Feb 07 17:52:24 volumio volumio[2128]: self.send(data, True) Feb 07 17:52:24 volumio volumio[2128]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Feb 07 17:52:24 volumio volumio[2128]: self._spi.xfer(data[start:end]) Feb 07 17:52:24 volumio volumio[2128]: TimeoutError: [Errno 110] Connection timed out Feb 07 17:52:24 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:24 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:24 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:24 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 15. Feb 07 17:52:24 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:25 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:26 volumio volumio[1029]: 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: 10 Feb 07 17:52:26 volumio volumio[2144]: Traceback (most recent call last): Feb 07 17:52:26 volumio volumio[2144]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:26 volumio volumio[2144]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:26 volumio volumio[2144]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:26 volumio volumio[2144]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:26 volumio volumio[2144]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:26 volumio volumio[2144]: self._transport Feb 07 17:52:26 volumio volumio[2144]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:26 volumio volumio[2144]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:26 volumio volumio[2144]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:26 volumio volumio[2144]: transport.recv_packet()) Feb 07 17:52:26 volumio volumio[2144]: StopIteration Feb 07 17:52:26 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:26 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:26 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:26 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 16. Feb 07 17:52:26 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:27 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:27 volumio kernel: hwmon hwmon1: Voltage normalised Feb 07 17:52:28 volumio volumio[1029]: 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: 11 Feb 07 17:52:28 volumio volumio[2145]: Traceback (most recent call last): Feb 07 17:52:28 volumio volumio[2145]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:28 volumio volumio[2145]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:28 volumio volumio[2145]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:28 volumio volumio[2145]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:28 volumio volumio[2145]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:28 volumio volumio[2145]: self._transport Feb 07 17:52:28 volumio volumio[2145]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:28 volumio volumio[2145]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:28 volumio volumio[2145]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:28 volumio volumio[2145]: transport.recv_packet()) Feb 07 17:52:28 volumio volumio[2145]: StopIteration Feb 07 17:52:28 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:28 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:28 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:28 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 17. Feb 07 17:52:28 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:29 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:30 volumio volumio[1029]: 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: 12 Feb 07 17:52:30 volumio volumio[2161]: Traceback (most recent call last): Feb 07 17:52:30 volumio volumio[2161]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:30 volumio volumio[2161]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:30 volumio volumio[2161]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:30 volumio volumio[2161]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:30 volumio volumio[2161]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:30 volumio volumio[2161]: self._transport Feb 07 17:52:30 volumio volumio[2161]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:30 volumio volumio[2161]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:30 volumio volumio[2161]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:30 volumio volumio[2161]: transport.recv_packet()) Feb 07 17:52:30 volumio volumio[2161]: StopIteration Feb 07 17:52:30 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:30 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:31 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:31 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 18. Feb 07 17:52:31 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:31 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:31 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 07 17:52:32 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150:3000 from 192.168.1.38 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:32 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:32 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:32 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:32 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 07 17:52:32 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 07 17:52:33 volumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 07 17:52:33 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 17:52:33 volumio sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2213]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 17:52:33 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 07 17:52:33 volumio sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2227]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:52:33 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:33 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:33 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:33 volumio sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:52:33 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:52:33 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Feb 07 17:52:33 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150:3000 from 192.168.1.38 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:33 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:33 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:33 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:33 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:33 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:33 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150:3000 from 192.168.1.38 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 17:52:33 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 17:52:34 volumio volumio[1029]: 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: 14 Feb 07 17:52:34 volumio volumio[2162]: Traceback (most recent call last): Feb 07 17:52:34 volumio volumio[2162]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:34 volumio volumio[2162]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:34 volumio volumio[2162]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:34 volumio volumio[2162]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:34 volumio volumio[2162]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:34 volumio volumio[2162]: self._transport Feb 07 17:52:34 volumio volumio[2162]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:34 volumio volumio[2162]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:34 volumio volumio[2162]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:34 volumio volumio[2162]: transport.recv_packet()) Feb 07 17:52:34 volumio volumio[2162]: StopIteration Feb 07 17:52:34 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:34 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:34 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:34 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 19. Feb 07 17:52:34 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:34 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:37 volumio volumio[1029]: 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: 15 Feb 07 17:52:37 volumio volumio[2238]: Traceback (most recent call last): Feb 07 17:52:37 volumio volumio[2238]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:37 volumio volumio[2238]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:37 volumio volumio[2238]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:37 volumio volumio[2238]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:37 volumio volumio[2238]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:37 volumio volumio[2238]: self._transport Feb 07 17:52:37 volumio volumio[2238]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:37 volumio volumio[2238]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:37 volumio volumio[2238]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:37 volumio volumio[2238]: transport.recv_packet()) Feb 07 17:52:37 volumio volumio[2238]: StopIteration Feb 07 17:52:37 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:37 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:37 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:37 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 20. Feb 07 17:52:37 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:37 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:39 volumio volumio[1029]: 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 Feb 07 17:52:39 volumio volumio[2240]: Traceback (most recent call last): Feb 07 17:52:39 volumio volumio[2240]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:39 volumio volumio[2240]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:39 volumio volumio[2240]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:39 volumio volumio[2240]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:39 volumio volumio[2240]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:39 volumio volumio[2240]: self._transport Feb 07 17:52:39 volumio volumio[2240]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:39 volumio volumio[2240]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:39 volumio volumio[2240]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:39 volumio volumio[2240]: transport.recv_packet()) Feb 07 17:52:39 volumio volumio[2240]: StopIteration Feb 07 17:52:39 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:39 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:39 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:39 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 21. Feb 07 17:52:39 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:39 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:40 volumio kernel: hwmon hwmon1: Voltage normalised Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:52:40 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:52:40 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:52:40 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150:3000 from 192.168.1.38 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 17 Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 17:52:40 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 17:52:41 volumio volumio[1029]: 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 Feb 07 17:52:41 volumio volumio[2255]: Traceback (most recent call last): Feb 07 17:52:41 volumio volumio[2255]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:41 volumio volumio[2255]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:41 volumio volumio[2255]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:41 volumio volumio[2255]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:41 volumio volumio[2255]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:41 volumio volumio[2255]: self._transport Feb 07 17:52:41 volumio volumio[2255]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:41 volumio volumio[2255]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:41 volumio volumio[2255]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:41 volumio volumio[2255]: transport.recv_packet()) Feb 07 17:52:41 volumio volumio[2255]: StopIteration Feb 07 17:52:41 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:41 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:41 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:41 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 22. Feb 07 17:52:41 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:41 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:43 volumio volumio[1029]: 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 Feb 07 17:52:43 volumio volumio[2257]: Traceback (most recent call last): Feb 07 17:52:43 volumio volumio[2257]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:43 volumio volumio[2257]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:43 volumio volumio[2257]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:43 volumio volumio[2257]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:43 volumio volumio[2257]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:43 volumio volumio[2257]: self._transport Feb 07 17:52:43 volumio volumio[2257]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:43 volumio volumio[2257]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:43 volumio volumio[2257]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:43 volumio volumio[2257]: transport.recv_packet()) Feb 07 17:52:43 volumio volumio[2257]: StopIteration Feb 07 17:52:43 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:43 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:43 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:43 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 23. Feb 07 17:52:43 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:43 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:45 volumio volumio[1029]: 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: 19 Feb 07 17:52:45 volumio volumio[2258]: Traceback (most recent call last): Feb 07 17:52:45 volumio volumio[2258]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:45 volumio volumio[2258]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:45 volumio volumio[2258]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:45 volumio volumio[2258]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:45 volumio volumio[2258]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:45 volumio volumio[2258]: self._transport Feb 07 17:52:45 volumio volumio[2258]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:45 volumio volumio[2258]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:45 volumio volumio[2258]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:45 volumio volumio[2258]: transport.recv_packet()) Feb 07 17:52:45 volumio volumio[2258]: StopIteration Feb 07 17:52:45 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:45 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:45 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:45 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 24. Feb 07 17:52:45 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:45 volumio systemd[1]: Started Service for using pirate audio display in volumio. Feb 07 17:52:47 volumio volumio[1029]: 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: 20 Feb 07 17:52:47 volumio volumio[2262]: Traceback (most recent call last): Feb 07 17:52:47 volumio volumio[2262]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Feb 07 17:52:47 volumio volumio[2262]: SOCKETIO = SocketIO('localhost', 3000) Feb 07 17:52:47 volumio volumio[2262]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Feb 07 17:52:47 volumio volumio[2262]: resource, hurry_interval_in_seconds, **kw) Feb 07 17:52:47 volumio volumio[2262]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Feb 07 17:52:47 volumio volumio[2262]: self._transport Feb 07 17:52:47 volumio volumio[2262]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Feb 07 17:52:47 volumio volumio[2262]: self._engineIO_session = self._get_engineIO_session() Feb 07 17:52:47 volumio volumio[2262]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Feb 07 17:52:47 volumio volumio[2262]: transport.recv_packet()) Feb 07 17:52:47 volumio volumio[2262]: StopIteration Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 25. Feb 07 17:52:47 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Start request repeated too quickly. Feb 07 17:52:47 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Feb 07 17:52:47 volumio systemd[1]: Failed to start Service for using pirate audio display in volumio. Feb 07 17:52:52 volumio go-librespot[1603]: time="2026-02-07T17:52:52+01:00" level=trace msg="sent dealer ping" Feb 07 17:52:52 volumio go-librespot[1603]: time="2026-02-07T17:52:52+01:00" level=trace msg="received dealer pong" Feb 07 17:53:01 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 17:53:01 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 17:53:01 volumio sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:53:02 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:53:02 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Feb 07 17:53:02 volumio sudo[2297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:53:02 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:53:02 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Feb 07 17:53:02 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 15; 24094RAD4G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Feb 07 17:53:02 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 17:53:02 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:53:02 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Feb 07 17:53:02 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 17:53:02 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 07 17:53:02 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Feb 07 17:53:02 volumio volumio[1029]: verbose: New Socket.io Connection to 192.168.1.150 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 15; 24094RAD4G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetVisibleSources Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:02 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetQueue Feb 07 17:53:02 volumio volumio[1029]: info: CoreStateMachine::getQueue Feb 07 17:53:02 volumio volumio[1029]: info: CorePlayQueue::getQueue Feb 07 17:53:02 volumio volumio[1029]: info: Listing playlists Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 07 17:53:02 volumio volumio[1029]: info: Received Get System Info Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:53:02 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:02 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:02 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 17:53:02 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 17:53:04 volumio volumio[1029]: info: Received Get System Info Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:53:04 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:04 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:53:04 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 07 17:53:05 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 17:53:05 volumio volumio[1029]: info: Received Get System Info Feb 07 17:53:05 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:53:05 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:53:05 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:53:05 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:05 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:05 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:53:11 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 07 17:53:12 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , handleBrowseUri Feb 07 17:53:12 volumio volumio[1029]: info: [mixcloud] browseUri: mixcloud Feb 07 17:53:12 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:13 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:17 volumio kernel: hwmon hwmon1: Voltage normalised Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 17:53:20 volumio volumio[1029]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 07 17:53:20 volumio volumio[1029]: info: Received Get System Version Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 17:53:20 volumio volumio[1029]: info: Received Get System Info Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 17:53:20 volumio volumio[1029]: info: Discovery: Getting this device information Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Feb 07 17:53:20 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:20 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 17:53:22 volumio go-librespot[1603]: time="2026-02-07T17:53:22+01:00" level=trace msg="received accesspoint ping" Feb 07 17:53:22 volumio go-librespot[1603]: time="2026-02-07T17:53:22+01:00" level=trace msg="received accesspoint pong ack" Feb 07 17:53:22 volumio go-librespot[1603]: time="2026-02-07T17:53:22+01:00" level=trace msg="sent dealer ping" Feb 07 17:53:22 volumio go-librespot[1603]: time="2026-02-07T17:53:22+01:00" level=trace msg="received dealer pong" Feb 07 17:53:24 volumio volumio[1029]: Searching plugin music_service/mixcloud Feb 07 17:53:24 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , search Feb 07 17:53:25 volumio volumio[1029]: Searching plugin music_service/mixcloud Feb 07 17:53:25 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , search Feb 07 17:53:26 volumio volumio[1029]: info: All search sources collected, pushing search results Feb 07 17:53:26 volumio volumio[1029]: Searching plugin music_service/mixcloud Feb 07 17:53:26 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , search Feb 07 17:53:26 volumio volumio[1029]: info: All search sources collected, pushing search results Feb 07 17:53:28 volumio volumio[1029]: info: All search sources collected, pushing search results Feb 07 17:53:30 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , handleBrowseUri Feb 07 17:53:30 volumio volumio[1029]: info: [mixcloud] browseUri: mixcloud/tags@keywords=Jaz@inSection=1/discover@slug=smooth-jazz Feb 07 17:53:31 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:31 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:31 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:34 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mixcloud , handleBrowseUri Feb 07 17:53:34 volumio volumio[1029]: info: [mixcloud] browseUri: mixcloud/tags@keywords=Jaz@inSection=1/discover@slug=smooth-jazz/cloudcast@cloudcastId=Q2xvdWRjYXN0OjIxODc4NjQ0MjI%3D@showMoreFromUser=1 Feb 07 17:53:34 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:34 volumio volumio[1029]: info: Preloading song: mixcloud/tags@keywords=Jaz@inSection=1/discover@slug=smooth-jazz/cloudcast@cloudcastId=Q2xvdWRjYXN0OjIxODc4NjQ0MjI%3D@showMoreFromUser=1 Feb 07 17:53:34 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:39 volumio volumio[1029]: info: Preload queue cleared Feb 07 17:53:39 volumio volumio[1029]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::ClearQueue Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::stop Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::updateTrackBlock Feb 07 17:53:39 volumio volumio[1029]: info: CorePlayQueue::getTrackBlock Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::stPlaybackTimer Feb 07 17:53:39 volumio volumio[1029]: info: CoreStateMachine::pushState Feb 07 17:53:39 volumio volumio[1029]: info: CorePlayQueue::getTrack 0 Feb 07 17:53:39 volumio volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 17:53:39 volumio volumio[1029]: info: CoreCommandRouter::volumioPushState Feb 07 17:53:39 volumio volumio[1029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:53:39 volumio volumio[1029]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 07 17:53:39 volumio volumio[1029]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Feb 07 17:53:39 volumio volumio[1029]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 07 17:53:39 volumio volumio[1029]: at /volumio/app/index.js:326:81 Feb 07 17:53:39 volumio volumio[1029]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 07 17:53:39 volumio volumio[1029]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 07 17:53:39 volumio volumio[1029]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 07 17:53:39 volumio volumio[1029]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 07 17:53:39 volumio volumio[1029]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1256:12) Feb 07 17:53:39 volumio volumio[1029]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Feb 07 17:53:39 volumio volumio[1029]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Feb 07 17:53:39 volumio volumio[1029]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Feb 07 17:53:39 volumio volumio[1029]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Feb 07 17:53:39 volumio volumio[1029]: at Socket.emit (events.js:315:20) Feb 07 17:53:39 volumio volumio[1029]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 07 17:53:39 volumio volumio[1029]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 07 17:53:39 volumio volumio[1029]: code: 'NumberExpected' Feb 07 17:53:39 volumio volumio[1029]: } Feb 07 17:53:39 volumio volumio[1029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 17:53:40 volumio sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-07 17:52 Feb 07 17:53:40 volumio sudo[2424]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"