-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-09-07 18:38:29 CEST. -- Sep 07 18:37:01 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:37:01 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:37:01 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:37:01 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:37:01 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="handling pause player command from ba83eb36b94ab0346b2c0af23f82f09508a178db" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="pause track at 0ms" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="sending successful reply for dealer request" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 242" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=trace msg="emitting websocket event: paused" Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:02 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:02 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:02 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status pause Sep 07 18:37:02 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status pause Sep 07 18:37:02 pi-z83 sudo[3458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 07 18:37:02 pi-z83 sudo[3458]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:02 pi-z83 sudo[3460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 07 18:37:02 pi-z83 sudo[3460]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:02 pi-z83 sudo[3458]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:02 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Sep 07 18:37:02 pi-z83 volumio[961]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Sep 07 18:37:02 pi-z83 sudo[3460]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:02 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Sep 07 18:37:02 pi-z83 volumio[961]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="handling resume player command from ba83eb36b94ab0346b2c0af23f82f09508a178db" Sep 07 18:37:02 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:02+02:00" level=debug msg="resume track at 0ms" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=trace msg="scheduling prefetch in 110s" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="sending successful reply for dealer request" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=trace msg="emitting websocket event: playing" Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:03 pi-z83 sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:03 pi-z83 sudo[3471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 sudo[3468]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:03 pi-z83 sudo[3471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:03 pi-z83 sudo[3471]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:03 pi-z83 volumio[961]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:03 pi-z83 sudo[3476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 sudo[3476]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:03 pi-z83 sudo[3476]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:03 pi-z83 sudo[3479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 sudo[3479]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:03 pi-z83 sudo[3479]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:03 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:03 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="handling seek_to player command from ba83eb36b94ab0346b2c0af23f82f09508a178db" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="seek track to 0ms" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=trace msg="scheduling prefetch in 110s" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=trace msg="emitting websocket event: seek" Sep 07 18:37:03 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:03+02:00" level=debug msg="sending successful reply for dealer request" Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","position":0,"duration":140106,"play_origin":"playlist"}} Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: 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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","position":0,"duration":140106,"play_origin":"playlist"}} Sep 07 18:37:03 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:03 pi-z83 volumio[961]: 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"} Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:03 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:04 pi-z83 sudo[3484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 sudo[3484]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:04 pi-z83 sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:04 pi-z83 sudo[3484]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:04 pi-z83 sudo[3486]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=debug msg="handling seek_to player command from ba83eb36b94ab0346b2c0af23f82f09508a178db" Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=debug msg="seek track to 0ms" Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=trace msg="scheduling prefetch in 110s" Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=trace msg="emitting websocket event: seek" Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","position":0,"duration":140106,"play_origin":"playlist"}} Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:04 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:04+02:00" level=debug msg="sending successful reply for dealer request" Sep 07 18:37:04 pi-z83 volumio[961]: 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"} Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: received: {"type":"seek","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","position":0,"duration":140106,"play_origin":"playlist"}} Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:04 pi-z83 volumio[961]: 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"} Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:04 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:04 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status play Sep 07 18:37:04 pi-z83 sudo[3490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 sudo[3490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:04 pi-z83 sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:04 pi-z83 sudo[3490]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:04 pi-z83 sudo[3493]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:04 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Sep 07 18:37:04 pi-z83 volumio[961]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=debug msg="handling pause player command from ba83eb36b94ab0346b2c0af23f82f09508a178db" Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=debug msg="pause track at 0ms" Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=debug msg="sending successful reply for dealer request" Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 07 18:37:06 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:06+02:00" level=trace msg="emitting websocket event: paused" Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":2000,"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"} Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5GCU2LJWPzoP8Q6nexs61z","play_origin":"playlist"}} Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: PUSH STATE SPOTIFY Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":2000,"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"} Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreCommandRouter::servicePushState Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreStateMachine::pushState Sep 07 18:37:06 pi-z83 volumio[961]: info: CoreCommandRouter::volumioPushState Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:06 pi-z83 volumio[961]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Sep 07 18:37:06 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status pause Sep 07 18:37:06 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum status pause Sep 07 18:37:06 pi-z83 sudo[3502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 07 18:37:06 pi-z83 sudo[3502]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:06 pi-z83 sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Sep 07 18:37:06 pi-z83 sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:06 pi-z83 sudo[3502]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:06 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Sep 07 18:37:06 pi-z83 volumio[961]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Sep 07 18:37:06 pi-z83 sudo[3505]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:06 pi-z83 volumio[961]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service Sep 07 18:37:06 pi-z83 volumio[961]: Failed to stop peppyspectrum.service: Unit peppyspectrum.service not loaded. Sep 07 18:37:06 pi-z83 volumio[961]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 07 18:37:09 pi-z83 volumio[961]: info: Starting Uninstall of plugin music_service - spop Sep 07 18:37:09 pi-z83 volumio[961]: info: Uninstalling plugin spop Sep 07 18:37:09 pi-z83 go-librespot[3015]: time="2024-09-07T18:37:09+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 07 18:37:09 pi-z83 volumio[961]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 07 18:37:09 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 18:37:09 pi-z83 sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Sep 07 18:37:09 pi-z83 sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:09 pi-z83 volumio[961]: info: Disabling plugin spop Sep 07 18:37:09 pi-z83 systemd[1]: Stopping go-librespot Daemon... Sep 07 18:37:09 pi-z83 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 07 18:37:09 pi-z83 volumio[961]: info: Checking if uninstall.sh is present Sep 07 18:37:09 pi-z83 systemd[1]: go-librespot-daemon.service: Succeeded. Sep 07 18:37:09 pi-z83 systemd[1]: Stopped go-librespot Daemon. Sep 07 18:37:09 pi-z83 sudo[3516]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:09 pi-z83 volumio[961]: info: Connection to go-librespot Websocket closed Sep 07 18:37:09 pi-z83 volumio[961]: info: Executing uninstall.sh Sep 07 18:37:09 pi-z83 volumio[961]: info: Connection to go-librespot Websocket closed Sep 07 18:37:09 pi-z83 sudo[3520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Sep 07 18:37:09 pi-z83 sudo[3520]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:37:10 pi-z83 sudo[3520]: pam_unix(sudo:session): session closed for user root Sep 07 18:37:10 pi-z83 volumio[961]: info: Uninstall script completed Sep 07 18:37:10 pi-z83 volumio[961]: info: Removing plugin spop from configuration Sep 07 18:37:14 pi-z83 volumio[961]: info: Successfully removed spop configuration files Sep 07 18:37:14 pi-z83 volumio[961]: info: Plugin folders cleanup Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning into folder /volumio/app/plugins/ Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category audio_interface Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category miscellanea Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category music_service Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category plugins.json Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category system_controller Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category user_interface Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning into folder /data/plugins/ Sep 07 18:37:14 pi-z83 volumio[961]: info: Scanning category music_service Sep 07 18:37:14 pi-z83 volumio[961]: info: Cleaning folder for spop Sep 07 18:37:15 pi-z83 volumio[961]: info: Scanning category user_interface Sep 07 18:37:15 pi-z83 volumio[961]: info: Plugin folders cleanup completed Sep 07 18:37:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 18:37:15 pi-z83 volumio[961]: info: [{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 07 18:37:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 07 18:37:15 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:37:15 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:37:18 pi-z83 volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 07 18:37:28 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:37:28 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:37:28 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:37:28 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:37:28 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:37:29 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193:3000 from 192.168.1.135 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Sep 07 18:37:30 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 18:37:30 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 07 18:37:38 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:37:38 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:37:38 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:37:38 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:37:38 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:37:39 pi-z83 volumio[961]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 07 18:37:40 pi-z83 volumio[961]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 07 18:37:40 pi-z83 volumio[961]: info: Folder /tmp/plugins removed Sep 07 18:37:41 pi-z83 volumio[961]: info: Check plugin dependencies Sep 07 18:37:41 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 18:37:41 pi-z83 volumio[961]: info: Checking if plugin already exists Sep 07 18:37:41 pi-z83 volumio[961]: info: Rename folder Sep 07 18:37:41 pi-z83 volumio[961]: info: Folder /tmp/downloaded_plugin.zip removed Sep 07 18:37:41 pi-z83 volumio[961]: info: Move to category Sep 07 18:38:02 pi-z83 volumio-remote-updater[613]: [2024-09-07 18:38:02] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 07 18:38:07 pi-z83 volumio-remote-updater[613]: [2024-09-07 18:38:07] [connect] Successful connection Sep 07 18:38:10 pi-z83 volumio[961]: info: Checking if install.sh is present Sep 07 18:38:10 pi-z83 volumio[961]: info: Executing install.sh Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.177 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:130.0) Gecko/20100101 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 3 Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.177 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:130.0) Gecko/20100101 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 07 18:38:10 pi-z83 volumio-remote-updater[613]: [2024-09-07 18:38:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725727087 101 Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Sep 07 18:38:10 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.177 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:130.0) Gecko/20100101 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 7 Sep 07 18:38:10 pi-z83 sudo[3643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Sep 07 18:38:10 pi-z83 sudo[3643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: Upnp client error: Error: This socket has been ended by the other party Sep 07 18:38:10 pi-z83 volumio[961]: info: Installing Go-librespot Sep 07 18:38:10 pi-z83 volumio[961]: info: Checking old vollibrespot installs Sep 07 18:38:10 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:38:10 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:38:10 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:38:10 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:10 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:38:10 pi-z83 systemd[1]: Reloading. Sep 07 18:38:11 pi-z83 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 07 18:38:11 pi-z83 volumio[961]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 07 18:38:11 pi-z83 volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 18:38:11 pi-z83 volumio[961]: info: Received Get System Info Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:38:11 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:11 pi-z83 volumio[961]: info: Listing playlists Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:11 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 07 18:38:11 pi-z83 volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 07 18:38:11 pi-z83 volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 07 18:38:11 pi-z83 volumio[961]: info: Dowloading daemon Sep 07 18:38:12 pi-z83 volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Sep 07 18:38:13 pi-z83 volumio[961]: info: Creating Start Script Sep 07 18:38:13 pi-z83 systemd[1]: Reloading. Sep 07 18:38:13 pi-z83 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 07 18:38:14 pi-z83 sudo[3643]: pam_unix(sudo:session): session closed for user root Sep 07 18:38:14 pi-z83 volumio[961]: Plugin install end detected on script Sep 07 18:38:14 pi-z83 volumio[961]: info: Install script completed Sep 07 18:38:14 pi-z83 volumio[961]: info: Adding reference to registry Sep 07 18:38:14 pi-z83 volumio[961]: info: Done installing plugin. Sep 07 18:38:14 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 18:38:14 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 07 18:38:14 pi-z83 volumio[961]: info: Folder /tmp/plugins removed Sep 07 18:38:14 pi-z83 volumio[961]: info: Folder /tmp/downloaded_plugin.zip removed Sep 07 18:38:14 pi-z83 volumio[961]: info: Folder /data/temp removed Sep 07 18:38:15 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.177 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:130.0) Gecko/20100101 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 13 Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetVisibleSources Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 18:38:15 pi-z83 volumio[961]: info: Received Get System Info Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 18:38:15 pi-z83 volumio[961]: info: Discovery: Getting this device information Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetState Sep 07 18:38:15 pi-z83 volumio[961]: info: Listing playlists Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::volumioGetQueue Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreStateMachine::getQueue Sep 07 18:38:15 pi-z83 volumio[961]: info: CorePlayQueue::getQueue Sep 07 18:38:15 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 07 18:38:16 pi-z83 volumio[961]: verbose: New Socket.io Connection to 192.168.1.193:3000 from 192.168.1.135 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Sep 07 18:38:24 pi-z83 volumio[961]: info: Starting Uninstall of plugin music_service - spop Sep 07 18:38:24 pi-z83 volumio[961]: info: Uninstalling plugin spop Sep 07 18:38:24 pi-z83 volumio[961]: info: Disabling plugin spop Sep 07 18:38:24 pi-z83 volumio[961]: info: Checking if uninstall.sh is present Sep 07 18:38:24 pi-z83 volumio[961]: info: Executing uninstall.sh Sep 07 18:38:24 pi-z83 sudo[3715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Sep 07 18:38:24 pi-z83 sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 18:38:24 pi-z83 sudo[3715]: pam_unix(sudo:session): session closed for user root Sep 07 18:38:24 pi-z83 volumio[961]: info: Uninstall script completed Sep 07 18:38:24 pi-z83 volumio[961]: info: Removing plugin spop from configuration Sep 07 18:38:25 pi-z83 volumio[961]: info: Successfully removed spop configuration files Sep 07 18:38:25 pi-z83 volumio[961]: info: Plugin folders cleanup Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning into folder /volumio/app/plugins/ Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category audio_interface Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category miscellanea Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category music_service Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category plugins.json Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category system_controller Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category user_interface Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning into folder /data/plugins/ Sep 07 18:38:25 pi-z83 volumio[961]: info: Scanning category music_service Sep 07 18:38:25 pi-z83 volumio[961]: info: Cleaning folder for spop Sep 07 18:38:26 pi-z83 volumio[961]: info: Scanning category user_interface Sep 07 18:38:26 pi-z83 volumio[961]: info: Plugin folders cleanup completed Sep 07 18:38:26 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 18:38:26 pi-z83 volumio[961]: info: [{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.35","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 07 18:38:26 pi-z83 volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 07 18:38:28 pi-z83 volumio[961]: info: Starting Uninstall of plugin user_interface - peppyspectrum Sep 07 18:38:28 pi-z83 volumio[961]: info: Uninstalling plugin peppyspectrum Sep 07 18:38:28 pi-z83 volumio[961]: info: Stopping peppyspectrum service Sep 07 18:38:28 pi-z83 volumio[961]: info: CoreStateMachine::stop Sep 07 18:38:28 pi-z83 volumio[961]: info: CoreStateMachine::serviceStop Sep 07 18:38:28 pi-z83 volumio[961]: info: CoreCommandRouter::serviceStop Sep 07 18:38:28 pi-z83 volumio[961]: error: WARNING: No stop method for service spop Sep 07 18:38:28 pi-z83 volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 18:38:28 pi-z83 volumio[961]: TypeError: Cannot read property 'then' of undefined Sep 07 18:38:28 pi-z83 volumio[961]: at peppyspectrum.onStop (/data/plugins/user_interface/peppyspectrum/index.js:60:43) Sep 07 18:38:28 pi-z83 volumio[961]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 07 18:38:28 pi-z83 volumio[961]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Sep 07 18:38:28 pi-z83 volumio[961]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Sep 07 18:38:28 pi-z83 volumio[961]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Sep 07 18:38:28 pi-z83 volumio[961]: at Socket.emit (events.js:315:20) Sep 07 18:38:28 pi-z83 volumio[961]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 07 18:38:28 pi-z83 volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 07 18:38:28 pi-z83 volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 18:38:29 pi-z83 sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 18:37 Sep 07 18:38:29 pi-z83 sudo[3738]: 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"