-- Logs begin at Fri 2024-10-18 20:37:35 CEST, end at Fri 2024-10-18 23:24:22 CEST. -- Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="handling resume player command from 275b2fb0e8fedfe56d5efcec88786a9974893447" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="resume track at 0ms" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:7x9FkqEnDLvCXTHuKDMSTc" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:7x9FkqEnDLvCXTHuKDMSTc" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=trace msg="scheduling prefetch in 257s" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="sending successful reply for dealer request" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 18 23:23:00 volumio go-librespot[30620]: time="2024-10-18T23:23:00+02:00" level=trace msg="emitting websocket event: playing" Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7x9FkqEnDLvCXTHuKDMSTc","play_origin":"playlist"}} Oct 18 23:23:00 volumio volumio[1237]: info: Spotify is playing in volatile mode Oct 18 23:23:00 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: UNSET VOLATILE Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: {"status":"play","position":0,"title":"Fiction Factory - (Feels Like) Heaven","artist":"80s80s Wave","album":"","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/a4/dc/d2/a4dcd215-5963-51b2-59bf-a5b3b6899668/source/600x600bb.jpg","uri":"http://streams.80s80s.de/wave/mp3-192/volumio","trackType":"80s80s Radio","seek":113526,"duration":"200","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Oct 18 23:23:00 volumio volumio[1237]: info: Setting Spotify stop after unset volatile call Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: PUSH STATE SPOTIFY Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 18 23:23:00 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:00 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:00 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 23:23:00 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7x9FkqEnDLvCXTHuKDMSTc","play_origin":"playlist"}} Oct 18 23:23:00 volumio volumio[1237]: info: Spotify is playing in volatile mode Oct 18 23:23:00 volumio volumio[1237]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: UNSET VOLATILE Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: {"status":"play","position":0,"title":"Fiction Factory - (Feels Like) Heaven","artist":"80s80s Wave","album":"","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/a4/dc/d2/a4dcd215-5963-51b2-59bf-a5b3b6899668/source/600x600bb.jpg","uri":"http://streams.80s80s.de/wave/mp3-192/volumio","trackType":"80s80s Radio","seek":113526,"duration":"200","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Oct 18 23:23:00 volumio volumio[1237]: info: Setting Spotify stop after unset volatile call Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: PUSH STATE SPOTIFY Oct 18 23:23:00 volumio volumio[1237]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 18 23:23:00 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: PUSH STATE SPOTIFY Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 18 23:23:01 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: PUSH STATE SPOTIFY Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 18 23:23:01 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:01 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:01 volumio volumio[1237]: info: Spotify Stop Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: SPOTIFY STOP Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: {"status":"play","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Oct 18 23:23:01 volumio volumio[1237]: info: Spotify Stop Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: SPOTIFY STOP Oct 18 23:23:01 volumio volumio[1237]: SPOTIFY: {"status":"play","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Oct 18 23:23:01 volumio volumio[30633]: Traceback (most recent call last): Oct 18 23:23:01 volumio volumio[30633]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:01 volumio volumio[30633]: import ST7789 # v0.0.6 Oct 18 23:23:01 volumio volumio[30633]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:02 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:02 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:02 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:02 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509829. Oct 18 23:23:02 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:02 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:05 volumio volumio[30634]: Traceback (most recent call last): Oct 18 23:23:05 volumio volumio[30634]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:05 volumio volumio[30634]: import ST7789 # v0.0.6 Oct 18 23:23:05 volumio volumio[30634]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:05 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:05 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:05 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:05 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509830. Oct 18 23:23:05 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:05 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:08 volumio volumio[30636]: Traceback (most recent call last): Oct 18 23:23:08 volumio volumio[30636]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:08 volumio volumio[30636]: import ST7789 # v0.0.6 Oct 18 23:23:08 volumio volumio[30636]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:08 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:08 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:08 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:08 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509831. Oct 18 23:23:09 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:09 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:11 volumio volumio[30637]: Traceback (most recent call last): Oct 18 23:23:11 volumio volumio[30637]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:11 volumio volumio[30637]: import ST7789 # v0.0.6 Oct 18 23:23:11 volumio volumio[30637]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:11 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:11 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:12 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:12 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509832. Oct 18 23:23:12 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:12 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:14 volumio go-librespot[30620]: time="2024-10-18T23:23:14+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 18 23:23:14 volumio go-librespot[30620]: time="2024-10-18T23:23:14+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 18 23:23:14 volumio go-librespot[30620]: time="2024-10-18T23:23:14+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 242" Oct 18 23:23:14 volumio go-librespot[30620]: time="2024-10-18T23:23:14+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1714" Oct 18 23:23:15 volumio volumio[30638]: Traceback (most recent call last): Oct 18 23:23:15 volumio volumio[30638]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:15 volumio volumio[30638]: import ST7789 # v0.0.6 Oct 18 23:23:15 volumio volumio[30638]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:15 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:15 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:15 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:15 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509833. Oct 18 23:23:15 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:15 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:18 volumio volumio[30639]: Traceback (most recent call last): Oct 18 23:23:18 volumio volumio[30639]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:18 volumio volumio[30639]: import ST7789 # v0.0.6 Oct 18 23:23:18 volumio volumio[30639]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:18 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:18 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:18 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:18 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509834. Oct 18 23:23:18 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:18 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:21 volumio volumio[30640]: Traceback (most recent call last): Oct 18 23:23:21 volumio volumio[30640]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:21 volumio volumio[30640]: import ST7789 # v0.0.6 Oct 18 23:23:21 volumio volumio[30640]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:21 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:21 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:21 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:21 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509835. Oct 18 23:23:22 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:22 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:24 volumio volumio[30641]: Traceback (most recent call last): Oct 18 23:23:24 volumio volumio[30641]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:24 volumio volumio[30641]: import ST7789 # v0.0.6 Oct 18 23:23:24 volumio volumio[30641]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:25 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:25 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:25 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:25 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509836. Oct 18 23:23:25 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:25 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:27 volumio volumio[30644]: Traceback (most recent call last): Oct 18 23:23:27 volumio volumio[30644]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:27 volumio volumio[30644]: import ST7789 # v0.0.6 Oct 18 23:23:27 volumio volumio[30644]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:28 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:28 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:28 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:28 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509837. Oct 18 23:23:28 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:28 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:31 volumio volumio[30645]: Traceback (most recent call last): Oct 18 23:23:31 volumio volumio[30645]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:31 volumio volumio[30645]: import ST7789 # v0.0.6 Oct 18 23:23:31 volumio volumio[30645]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:31 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:31 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:31 volumio volumio[1237]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 18 23:23:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 18 23:23:31 volumio volumio[1237]: info: Creating Spotify config file Oct 18 23:23:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 23:23:31 volumio volumio[1237]: info: Spotify config file written Oct 18 23:23:31 volumio sudo[30647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 18 23:23:31 volumio sudo[30647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 23:23:31 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:31 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509838. Oct 18 23:23:31 volumio systemd[1]: Stopping go-librespot Daemon... Oct 18 23:23:31 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:31 volumio volumio[1237]: info: Connection to go-librespot Websocket closed Oct 18 23:23:31 volumio volumio[1237]: info: Connection to go-librespot Websocket closed Oct 18 23:23:31 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 18 23:23:31 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 18 23:23:31 volumio systemd[1]: Stopped go-librespot Daemon. Oct 18 23:23:31 volumio systemd[1]: Started go-librespot Daemon. Oct 18 23:23:31 volumio sudo[30647]: pam_unix(sudo:session): session closed for user root Oct 18 23:23:32 volumio go-librespot[30650]: Librespot-go daemon starting... Oct 18 23:23:32 volumio go-librespot[30650]: time="2024-10-18T23:23:32+02:00" level=info msg="generated new device id: e3f32efb0f2395b81a5a48b760ac9008d66cec79" Oct 18 23:23:32 volumio go-librespot[30650]: time="2024-10-18T23:23:32+02:00" level=debug msg="stored credentials found for 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="zeroconf server listening on port 33681" Oct 18 23:23:34 volumio volumio[1237]: info: Initializing connection to go-librespot Websocket Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="new websocket client" Oct 18 23:23:34 volumio volumio[1237]: info: Connection to go-librespot Websocket established Oct 18 23:23:34 volumio go-librespot[30650]: time="2024-10-18T23:23:34+02:00" level=debug msg="obtained new client token: AACzUQSSdyugTurrx1t+sg0xyhUB1ATonLfqCTvQFPVKJR6/4dvAtCCNFtFtWdgY0jXZMv7K/RX8Gq8vK1SgO72zUxbiVtH9rlFLDc27P0fyKSEkmUA9yoFAGPsL/mwg3xOtBmnoJnz60TsopVE1Z2xfP1pSedMJCabJ8u5nFr8+z9BIOWTpWGzFNkK4r9/WtE/wO4E/eUXYmVF/sLlXdbMzTyoRJ6xvI5zJENJ+4hk7Jlwr/QUh9uWf3Og=" Oct 18 23:23:34 volumio volumio[1237]: info: go-librespot daemon successfully initialized Oct 18 23:23:35 volumio go-librespot[30650]: time="2024-10-18T23:23:35+02:00" level=debug msg="completed keyexchange" Oct 18 23:23:35 volumio go-librespot[30650]: time="2024-10-18T23:23:35+02:00" level=debug msg="completed challenge" Oct 18 23:23:35 volumio go-librespot[30650]: time="2024-10-18T23:23:35+02:00" level=debug msg="authenticated as 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:23:36 volumio volumio[30649]: Traceback (most recent call last): Oct 18 23:23:36 volumio volumio[30649]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:36 volumio volumio[30649]: import ST7789 # v0.0.6 Oct 18 23:23:36 volumio volumio[30649]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="authenticated as 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="dealer connection opened" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="initializing zeroconf session, username: 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:23:36 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:36 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="received connection id: ZTJiNTYwMWItZWFlZS00NGI0LWE2N2MtNGUzZjFkZWI3YjFhK2RlYWxlcit0Y3A6Ly8wYWNhNTkwMi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzdBMkI3RkRGMzA2ODdFMjExRTVCMjFBMzhGNDk3QUEwQzlEQUMwNzNFQUMwQjkwM0I4RDEyNjU5NTZEQzBFOQ==" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 18 23:23:36 volumio go-librespot[30650]: time="2024-10-18T23:23:36+02:00" level=debug msg="autoplay enabled: false" Oct 18 23:23:36 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:36 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509839. Oct 18 23:23:37 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:37 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:37 volumio volumio[1237]: info: Getting Spotify volume Oct 18 23:23:37 volumio volumio[1237]: info: Spotify volume: 100 Oct 18 23:23:37 volumio volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 18 23:23:38 volumio volumio[1237]: info: Initializing connection to go-librespot Websocket Oct 18 23:23:38 volumio go-librespot[30650]: time="2024-10-18T23:23:38+02:00" level=debug msg="new websocket client" Oct 18 23:23:38 volumio volumio[1237]: info: Connection to go-librespot Websocket established Oct 18 23:23:38 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 18 23:23:38 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:39 volumio volumio[30694]: Traceback (most recent call last): Oct 18 23:23:39 volumio volumio[30694]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:39 volumio volumio[30694]: import ST7789 # v0.0.6 Oct 18 23:23:39 volumio volumio[30694]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:40 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:40 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:40 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:40 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509840. Oct 18 23:23:40 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:40 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:41 volumio volumio[1237]: info: Getting Spotify volume Oct 18 23:23:41 volumio volumio[1237]: info: Spotify volume: 100 Oct 18 23:23:41 volumio volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 18 23:23:41 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 18 23:23:41 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:43 volumio volumio[30695]: Traceback (most recent call last): Oct 18 23:23:43 volumio volumio[30695]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:43 volumio volumio[30695]: import ST7789 # v0.0.6 Oct 18 23:23:43 volumio volumio[30695]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:43 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:43 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:43 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:43 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509841. Oct 18 23:23:44 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:44 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:46 volumio volumio[30697]: Traceback (most recent call last): Oct 18 23:23:46 volumio volumio[30697]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:46 volumio volumio[30697]: import ST7789 # v0.0.6 Oct 18 23:23:46 volumio volumio[30697]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:46 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:46 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:47 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:47 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509842. Oct 18 23:23:47 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:47 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces system playlist update Oct 18 23:23:47 volumio volumio[1237]: info: Ignoring MPD Status Update Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces system playlist update Oct 18 23:23:47 volumio volumio[1237]: info: Ignoring MPD Status Update Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces system playlist update Oct 18 23:23:47 volumio volumio[1237]: info: Ignoring MPD Status Update Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces state update: player Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 78ms Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::getState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 64ms Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces state update: player Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::getState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:23:47 volumio volumio[1237]: info: Oct 18 23:23:47 volumio volumio[1237]: ---------------------------- MPD announces state update: player Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::getState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand status Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 95ms Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand status took 43 milliseconds Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand status took 33 milliseconds Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand status took 39 milliseconds Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseState Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 233ms Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 186ms Oct 18 23:23:47 volumio volumio[1237]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 18 23:23:47 volumio volumio[1237]: verbose: ControllerMpd::parseTrackInfo Oct 18 23:23:47 volumio volumio[1237]: info: ControllerMpd::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::servicePushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreStateMachine::pushState Oct 18 23:23:47 volumio volumio[1237]: info: CoreCommandRouter::volumioPushState Oct 18 23:23:47 volumio volumio[1237]: info: ------------------------------ 214ms Oct 18 23:23:47 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:47 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:47 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:23:50 volumio volumio[30698]: Traceback (most recent call last): Oct 18 23:23:50 volumio volumio[30698]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:50 volumio volumio[30698]: import ST7789 # v0.0.6 Oct 18 23:23:50 volumio volumio[30698]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:50 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:50 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:50 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:50 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509843. Oct 18 23:23:50 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:50 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:53 volumio volumio[30699]: Traceback (most recent call last): Oct 18 23:23:53 volumio volumio[30699]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:53 volumio volumio[30699]: import ST7789 # v0.0.6 Oct 18 23:23:53 volumio volumio[30699]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:53 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:53 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:53 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:53 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509844. Oct 18 23:23:54 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:54 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:56 volumio volumio[30700]: Traceback (most recent call last): Oct 18 23:23:56 volumio volumio[30700]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:56 volumio volumio[30700]: import ST7789 # v0.0.6 Oct 18 23:23:56 volumio volumio[30700]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:23:56 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:23:56 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:23:57 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:23:57 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509845. Oct 18 23:23:57 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:23:57 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:23:59 volumio volumio[30701]: Traceback (most recent call last): Oct 18 23:23:59 volumio volumio[30701]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:23:59 volumio volumio[30701]: import ST7789 # v0.0.6 Oct 18 23:23:59 volumio volumio[30701]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:00 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:00 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:00 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:00 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509846. Oct 18 23:24:00 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:00 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:02 volumio volumio[1237]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 18 23:24:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 18 23:24:02 volumio volumio[1237]: info: Creating Spotify config file Oct 18 23:24:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 18 23:24:02 volumio volumio[1237]: info: Spotify config file written Oct 18 23:24:03 volumio sudo[30704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 18 23:24:03 volumio sudo[30704]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 23:24:03 volumio volumio[30702]: Traceback (most recent call last): Oct 18 23:24:03 volumio volumio[30702]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:24:03 volumio volumio[30702]: import ST7789 # v0.0.6 Oct 18 23:24:03 volumio volumio[30702]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:03 volumio volumio[1237]: info: Connection to go-librespot Websocket closed Oct 18 23:24:03 volumio systemd[1]: Stopping go-librespot Daemon... Oct 18 23:24:03 volumio volumio[1237]: info: Connection to go-librespot Websocket closed Oct 18 23:24:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 18 23:24:03 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 18 23:24:03 volumio systemd[1]: Stopped go-librespot Daemon. Oct 18 23:24:03 volumio systemd[1]: Started go-librespot Daemon. Oct 18 23:24:03 volumio sudo[30704]: pam_unix(sudo:session): session closed for user root Oct 18 23:24:03 volumio go-librespot[30706]: Librespot-go daemon starting... Oct 18 23:24:03 volumio go-librespot[30706]: time="2024-10-18T23:24:03+02:00" level=info msg="generated new device id: f6ce33a92402f747e19f18126332183a92252a5e" Oct 18 23:24:03 volumio go-librespot[30706]: time="2024-10-18T23:24:03+02:00" level=debug msg="stored credentials found for 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:24:03 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:03 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:04 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:04 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509847. Oct 18 23:24:04 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:04 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:04 volumio go-librespot[30706]: time="2024-10-18T23:24:04+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 18 23:24:05 volumio go-librespot[30706]: time="2024-10-18T23:24:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 18 23:24:05 volumio go-librespot[30706]: time="2024-10-18T23:24:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 18 23:24:05 volumio go-librespot[30706]: time="2024-10-18T23:24:05+02:00" level=debug msg="zeroconf server listening on port 33597" Oct 18 23:24:05 volumio go-librespot[30706]: time="2024-10-18T23:24:05+02:00" level=debug msg="obtained new client token: AAB7anofYEfdGD1mRdieRG1WnmAztVMKjiSSc7RLgLe88yvcyhh5PaHoAXNOLlnqoJMoK4JZ6BCSMa36DC9IHP228XHGjbkP35WcSwu76I5jP1xygOuqUV5DsyZbZ8Yt5bVYs3gq1Grm8SeazYZb3H/ZUEHrbBTGd7vlraHxW4ZCvGYXqLrp5QMkEQ6JUZJVKknJcwr/dlzmIpVl0QuoI8BPazLsQEMf80gwBCHiU2Qp5cdgh4e1uPn09Hw=" Oct 18 23:24:05 volumio go-librespot[30706]: time="2024-10-18T23:24:05+02:00" level=debug msg="completed keyexchange" Oct 18 23:24:06 volumio go-librespot[30706]: time="2024-10-18T23:24:06+02:00" level=debug msg="completed challenge" Oct 18 23:24:06 volumio go-librespot[30706]: time="2024-10-18T23:24:06+02:00" level=debug msg="authenticated as 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:24:06 volumio volumio[1237]: info: Initializing connection to go-librespot Websocket Oct 18 23:24:06 volumio go-librespot[30706]: time="2024-10-18T23:24:06+02:00" level=debug msg="authenticated as 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:24:07 volumio volumio[1237]: info: go-librespot daemon successfully initialized Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="dealer connection opened" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="initializing zeroconf session, username: 3fvnhn07itfimnm0ojj95tmji" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="received connection id: NTVkNzhlNzctZWY2MC00NTkyLThjMzktZDMxMTM4ZjNlMzcyK2RlYWxlcit0Y3A6Ly8wYWNhNThkOC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQ0I1NTk4NDQyQ0JCMDc4NjU3NDQ0ODA5QUY5NEYwOTNEMzVGRjVDRjRFQTgxQTRDMTBCNjY4QTc1NTE3QkNFRg==" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="new websocket client" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 18 23:24:07 volumio go-librespot[30706]: time="2024-10-18T23:24:07+02:00" level=debug msg="autoplay enabled: false" Oct 18 23:24:07 volumio volumio[1237]: info: Connection to go-librespot Websocket established Oct 18 23:24:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 23:24:08 volumio volumio[1237]: info: Received Get System Info Oct 18 23:24:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 18 23:24:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 18 23:24:08 volumio volumio[1237]: info: Discovery: Getting this device information Oct 18 23:24:08 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 18 23:24:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 18 23:24:09 volumio volumio[30712]: Traceback (most recent call last): Oct 18 23:24:09 volumio volumio[30712]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:24:09 volumio volumio[30712]: import ST7789 # v0.0.6 Oct 18 23:24:09 volumio volumio[30712]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:09 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:09 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:09 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:09 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509848. Oct 18 23:24:10 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:10 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:10 volumio volumio[1237]: info: Initializing connection to go-librespot Websocket Oct 18 23:24:10 volumio go-librespot[30706]: time="2024-10-18T23:24:10+02:00" level=debug msg="new websocket client" Oct 18 23:24:10 volumio volumio[1237]: info: Connection to go-librespot Websocket established Oct 18 23:24:10 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Oct 18 23:24:10 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 18 23:24:10 volumio volumio[1237]: info: CoreCommandRouter::volumioGetQueue Oct 18 23:24:10 volumio volumio[1237]: info: CoreStateMachine::getQueue Oct 18 23:24:10 volumio volumio[1237]: info: CorePlayQueue::getQueue Oct 18 23:24:10 volumio volumio[1237]: info: Listing playlists Oct 18 23:24:11 volumio volumio[1237]: info: Getting Spotify volume Oct 18 23:24:11 volumio volumio[1237]: info: Spotify volume: 100 Oct 18 23:24:11 volumio volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 18 23:24:11 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 18 23:24:11 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:24:13 volumio volumio[30714]: Traceback (most recent call last): Oct 18 23:24:13 volumio volumio[30714]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:24:13 volumio volumio[30714]: import ST7789 # v0.0.6 Oct 18 23:24:13 volumio volumio[30714]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:13 volumio volumio[1237]: info: Getting Spotify volume Oct 18 23:24:13 volumio volumio[1237]: info: Spotify volume: 100 Oct 18 23:24:13 volumio volumio[1237]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 18 23:24:13 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Oct 18 23:24:13 volumio volumio[1237]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 18 23:24:13 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:13 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:13 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:13 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509849. Oct 18 23:24:13 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:13 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:14 volumio volumio[1237]: info: [1729286654568] [80s80s] Pushing the next song state Talking Heads - Burning Down the House and getting next track. Oct 18 23:24:14 volumio volumio[1237]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 23:24:14 volumio volumio[1237]: TypeError: Cannot set property 'name' of undefined Oct 18 23:24:14 volumio volumio[1237]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Oct 18 23:24:14 volumio volumio[1237]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Oct 18 23:24:14 volumio volumio[1237]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Oct 18 23:24:14 volumio volumio[1237]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Oct 18 23:24:14 volumio volumio[1237]: at processImmediate (internal/timers.js:461:21) Oct 18 23:24:14 volumio volumio[1237]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 23:24:16 volumio volumio[30715]: Traceback (most recent call last): Oct 18 23:24:16 volumio volumio[30715]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:24:16 volumio volumio[30715]: import ST7789 # v0.0.6 Oct 18 23:24:16 volumio volumio[30715]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:17 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:17 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:17 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:17 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509850. Oct 18 23:24:17 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:17 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:21 volumio volumio[30724]: Traceback (most recent call last): Oct 18 23:24:21 volumio volumio[30724]: File "/data/plugins/system_hardware/pirateaudio/display.py", line 13, in Oct 18 23:24:21 volumio volumio[30724]: import ST7789 # v0.0.6 Oct 18 23:24:21 volumio volumio[30724]: ModuleNotFoundError: No module named 'ST7789' Oct 18 23:24:21 volumio systemd[1]: pirateaudio.service: Main process exited, code=exited, status=1/FAILURE Oct 18 23:24:21 volumio systemd[1]: pirateaudio.service: Failed with result 'exit-code'. Oct 18 23:24:22 volumio systemd[1]: pirateaudio.service: Service RestartSec=100ms expired, scheduling restart. Oct 18 23:24:22 volumio systemd[1]: pirateaudio.service: Scheduled restart job, restart counter is at 1509851. Oct 18 23:24:22 volumio systemd[1]: Stopped Service for using pirate audio display in volumio. Oct 18 23:24:22 volumio systemd[1]: Started Service for using pirate audio display in volumio. Oct 18 23:24:22 volumio sudo[30727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 23:23 Oct 18 23:24:22 volumio sudo[30727]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"