-- Logs begin at Wed 2024-09-18 00:06:54 KST, end at Sun 2024-10-06 12:30:22 KST. -- Oct 06 12:29:49 volumioworking ntpd[918]: receive: Unexpected origin timestamp 0xea9419e1.651ec5eb does not match aorg 0000000000.00000000 from server@175.195.167.194 xmt 0xeaac82ad.d8cb9a0c Oct 06 12:29:49 volumioworking ntpd[918]: receive: Unexpected origin timestamp 0xea9419e1.651cc78a does not match aorg 0000000000.00000000 from server@39.118.108.191 xmt 0xeaac82ad.d894381a Oct 06 12:29:49 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1930, instance 1, run: true Oct 06 12:29:49 volumioworking systemd[1]: Starting Daily apt download activities... Oct 06 12:29:49 volumioworking volumio[1877]: cset: --> shielding system active with Oct 06 12:29:49 volumioworking volumio[1877]: cset: "system" cpuset of CPUSPEC(1-3) with 149 tasks running Oct 06 12:29:49 volumioworking volumio[1877]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Oct 06 12:29:49 volumioworking systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Oct 06 12:29:50 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1939, instance 1, run: true Oct 06 12:29:50 volumioworking volumio[1134]: info: Getting Spotify volume Oct 06 12:29:50 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1954, instance 1, run: true Oct 06 12:29:50 volumioworking volumio[1134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Oct 06 12:29:50 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1973, instance 1, run: true Oct 06 12:29:50 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:29:50 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:29:50 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1983, instance 1, run: true Oct 06 12:29:51 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1990, instance 1, run: true Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Device livingroom disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Disconnected from remote: 192.168.0.48 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Device bedroom disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Disconnected from remote: 192.168.0.5 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 1997, instance 1, run: true Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: A device disappeared from network Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: adding 96100db4-17ac-4645-bbc1-72cb51b2b006 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device livingroom Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Connecting to remote: 192.168.0.48 Oct 06 12:29:51 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2007, instance 1, run: true Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Connected to remote: 192.168.0.48 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: adding 2e5e8e17-a319-4025-aec7-58ee24e4429d Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device Volumio_working Oct 06 12:29:51 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:29:51 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: this is already registered, 96100db4-17ac-4645-bbc1-72cb51b2b006 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device livingroom Oct 06 12:29:51 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2018, instance 1, run: true Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: this is already registered, 2e5e8e17-a319-4025-aec7-58ee24e4429d Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device Volumio_working Oct 06 12:29:51 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:29:51 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: adding a7ff52b9-8185-499d-8f5a-76ecd25b0707 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device bedroom Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Connecting to remote: 192.168.0.5 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: this is already registered, a7ff52b9-8185-499d-8f5a-76ecd25b0707 Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Found device bedroom Oct 06 12:29:51 volumioworking volumio[1134]: info: Discovery: Connecting to remote: 192.168.0.5 Oct 06 12:29:52 volumioworking volumio[1134]: info: Discovery: Connected to remote: 192.168.0.5 Oct 06 12:29:52 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2030, instance 1, run: true Oct 06 12:29:52 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2040, instance 1, run: true Oct 06 12:29:52 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2047, instance 1, run: true Oct 06 12:29:52 volumioworking volumio[1868]: Traceback (most recent call last): Oct 06 12:29:52 volumioworking volumio[1868]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 12:29:52 volumioworking volumio[1868]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 12:29:52 volumioworking volumio[1868]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 12:29:52 volumioworking volumio[1868]: resource, hurry_interval_in_seconds, **kw) Oct 06 12:29:52 volumioworking volumio[1868]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 12:29:52 volumioworking volumio[1868]: self._transport Oct 06 12:29:52 volumioworking volumio[1868]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 12:29:52 volumioworking volumio[1868]: self._engineIO_session = self._get_engineIO_session() Oct 06 12:29:52 volumioworking volumio[1868]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 12:29:52 volumioworking volumio[1868]: transport.recv_packet()) Oct 06 12:29:52 volumioworking volumio[1868]: StopIteration Oct 06 12:29:52 volumioworking volumio[1134]: 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: 26 Oct 06 12:29:52 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2054, instance 1, run: true Oct 06 12:29:52 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2061, instance 1, run: true Oct 06 12:29:52 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:29:52 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:29:53 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2068, instance 1, run: true Oct 06 12:29:53 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:29:53 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 5. Oct 06 12:29:53 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:29:53 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2076, instance 1, run: true Oct 06 12:29:53 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:29:53 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2083, instance 1, run: true Oct 06 12:29:53 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2090, instance 1, run: true Oct 06 12:29:53 volumioworking volumio[1134]: info: AutoStart - Plugin is starting Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:29:53 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:29:53 volumioworking volumio[1134]: info: AutoStart - start playing Oct 06 12:29:53 volumioworking volumio[1134]: info: AutoStart - start playing with no specific position Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPlay Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::play index 0 Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::stop Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::play index undefined Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 12:29:53 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:29:53 volumioworking volumio[1134]: info: CoreStateMachine::startPlaybackTimer Oct 06 12:29:53 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:29:53 volumioworking volumio[1134]: info: [1728185393847] ControllerWebradio::clearAddPlayTrack Oct 06 12:29:53 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 12:29:53 volumioworking volumio[1134]: info: sendMpdCommand stop took 3 milliseconds Oct 06 12:29:53 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Oct 06 12:29:53 volumioworking volumio[1134]: info: Oct 06 12:29:53 volumioworking volumio[1134]: ---------------------------- MPD announces system playlist update Oct 06 12:29:53 volumioworking volumio[1134]: info: Ignoring MPD Status Update Oct 06 12:29:53 volumioworking volumio[1134]: info: sendMpdCommand clear took 10 milliseconds Oct 06 12:29:53 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" Oct 06 12:29:53 volumioworking volumio[1134]: info: Oct 06 12:29:53 volumioworking volumio[1134]: ---------------------------- MPD announces system playlist update Oct 06 12:29:53 volumioworking volumio[1134]: info: Ignoring MPD Status Update Oct 06 12:29:53 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2102, instance 1, run: true Oct 06 12:29:53 volumioworking volumio[1134]: error: updateQueue error: null Oct 06 12:29:53 volumioworking volumio[1134]: info: ------------------------------ 114ms Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2117, instance 1, run: true Oct 06 12:29:54 volumioworking volumio[1134]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs1fm.pls" took 327 milliseconds Oct 06 12:29:54 volumioworking volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 12:29:54 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Oct 06 12:29:54 volumioworking volumio[1134]: info: Oct 06 12:29:54 volumioworking volumio[1134]: ---------------------------- MPD announces system playlist update Oct 06 12:29:54 volumioworking volumio[1134]: info: Ignoring MPD Status Update Oct 06 12:29:54 volumioworking volumio[1134]: info: ------------------------------ 335ms Oct 06 12:29:54 volumioworking volumio[1134]: info: Oct 06 12:29:54 volumioworking volumio[1134]: ---------------------------- MPD announces system playlist update Oct 06 12:29:54 volumioworking volumio[1134]: info: Ignoring MPD Status Update Oct 06 12:29:54 volumioworking volumio[1134]: info: sendMpdCommand play took 30 milliseconds Oct 06 12:29:54 volumioworking volumio[1134]: info: ------------------------------ 41ms Oct 06 12:29:54 volumioworking volumio[1134]: info: ------------------------------ 33ms Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2135, instance 1, run: true Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2153, instance 1, run: true Oct 06 12:29:54 volumioworking systemd[1]: apt-daily.service: Succeeded. Oct 06 12:29:54 volumioworking systemd[1]: Started Daily apt download activities. Oct 06 12:29:54 volumioworking systemd[1]: Starting Daily apt upgrade and clean activities... Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2163, instance 1, run: true Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2173, instance 1, run: true Oct 06 12:29:54 volumioworking RoonBridge[1395]: 00:00:18.586 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 06 12:29:54 volumioworking RoonBridge[1395]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in :0 Oct 06 12:29:54 volumioworking RoonBridge[1395]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in :0 Oct 06 12:29:54 volumioworking RoonBridge[1395]: 00:00:18.586 Debug: PathForResource, filename: ../.update Oct 06 12:29:54 volumioworking RoonBridge[1395]: 00:00:18.586 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 06 12:29:54 volumioworking RoonBridge[1395]: Not Running (.o) Oct 06 12:29:54 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2188, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2200, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2209, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2216, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2223, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2230, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2237, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2245, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2252, instance 1, run: true Oct 06 12:29:55 volumioworking volumio[1134]: 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: 27 Oct 06 12:29:55 volumioworking volumio[2075]: Traceback (most recent call last): Oct 06 12:29:55 volumioworking volumio[2075]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 12:29:55 volumioworking volumio[2075]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 12:29:55 volumioworking volumio[2075]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 12:29:55 volumioworking volumio[2075]: resource, hurry_interval_in_seconds, **kw) Oct 06 12:29:55 volumioworking volumio[2075]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 12:29:55 volumioworking volumio[2075]: self._transport Oct 06 12:29:55 volumioworking volumio[2075]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 12:29:55 volumioworking volumio[2075]: self._engineIO_session = self._get_engineIO_session() Oct 06 12:29:55 volumioworking volumio[2075]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 12:29:55 volumioworking volumio[2075]: transport.recv_packet()) Oct 06 12:29:55 volumioworking volumio[2075]: StopIteration Oct 06 12:29:55 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2259, instance 1, run: true Oct 06 12:29:56 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2266, instance 1, run: true Oct 06 12:29:56 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:29:56 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:29:56 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2273, instance 1, run: true Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 06 12:29:56 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:29:56 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 6. Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 06 12:29:56 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin bluetooth to MyMusic Plugins Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin multiroom to MyMusic Plugins Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin metavolumio to MyMusic Plugins Oct 06 12:29:56 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin cd_controller to MyMusic Plugins Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 06 12:29:56 volumioworking volumio[1134]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 06 12:29:56 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 06 12:29:56 volumioworking RoonBridge[1395]: 00:00:20.587 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 06 12:29:56 volumioworking RoonBridge[1395]: Running Oct 06 12:29:57 volumioworking systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 06 12:29:57 volumioworking systemd[1]: Started Daily apt upgrade and clean activities. Oct 06 12:29:59 volumioworking volumio[1134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 06 12:29:59 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 06 12:29:59 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:29:59 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:29:59 volumioworking volumio[1134]: info: Starting MyVolumio Remote Streaming Endpoints Oct 06 12:29:59 volumioworking volumio[1134]: info: MyVolumio login type: Token Oct 06 12:29:59 volumioworking volumio[1134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 06 12:29:59 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 06 12:30:00 volumioworking volumio[1134]: info: Starting Streaming Service Transparent Proxy Oct 06 12:30:00 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 06 12:30:00 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 06 12:30:00 volumioworking volumio[1134]: info: Streaming services startup Oct 06 12:30:00 volumioworking volumio[1134]: info: Starting Streaming Daemon Oct 06 12:30:01 volumioworking volumio[1134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 06 12:30:01 volumioworking sudo[2332]: volumio : unable to resolve host volumioworking Oct 06 12:30:01 volumioworking sudo[2332]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:01 volumioworking sudo[2332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 06 12:30:01 volumioworking sudo[2332]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 12:30:01 volumioworking sudo[2332]: pam_unix(sudo:session): session closed for user root Oct 06 12:30:01 volumioworking volumio[1134]: info: Oct 06 12:30:01 volumioworking volumio[1134]: ---------------------------- MPD announces state update: player Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::getState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 06 12:30:01 volumioworking volumio[1134]: info: Oct 06 12:30:01 volumioworking volumio[1134]: ---------------------------- MPD announces state update: player Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::getState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 06 12:30:01 volumioworking volumio[1134]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Oct 06 12:30:01 volumioworking volumio[1134]: info: FusionDsp - ---- read samplerate from file: 192000 Oct 06 12:30:01 volumioworking volumio[1134]: info: camilladsp stopping service pid 2273... Oct 06 12:30:01 volumioworking volumio[1134]: info: camilladsp service terminated, instance 1 Oct 06 12:30:01 volumioworking volumio[1134]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 06 12:30:01 volumioworking volumio[1134]: info: camilladsp service started and running in background, instance 1 Oct 06 12:30:01 volumioworking volumio[1134]: error: Cannot start Volumio Streaming Daemon Oct 06 12:30:01 volumioworking volumio[1134]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 06 12:30:01 volumioworking volumio[1134]: sudo: unable to resolve host volumioworking: System error Oct 06 12:30:01 volumioworking volumio[1134]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 06 12:30:01 volumioworking volumio[1134]: info: Oct 06 12:30:01 volumioworking volumio[1134]: ---------------------------- MPD announces state update: player Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::getState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand status took 114 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand status took 112 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 12:30:01 volumioworking volumio[1134]: info: Oct 06 12:30:01 volumioworking volumio[1134]: ---------------------------- MPD announces state update: player Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::getState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 06 12:30:01 volumioworking volumio[2280]: Traceback (most recent call last): Oct 06 12:30:01 volumioworking volumio[2280]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 12:30:01 volumioworking volumio[2280]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 12:30:01 volumioworking volumio[2280]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 12:30:01 volumioworking volumio[2280]: resource, hurry_interval_in_seconds, **kw) Oct 06 12:30:01 volumioworking volumio[2280]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 12:30:01 volumioworking volumio[2280]: self._transport Oct 06 12:30:01 volumioworking volumio[2280]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 12:30:01 volumioworking volumio[2280]: self._engineIO_session = self._get_engineIO_session() Oct 06 12:30:01 volumioworking volumio[2280]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 12:30:01 volumioworking volumio[2280]: transport.recv_packet()) Oct 06 12:30:01 volumioworking volumio[2280]: StopIteration Oct 06 12:30:01 volumioworking volumio[1134]: 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: 28 Oct 06 12:30:01 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2342, instance 1, run: true Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand status took 170 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand playlistinfo took 163 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand playlistinfo took 162 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand status took 140 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseState Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::servicePushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:30:01 volumioworking volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":682,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"197 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728357596&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzU3NTk2fX19XX0_&Signature=NCmxN39MVkRrwYOSBryvNiS3UkKCiJ9b7tnq93F5-nAJpJfi0Lw2GPl0c-d9JzpgmIR-AW6bGzVLUgoNGssxdZEE0SO799G9i-pksM7Qcl5pe4j5-QhVpR~rB-uyIZSPfXMKf3n9gOe-atQeABhv9IjMmSm6umW1zAIjypQR10Tna~VFIozUK5~0UQl6~IgXdhgKGmXpDwehMx9kboVQLccGBGqkFYZlno~Iq7k3A3kkldLLkRfWsEHDIrPKYqxbkjDAlxwBja7XBdFaUtgmUbyCf8L~5rYZqq9b5SJ7zOCEZWvy9JUeGtYMZBBYLeJYsyO69deGgeC3aostHs43AQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 12:30:01 volumioworking volumio[1134]: verbose: CURRENT POSITION 0 Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::servicePushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:30:01 volumioworking volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":682,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"197 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728357596&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzU3NTk2fX19XX0_&Signature=NCmxN39MVkRrwYOSBryvNiS3UkKCiJ9b7tnq93F5-nAJpJfi0Lw2GPl0c-d9JzpgmIR-AW6bGzVLUgoNGssxdZEE0SO799G9i-pksM7Qcl5pe4j5-QhVpR~rB-uyIZSPfXMKf3n9gOe-atQeABhv9IjMmSm6umW1zAIjypQR10Tna~VFIozUK5~0UQl6~IgXdhgKGmXpDwehMx9kboVQLccGBGqkFYZlno~Iq7k3A3kkldLLkRfWsEHDIrPKYqxbkjDAlxwBja7XBdFaUtgmUbyCf8L~5rYZqq9b5SJ7zOCEZWvy9JUeGtYMZBBYLeJYsyO69deGgeC3aostHs43AQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 12:30:01 volumioworking volumio[1134]: verbose: CURRENT POSITION 0 Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 06 12:30:01 volumioworking volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking volumio[1134]: info: ------------------------------ 382ms Oct 06 12:30:01 volumioworking volumio[1134]: info: ------------------------------ 460ms Oct 06 12:30:01 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:01 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand playlistinfo took 229 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: info: sendMpdCommand playlistinfo took 211 milliseconds Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 06 12:30:01 volumioworking volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::servicePushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:30:01 volumioworking volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":682,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"197 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728357596&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzU3NTk2fX19XX0_&Signature=NCmxN39MVkRrwYOSBryvNiS3UkKCiJ9b7tnq93F5-nAJpJfi0Lw2GPl0c-d9JzpgmIR-AW6bGzVLUgoNGssxdZEE0SO799G9i-pksM7Qcl5pe4j5-QhVpR~rB-uyIZSPfXMKf3n9gOe-atQeABhv9IjMmSm6umW1zAIjypQR10Tna~VFIozUK5~0UQl6~IgXdhgKGmXpDwehMx9kboVQLccGBGqkFYZlno~Iq7k3A3kkldLLkRfWsEHDIrPKYqxbkjDAlxwBja7XBdFaUtgmUbyCf8L~5rYZqq9b5SJ7zOCEZWvy9JUeGtYMZBBYLeJYsyO69deGgeC3aostHs43AQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 12:30:01 volumioworking volumio[1134]: verbose: CURRENT POSITION 0 Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 06 12:30:01 volumioworking volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking volumio[1134]: info: ControllerMpd::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::servicePushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 06 12:30:01 volumioworking volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":682,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"197 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1728357596&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzI4MzU3NTk2fX19XX0_&Signature=NCmxN39MVkRrwYOSBryvNiS3UkKCiJ9b7tnq93F5-nAJpJfi0Lw2GPl0c-d9JzpgmIR-AW6bGzVLUgoNGssxdZEE0SO799G9i-pksM7Qcl5pe4j5-QhVpR~rB-uyIZSPfXMKf3n9gOe-atQeABhv9IjMmSm6umW1zAIjypQR10Tna~VFIozUK5~0UQl6~IgXdhgKGmXpDwehMx9kboVQLccGBGqkFYZlno~Iq7k3A3kkldLLkRfWsEHDIrPKYqxbkjDAlxwBja7XBdFaUtgmUbyCf8L~5rYZqq9b5SJ7zOCEZWvy9JUeGtYMZBBYLeJYsyO69deGgeC3aostHs43AQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Oct 06 12:30:01 volumioworking volumio[1134]: verbose: CURRENT POSITION 0 Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 06 12:30:01 volumioworking volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:01 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:01 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 7. Oct 06 12:30:01 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:01 volumioworking volumio[1134]: info: ------------------------------ 581ms Oct 06 12:30:01 volumioworking volumio[1134]: info: ------------------------------ 571ms Oct 06 12:30:01 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:01 volumioworking volumio[1134]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 12:30:01 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:02 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:02 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2351, instance 1, run: true Oct 06 12:30:02 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:02 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:02 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:02 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:02 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2358, instance 1, run: true Oct 06 12:30:03 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2366, instance 1, run: true Oct 06 12:30:03 volumioworking volumio[1134]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:03 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:03 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2377, instance 1, run: true Oct 06 12:30:03 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2384, instance 1, run: true Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:03 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 06 12:30:04 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2395, instance 1, run: true Oct 06 12:30:04 volumioworking volumio-remote-updater[655]: No test mode Oct 06 12:30:04 volumioworking volumio-remote-updater[655]: No alpha test mode Oct 06 12:30:04 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 06 12:30:04 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:04 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:04 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:04 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:04 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2409, instance 1, run: true Oct 06 12:30:04 volumioworking volumio[2350]: Traceback (most recent call last): Oct 06 12:30:04 volumioworking volumio[2350]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:04 volumioworking volumio[2350]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:04 volumioworking volumio[2350]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:04 volumioworking volumio[2350]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:04 volumioworking volumio[2350]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:04 volumioworking volumio[2350]: DISP.display(img4) Oct 06 12:30:04 volumioworking volumio[2350]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:04 volumioworking volumio[2350]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:04 volumioworking volumio[2350]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:04 volumioworking volumio[2350]: self.send(data, True) Oct 06 12:30:04 volumioworking volumio[2350]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:04 volumioworking volumio[2350]: self._spi.xfer(data[start:end]) Oct 06 12:30:04 volumioworking volumio[2350]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:04 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:04 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:04 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:04 volumioworking volumio[1134]: info: MyVolumio token set successfully Oct 06 12:30:04 volumioworking volumio[1134]: info: MYVOLUMIO: Adding device Oct 06 12:30:04 volumioworking volumio[1134]: info: MYVOLUMIO: Evaluating Server Oct 06 12:30:04 volumioworking volumio[1134]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 06 12:30:04 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 06 12:30:04 volumioworking volumio[1134]: STREAMING PROXY: Starting server on port 3245 Oct 06 12:30:04 volumioworking volumio[1134]: Node JS runtime: 14 Oct 06 12:30:04 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:04 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:05 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:05 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 8. Oct 06 12:30:05 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:05 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:06 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2434, instance 1, run: true Oct 06 12:30:06 volumioworking volumio[1134]: info: MyVolumio status changed Oct 06 12:30:06 volumioworking volumio[1134]: info: Streaming services startup Oct 06 12:30:06 volumioworking volumio[1134]: info: Starting Streaming Daemon Oct 06 12:30:06 volumioworking volumio[1134]: info: Removing browser output: myVolumio user plan is not superstar Oct 06 12:30:06 volumioworking volumio[1134]: info: Removing audio output: Oct 06 12:30:06 volumioworking volumio[1134]: info: Stoppping Tunnel 1 Oct 06 12:30:06 volumioworking sudo[2442]: volumio : unable to resolve host volumioworking Oct 06 12:30:06 volumioworking sudo[2442]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:06 volumioworking sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 06 12:30:06 volumioworking sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 12:30:06 volumioworking sudo[2442]: pam_unix(sudo:session): session closed for user root Oct 06 12:30:06 volumioworking sudo[2445]: volumio : unable to resolve host volumioworking Oct 06 12:30:06 volumioworking sudo[2445]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:06 volumioworking sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 06 12:30:06 volumioworking sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 12:30:06 volumioworking sudo[2445]: pam_unix(sudo:session): session closed for user root Oct 06 12:30:06 volumioworking volumio[1134]: info: Setting Geolocation for MyVolumio to as1 Oct 06 12:30:06 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:06 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:06 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:07 volumioworking volumio[1134]: error: Cannot start Volumio Streaming Daemon Oct 06 12:30:07 volumioworking volumio[1134]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 06 12:30:07 volumioworking volumio[1134]: sudo: unable to resolve host volumioworking: System error Oct 06 12:30:07 volumioworking volumio[1134]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 06 12:30:07 volumioworking volumio[1134]: info: Remote SSH Stopped Oct 06 12:30:07 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2447, instance 1, run: true Oct 06 12:30:07 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2454, instance 1, run: true Oct 06 12:30:07 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:07 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:07 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:07 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:07 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2461, instance 1, run: true Oct 06 12:30:07 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2468, instance 1, run: true Oct 06 12:30:07 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2475, instance 1, run: true Oct 06 12:30:07 volumioworking volumio[2418]: Traceback (most recent call last): Oct 06 12:30:07 volumioworking volumio[2418]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:07 volumioworking volumio[2418]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:07 volumioworking volumio[2418]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:07 volumioworking volumio[2418]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:07 volumioworking volumio[2418]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:07 volumioworking volumio[2418]: DISP.display(img4) Oct 06 12:30:07 volumioworking volumio[2418]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:07 volumioworking volumio[2418]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:07 volumioworking volumio[2418]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:07 volumioworking volumio[2418]: self.send(data, True) Oct 06 12:30:07 volumioworking volumio[2418]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:07 volumioworking volumio[2418]: self._spi.xfer(data[start:end]) Oct 06 12:30:07 volumioworking volumio[2418]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:07 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:07 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:07 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:07 volumioworking volumio[1134]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2482, instance 1, run: true Oct 06 12:30:08 volumioworking volumio[1134]: info: Updating MyVolumio device info Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:08 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:08 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2489, instance 1, run: true Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2496, instance 1, run: true Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:08 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:08 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:08 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 9. Oct 06 12:30:08 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2503, instance 1, run: true Oct 06 12:30:08 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:08 volumioworking sudo[2512]: volumio : unable to resolve host volumioworking Oct 06 12:30:08 volumioworking sudo[2512]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:08 volumioworking sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 12:30:08 volumioworking sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 12:30:08 volumioworking sudo[2512]: pam_unix(sudo:session): session closed for user root Oct 06 12:30:08 volumioworking sudo[2515]: volumio : unable to resolve host volumioworking Oct 06 12:30:08 volumioworking sudo[2515]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:08 volumioworking sudo[2515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 12:30:08 volumioworking sudo[2515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 12:30:08 volumioworking sudo[2515]: pam_unix(sudo:session): session closed for user root Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2516, instance 1, run: true Oct 06 12:30:08 volumioworking volumio[1134]: verbose: New Socket.io Connection to 192.168.0.46 from 192.168.0.53 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Oct 06 12:30:08 volumioworking volumio[1134]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 06 12:30:08 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 06 12:30:08 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2524, instance 1, run: true Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 12:30:09 volumioworking volumio[1134]: info: Received Get System Info Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 12:30:09 volumioworking volumio[1134]: info: Discovery: Getting this device information Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:09 volumioworking volumio[1134]: info: Listing playlists Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 06 12:30:09 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2531, instance 1, run: true Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 12:30:09 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2538, instance 1, run: true Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:09 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2545, instance 1, run: true Oct 06 12:30:09 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2552, instance 1, run: true Oct 06 12:30:09 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2559, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2566, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2573, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2580, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2587, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2594, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:10 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2601, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 12:30:10 volumioworking volumio[1134]: info: Received Get System Info Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 12:30:10 volumioworking volumio[1134]: info: Discovery: Getting this device information Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2608, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2615, instance 1, run: true Oct 06 12:30:10 volumioworking volumio[2504]: Traceback (most recent call last): Oct 06 12:30:10 volumioworking volumio[2504]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:10 volumioworking volumio[2504]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:10 volumioworking volumio[2504]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:10 volumioworking volumio[2504]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:10 volumioworking volumio[2504]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:10 volumioworking volumio[2504]: DISP.display(img4) Oct 06 12:30:10 volumioworking volumio[2504]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:10 volumioworking volumio[2504]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:10 volumioworking volumio[2504]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:10 volumioworking volumio[2504]: self.send(data, True) Oct 06 12:30:10 volumioworking volumio[2504]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:10 volumioworking volumio[2504]: self._spi.xfer(data[start:end]) Oct 06 12:30:10 volumioworking volumio[2504]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:10 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:10 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:10 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:10 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 06 12:30:10 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2622, instance 1, run: true Oct 06 12:30:11 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:11 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2629, instance 1, run: true Oct 06 12:30:11 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:11 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 10. Oct 06 12:30:11 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2636, instance 1, run: true Oct 06 12:30:11 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2644, instance 1, run: true Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2652, instance 1, run: true Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2659, instance 1, run: true Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2666, instance 1, run: true Oct 06 12:30:11 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2673, instance 1, run: true Oct 06 12:30:11 volumioworking volumio[1134]: info: MYVOLUMIO: Adding device Oct 06 12:30:11 volumioworking volumio[1134]: info: MYVOLUMIO: Evaluating Server Oct 06 12:30:12 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2680, instance 1, run: true Oct 06 12:30:12 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2687, instance 1, run: true Oct 06 12:30:12 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 12:30:12 volumioworking volumio[1134]: info: Received Get System Info Oct 06 12:30:12 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 12:30:12 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 12:30:12 volumioworking volumio[1134]: info: Discovery: Getting this device information Oct 06 12:30:12 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:12 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2709, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:13 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2716, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2723, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: Setting Geolocation for MyVolumio to as1 Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:13 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2730, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2737, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2744, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2751, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2758, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[2637]: Traceback (most recent call last): Oct 06 12:30:13 volumioworking volumio[2637]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:13 volumioworking volumio[2637]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:13 volumioworking volumio[2637]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:13 volumioworking volumio[2637]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:13 volumioworking volumio[2637]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:13 volumioworking volumio[2637]: DISP.display(img4) Oct 06 12:30:13 volumioworking volumio[2637]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:13 volumioworking volumio[2637]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:13 volumioworking volumio[2637]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:13 volumioworking volumio[2637]: self.send(data, True) Oct 06 12:30:13 volumioworking volumio[2637]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:13 volumioworking volumio[2637]: self._spi.xfer(data[start:end]) Oct 06 12:30:13 volumioworking volumio[2637]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:13 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:13 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:13 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2765, instance 1, run: true Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2772, instance 1, run: true Oct 06 12:30:13 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:13 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:13 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2779, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2786, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 06 12:30:14 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 12:30:14 volumioworking volumio[1134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2793, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:14 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:14 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 11. Oct 06 12:30:14 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2801, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: Updating MyVolumio device info Oct 06 12:30:14 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:14 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:14 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:14 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2808, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2815, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2822, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2829, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2836, instance 1, run: true Oct 06 12:30:14 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2843, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2850, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2857, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2864, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2871, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2878, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2885, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2892, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2899, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2906, instance 1, run: true Oct 06 12:30:15 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:15 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:15 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:15 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:15 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2913, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2920, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2927, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2934, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2941, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2948, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2955, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[2800]: Traceback (most recent call last): Oct 06 12:30:16 volumioworking volumio[2800]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:16 volumioworking volumio[2800]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:16 volumioworking volumio[2800]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:16 volumioworking volumio[2800]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:16 volumioworking volumio[2800]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:16 volumioworking volumio[2800]: DISP.display(img4) Oct 06 12:30:16 volumioworking volumio[2800]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:16 volumioworking volumio[2800]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:16 volumioworking volumio[2800]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:16 volumioworking volumio[2800]: self.send(data, True) Oct 06 12:30:16 volumioworking volumio[2800]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:16 volumioworking volumio[2800]: self._spi.xfer(data[start:end]) Oct 06 12:30:16 volumioworking volumio[2800]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:16 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:16 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:16 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2962, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2969, instance 1, run: true Oct 06 12:30:16 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:16 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2976, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2983, instance 1, run: true Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2990, instance 1, run: true Oct 06 12:30:16 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:16 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 12. Oct 06 12:30:16 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:16 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:16 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:16 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:16 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:16 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 2998, instance 1, run: true Oct 06 12:30:17 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3005, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3012, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3019, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3026, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3033, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3040, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3047, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3054, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3061, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3068, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3075, instance 1, run: true Oct 06 12:30:17 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:17 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:17 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:17 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3082, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3089, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3096, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3103, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3110, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3117, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3124, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3131, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3139, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3147, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3154, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3161, instance 1, run: true Oct 06 12:30:18 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:18 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:18 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:18 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3168, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 12:30:19 volumioworking volumio[1134]: 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 Oct 06 12:30:19 volumioworking volumio[2997]: Traceback (most recent call last): Oct 06 12:30:19 volumioworking volumio[2997]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 310, in Oct 06 12:30:19 volumioworking volumio[2997]: SOCKETIO = SocketIO('localhost', 3000) Oct 06 12:30:19 volumioworking volumio[2997]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 353, in __init__ Oct 06 12:30:19 volumioworking volumio[2997]: resource, hurry_interval_in_seconds, **kw) Oct 06 12:30:19 volumioworking volumio[2997]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 54, in __init__ Oct 06 12:30:19 volumioworking volumio[2997]: self._transport Oct 06 12:30:19 volumioworking volumio[2997]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 62, in _transport Oct 06 12:30:19 volumioworking volumio[2997]: self._engineIO_session = self._get_engineIO_session() Oct 06 12:30:19 volumioworking volumio[2997]: File "/usr/local/lib/python3.7/dist-packages/socketIO_client/__init__.py", line 76, in _get_engineIO_session Oct 06 12:30:19 volumioworking volumio[2997]: transport.recv_packet()) Oct 06 12:30:19 volumioworking volumio[2997]: StopIteration Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3175, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3182, instance 1, run: true Oct 06 12:30:19 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:19 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3189, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3196, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3203, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3210, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3217, instance 1, run: true Oct 06 12:30:19 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:19 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 13. Oct 06 12:30:19 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:19 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3225, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3232, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3239, instance 1, run: true Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:19 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:19 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3246, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3253, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3260, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3267, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3274, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3281, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3288, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3295, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3302, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3309, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3316, instance 1, run: true Oct 06 12:30:20 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:20 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetQueue Oct 06 12:30:20 volumioworking volumio[1134]: info: CoreStateMachine::getQueue Oct 06 12:30:20 volumioworking volumio[1134]: info: CorePlayQueue::getQueue Oct 06 12:30:20 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3323, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3330, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 06 12:30:21 volumioworking volumio[1134]: info: Completed starting MyVolumio Plugin Oct 06 12:30:21 volumioworking volumio[1134]: info: BOOT COMPLETED Oct 06 12:30:21 volumioworking volumio[1134]: [Metrics] CommandRouter: 88s 81.88ms Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::volumiosetStartupVolume Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 12:30:21 volumioworking volumio[1134]: info: VolumeController:: Setting startup Volume 75 Oct 06 12:30:21 volumioworking volumio[1134]: info: VolumeController::SetAlsaVolume75 Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::Close All Modals sent Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::Close All Modals sent Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreStateMachine::pushState Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 06 12:30:21 volumioworking volumio[1134]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! KBS Classic FM Oct 06 12:30:21 volumioworking volumio[1134]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Oct 06 12:30:21 volumioworking volumio[1134]: SPOTIFY: SPOTIFY VOLUME undefined Oct 06 12:30:21 volumioworking volumio[1134]: SPOTIFY: VOLUMIO VOLUME 75 Oct 06 12:30:21 volumioworking volumio[1134]: info: Aligning Spotify Volume to Volumio Volume Oct 06 12:30:21 volumioworking volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 06 12:30:21 volumioworking volumio[1134]: info: Setting Spotify Volume from Volumio: 75 Oct 06 12:30:21 volumioworking volumio[1134]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 06 12:30:21 volumioworking volumio[1134]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 06 12:30:21 volumioworking volumio[1134]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp service terminated, instance 1 Oct 06 12:30:21 volumioworking volumio[1134]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3341, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp service started and running in background, instance 1 Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3348, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3355, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3362, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3369, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3376, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[3224]: Traceback (most recent call last): Oct 06 12:30:21 volumioworking volumio[3224]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 309, in Oct 06 12:30:21 volumioworking volumio[3224]: display_stuff(IMAGE_DICT['BG_DEFAULT'], OBJ_TRANS['DISPLAY']['WAIT'], 0, 0, 'info') Oct 06 12:30:21 volumioworking volumio[3224]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 304, in display_stuff Oct 06 12:30:21 volumioworking volumio[3224]: sendtodisplay(IMAGE_DICT['IMG3']) Oct 06 12:30:21 volumioworking volumio[3224]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 210, in sendtodisplay Oct 06 12:30:21 volumioworking volumio[3224]: DISP.display(img4) Oct 06 12:30:21 volumioworking volumio[3224]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 343, in display Oct 06 12:30:21 volumioworking volumio[3224]: self.data(pixelbytes[i:i + 4096]) Oct 06 12:30:21 volumioworking volumio[3224]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 191, in data Oct 06 12:30:21 volumioworking volumio[3224]: self.send(data, True) Oct 06 12:30:21 volumioworking volumio[3224]: File "/usr/local/lib/python3.7/dist-packages/ST7789/__init__.py", line 170, in send Oct 06 12:30:21 volumioworking volumio[3224]: self._spi.xfer(data[start:end]) Oct 06 12:30:21 volumioworking volumio[3224]: TimeoutError: [Errno 110] Connection timed out Oct 06 12:30:21 volumioworking kernel: spidev spi0.1: SPI transfer timed out Oct 06 12:30:21 volumioworking kernel: spi_master spi0: failed to transfer one message from queue Oct 06 12:30:21 volumioworking kernel: spi_master spi0: noqueue transfer failed Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3383, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: camilladsp spawned new process with pid 3390, instance 1, run: true Oct 06 12:30:21 volumioworking volumio[1134]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Oct 06 12:30:21 volumioworking volumio[1134]: info: PLUGIN onShutdown : networkfs Oct 06 12:30:21 volumioworking volumio[1134]: info: PLUGIN onShutdown : audiophonicsonoff Oct 06 12:30:21 volumioworking volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 12:30:22 volumioworking volumio[1134]: TypeError: Cannot read property 'writeSync' of undefined Oct 06 12:30:22 volumioworking volumio[1134]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Oct 06 12:30:22 volumioworking volumio[1134]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Oct 06 12:30:22 volumioworking volumio[1134]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Oct 06 12:30:22 volumioworking volumio[1134]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 06 12:30:22 volumioworking volumio[1134]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 06 12:30:22 volumioworking volumio[1134]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Oct 06 12:30:22 volumioworking volumio[1134]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1319:24) Oct 06 12:30:22 volumioworking volumio[1134]: at /data/plugins/system_hardware/remotepi/index.js:91:35 Oct 06 12:30:22 volumioworking volumio[1134]: at /data/plugins/system_hardware/remotepi/node_modules/onoff/onoff.js:135:9 Oct 06 12:30:22 volumioworking volumio[1134]: at Array.forEach () Oct 06 12:30:22 volumioworking volumio[1134]: at pollerEventHandler (/data/plugins/system_hardware/remotepi/node_modules/onoff/onoff.js:134:32) Oct 06 12:30:22 volumioworking volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 12:30:22 volumioworking systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 06 12:30:22 volumioworking systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 06 12:30:22 volumioworking systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 06 12:30:22 volumioworking systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 14. Oct 06 12:30:22 volumioworking systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 06 12:30:22 volumioworking systemd[1]: Started Service for using pirate audio display in volumio. Oct 06 12:30:22 volumioworking sudo[3407]: volumio : unable to resolve host volumioworking Oct 06 12:30:22 volumioworking sudo[3407]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 06 12:30:22 volumioworking sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 12:29 Oct 06 12:30:22 volumioworking sudo[3407]: 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"