Dec 27 21:56:07 volumio ntpd[1314]: PROTO: 151.22.209.90 unlink local addr 192.168.1.109 -> Dec 27 21:56:07 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 21:56:07 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 21:56:07 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrums.4 Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------$basefolder Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------screensize1280x400 Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------screenwidth Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------screenheight Dec 27 21:56:07 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 21:56:07 volumio volumio[2869]: error: PeppySpectrum ---Error writing config null Dec 27 21:56:07 volumio sudo[11337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 21:56:07 volumio sudo[11337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:07 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 21:56:08 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:56:08 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:56:08 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 21:56:08 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:08 volumio systemd[1]: peppyspectrum.service: Consumed 3.533s CPU time. Dec 27 21:56:08 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:08 volumio sudo[11337]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:08 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:13 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 21:56:13 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 21:56:13 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrums.6 Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------$basefolder Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------screensize1280x400 Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------screenwidth Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------screenheight Dec 27 21:56:13 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 21:56:13 volumio volumio[2869]: error: PeppySpectrum ---Error writing config null Dec 27 21:56:13 volumio sudo[11360]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 21:56:13 volumio sudo[11360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:13 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 21:56:13 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:56:13 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 21:56:13 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:13 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:56:13 volumio systemd[1]: peppyspectrum.service: Consumed 1.720s CPU time. Dec 27 21:56:13 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:13 volumio sudo[11360]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:13 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:14 volumio startpeppyspectrum.sh[11364]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:23 volumio volumio[2869]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Dec 27 21:56:25 volumio volumio[2869]: verbose: New Socket.io Connection to 192.168.1.109 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetState Dec 27 21:56:25 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetState Dec 27 21:56:25 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 21:56:25 volumio volumio[2869]: info: Received Get System Info Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 21:56:25 volumio volumio[2869]: info: Discovery: Getting this device information Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetState Dec 27 21:56:25 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetState Dec 27 21:56:25 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:25 volumio volumio[2869]: info: Listing playlists Dec 27 21:56:25 volumio volumio[2869]: info: CoreCommandRouter::volumioGetQueue Dec 27 21:56:25 volumio volumio[2869]: info: CoreStateMachine::getQueue Dec 27 21:56:25 volumio volumio[2869]: info: CorePlayQueue::getQueue Dec 27 21:56:32 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Dec 27 21:56:32 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Dec 27 21:56:32 volumio volumio[2869]: aaaaaaaaaaa 800,480 Dec 27 21:56:32 volumio volumio[2869]: info: PeppySpectrum ---800480 Dec 27 21:56:32 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:56:32 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:56:32 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:35 volumio volumio[2869]: info: Prefetching next song Dec 27 21:56:35 volumio volumio[2869]: info: DOING PREFETCH IN MPD Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand add "USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/11.-Smooth Criminal.flac" Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand add "USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/11.-Smooth Criminal.flac" took 1 milliseconds Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 27 21:56:35 volumio volumio[2869]: info: Dec 27 21:56:35 volumio volumio[2869]: ---------------------------- MPD announces system playlist update Dec 27 21:56:35 volumio volumio[2869]: info: Ignoring MPD Status Update Dec 27 21:56:35 volumio volumio[2869]: info: Dec 27 21:56:35 volumio volumio[2869]: ---------------------------- MPD announces system playlist update Dec 27 21:56:35 volumio volumio[2869]: info: Ignoring MPD Status Update Dec 27 21:56:35 volumio volumio[2869]: info: Dec 27 21:56:35 volumio volumio[2869]: ---------------------------- MPD announces state update: options Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand consume 1 took 1 milliseconds Dec 27 21:56:35 volumio volumio[2869]: info: ------------------------------ 1ms Dec 27 21:56:35 volumio volumio[2869]: info: ------------------------------ 1ms Dec 27 21:56:35 volumio volumio[2869]: info: ControllerMpd::getState Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:56:35 volumio volumio[2869]: info: Dec 27 21:56:35 volumio volumio[2869]: ---------------------------- MPD announces state update: options Dec 27 21:56:35 volumio volumio[2869]: info: ControllerMpd::getState Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand status took 1 milliseconds Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand status took 1 milliseconds Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::parseState Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::parseState Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:56:35 volumio volumio[2869]: info: ControllerMpd::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::servicePushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242115,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3066 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Greatest Hits","uri":"USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/10.-Bad.flac","trackType":"flac"} Dec 27 21:56:35 volumio volumio[2869]: verbose: CURRENT POSITION 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::syncState stateService play Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:56:35 volumio volumio[2869]: info: Received an update from plugin. extracting info from payload Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:35 volumio volumio[2869]: info: ------------------------------ 6ms Dec 27 21:56:35 volumio volumio[2869]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 21:56:35 volumio volumio[2869]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:56:35 volumio volumio[2869]: info: ControllerMpd::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::servicePushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":242115,"duration":247,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3066 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Greatest Hits","uri":"USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/10.-Bad.flac","trackType":"flac"} Dec 27 21:56:35 volumio volumio[2869]: verbose: CURRENT POSITION 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::syncState stateService play Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:56:35 volumio volumio[2869]: info: Received an update from plugin. extracting info from payload Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:35 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:35 volumio volumio[2869]: info: CorePlayQueue::getTrack 9 Dec 27 21:56:35 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:35 volumio volumio[2869]: info: ------------------------------ 10ms Dec 27 21:56:35 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:35 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:35 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:35 volumio sudo[11451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:35 volumio sudo[11451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:35 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:35 volumio sudo[11453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:35 volumio sudo[11453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:35 volumio sudo[11456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:35 volumio sudo[11456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:35 volumio sudo[11459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:35 volumio sudo[11459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:35 volumio sudo[11456]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:35 volumio sudo[11459]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:35 volumio sudo[11453]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:35 volumio sudo[11451]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:35 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:35 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:35 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:35 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:35 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 27 21:56:36 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 21:56:36 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 21:56:36 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrums.1 Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------$basefolder Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------screensize800x480 Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------screenwidth Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------screenheight Dec 27 21:56:36 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 21:56:36 volumio volumio[2869]: error: PeppySpectrum ---Error writing config null Dec 27 21:56:36 volumio sudo[11498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 21:56:36 volumio sudo[11498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:36 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 21:56:36 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:56:36 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:56:36 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 21:56:36 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:36 volumio systemd[1]: peppyspectrum.service: Consumed 5.158s CPU time. Dec 27 21:56:36 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:36 volumio sudo[11498]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:36 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:36 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 27 21:56:36 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 27 21:56:36 volumio startpeppyspectrum.sh[11501]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:36 volumio startpeppyspectrum.sh[11501]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:36 volumio startpeppyspectrum.sh[11501]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:39 volumio volumio[2869]: info: CoreStateMachine::startPlaybackTimer Dec 27 21:56:39 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:40 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:40 volumio sudo[11523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:40 volumio sudo[11523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:40 volumio sudo[11523]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:40 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:40 volumio volumio[2869]: info: Dec 27 21:56:40 volumio volumio[2869]: ---------------------------- MPD announces system playlist update Dec 27 21:56:40 volumio volumio[2869]: info: Ignoring MPD Status Update Dec 27 21:56:40 volumio volumio[2869]: info: Dec 27 21:56:40 volumio volumio[2869]: ---------------------------- MPD announces state update: player Dec 27 21:56:40 volumio volumio[2869]: info: ControllerMpd::getState Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:56:40 volumio volumio[2869]: info: Dec 27 21:56:40 volumio volumio[2869]: ---------------------------- MPD announces system playlist update Dec 27 21:56:40 volumio volumio[2869]: info: Ignoring MPD Status Update Dec 27 21:56:40 volumio volumio[2869]: info: Dec 27 21:56:40 volumio volumio[2869]: ---------------------------- MPD announces state update: player Dec 27 21:56:40 volumio volumio[2869]: info: ControllerMpd::getState Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand status Dec 27 21:56:40 volumio volumio[2869]: info: ------------------------------ 1ms Dec 27 21:56:40 volumio volumio[2869]: info: sendMpdCommand status took 1 milliseconds Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::parseState Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:56:40 volumio volumio[2869]: info: ------------------------------ 1ms Dec 27 21:56:40 volumio volumio[2869]: info: sendMpdCommand status took 1 milliseconds Dec 27 21:56:40 volumio volumio[2869]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::parseState Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:56:40 volumio volumio[2869]: info: ControllerMpd::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::servicePushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":260,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2313 Kbps","isStreaming":false,"title":"Smooth Criminal","artist":"Michael Jackson","album":"Greatest Hits","uri":"USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/11.-Smooth Criminal.flac","trackType":"flac"} Dec 27 21:56:40 volumio volumio[2869]: verbose: CURRENT POSITION 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::syncState stateService play Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:56:40 volumio volumio[2869]: info: Received an update from plugin. extracting info from payload Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:40 volumio volumio[2869]: info: ------------------------------ 7ms Dec 27 21:56:40 volumio volumio[2869]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 21:56:40 volumio volumio[2869]: verbose: ControllerMpd::parseTrackInfo Dec 27 21:56:40 volumio volumio[2869]: info: ControllerMpd::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::servicePushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":260,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2313 Kbps","isStreaming":false,"title":"Smooth Criminal","artist":"Michael Jackson","album":"Greatest Hits","uri":"USB/Salvatore/musica/Michael Jackson - Greatest Hits (24Bit-96kHz) [FLAC] vtwin88cube/11.-Smooth Criminal.flac","trackType":"flac"} Dec 27 21:56:40 volumio volumio[2869]: verbose: CURRENT POSITION 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::syncState stateService play Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::syncState currentStatus play Dec 27 21:56:40 volumio volumio[2869]: info: Received an update from plugin. extracting info from payload Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:40 volumio volumio[2869]: info: CoreStateMachine::pushState Dec 27 21:56:40 volumio volumio[2869]: info: CorePlayQueue::getTrack 10 Dec 27 21:56:40 volumio volumio[2869]: info: CoreCommandRouter::volumioPushState Dec 27 21:56:40 volumio volumio[2869]: info: ------------------------------ 11ms Dec 27 21:56:40 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:40 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:40 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:40 volumio sudo[11526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:40 volumio sudo[11526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:40 volumio volumio[2869]: info: PeppySpectrum ---peppyspectrum status play Dec 27 21:56:40 volumio sudo[11528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:40 volumio sudo[11528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:40 volumio sudo[11531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:40 volumio sudo[11531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:40 volumio sudo[11534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 21:56:40 volumio sudo[11534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:40 volumio sudo[11528]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:40 volumio sudo[11531]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:40 volumio sudo[11526]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:40 volumio sudo[11534]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:40 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:40 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:40 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:40 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:53 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 21:56:53 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 21:56:53 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrums.2 Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------$basefolder Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------screensize800x480 Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------screenwidth Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------screenheight Dec 27 21:56:53 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 21:56:53 volumio volumio[2869]: error: PeppySpectrum ---Error writing config null Dec 27 21:56:53 volumio sudo[11582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 21:56:53 volumio sudo[11582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:56:53 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 21:56:53 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:56:53 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:56:54 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 21:56:54 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:54 volumio systemd[1]: peppyspectrum.service: Consumed 3.456s CPU time. Dec 27 21:56:54 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:56:54 volumio sudo[11582]: pam_unix(sudo:session): session closed for user root Dec 27 21:56:54 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:56:54 volumio startpeppyspectrum.sh[11586]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:54 volumio startpeppyspectrum.sh[11586]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:56:54 volumio startpeppyspectrum.sh[11586]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:57:02 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 21:57:02 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 21:57:02 volumio volumio[2869]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrums.3 Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------$basefolder Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------screensize800x480 Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------screenwidth Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------screenheight Dec 27 21:57:02 volumio volumio[2869]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 21:57:02 volumio volumio[2869]: error: PeppySpectrum ---Error writing config null Dec 27 21:57:02 volumio sudo[11625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 21:57:02 volumio sudo[11625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 21:57:02 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 21:57:02 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:57:02 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:57:02 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 21:57:02 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:57:02 volumio systemd[1]: peppyspectrum.service: Consumed 1.977s CPU time. Dec 27 21:57:02 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 21:57:02 volumio sudo[11625]: pam_unix(sudo:session): session closed for user root Dec 27 21:57:02 volumio volumio[2869]: info: peppyspectrum Daemon Started Dec 27 21:57:03 volumio startpeppyspectrum.sh[11628]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:57:03 volumio startpeppyspectrum.sh[11628]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:57:03 volumio startpeppyspectrum.sh[11628]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 21:57:21 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 27 21:57:21 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 27 21:57:21 volumio volumio[2869]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 27 21:57:22 volumio volumio[2869]: --2025-12-27 21:57:21-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 27 21:57:22 volumio volumio[2869]: Resolving github.com (github.com)... 140.82.121.3 Dec 27 21:57:22 volumio volumio[2869]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 27 21:57:22 volumio volumio[2869]: HTTP request sent, awaiting response... 302 Found Dec 27 21:57:22 volumio volumio[2869]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 27 21:57:22 volumio volumio[2869]: --2025-12-27 21:57:22-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 27 21:57:22 volumio volumio[2869]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.108.133, 185.199.109.133, ... Dec 27 21:57:22 volumio volumio[2869]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. Dec 27 21:57:22 volumio volumio[2869]: HTTP request sent, awaiting response... 200 OK Dec 27 21:57:22 volumio volumio[2869]: Length: 471 [text/plain] Dec 27 21:57:22 volumio volumio[2869]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 27 21:57:22 volumio volumio[2869]: 0K 100% 17.1M=0s Dec 27 21:57:22 volumio volumio[2869]: 2025-12-27 21:57:22 (17.1 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 27 21:57:22 volumio volumio[2869]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 21:57:22 volumio volumio[2869]: info: CoreCommandRouter::Close All Modals sent Dec 27 21:57:25 volumio volumio[2869]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 27 21:57:25 volumio volumio[2869]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 27 21:57:27 volumio volumio[2869]: --2025-12-27 21:57:25-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 27 21:57:27 volumio volumio[2869]: Resolving github.com (github.com)... 140.82.121.3 Dec 27 21:57:27 volumio volumio[2869]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 27 21:57:27 volumio volumio[2869]: HTTP request sent, awaiting response... 404 Not Found Dec 27 21:57:27 volumio volumio[2869]: 2025-12-27 21:57:26 ERROR 404: Not Found. Dec 27 21:57:27 volumio volumio[2869]: --2025-12-27 21:57:26-- http://a/ Dec 27 21:57:27 volumio volumio[2869]: Resolving a (a)... 127.0.0.1 Dec 27 21:57:27 volumio volumio[2869]: Connecting to a (a)|127.0.0.1|:80... failed: Connection refused. Dec 27 21:57:27 volumio volumio[2869]: --2025-12-27 21:57:26-- http://packet.zip/ Dec 27 21:57:27 volumio volumio[2869]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 27 21:57:27 volumio volumio[2869]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 27 21:57:27 volumio volumio[2869]: HTTP request sent, awaiting response... 302 Found Dec 27 21:57:27 volumio volumio[2869]: Location: http://www.packet.zip/ [following] Dec 27 21:57:27 volumio volumio[2869]: --2025-12-27 21:57:27-- http://www.packet.zip/ Dec 27 21:57:27 volumio volumio[2869]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 27 21:57:27 volumio volumio[2869]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 27 21:57:27 volumio volumio[2869]: HTTP request sent, awaiting response... 403 Forbidden Dec 27 21:57:27 volumio volumio[2869]: 2025-12-27 21:57:27 ERROR 403: Forbidden. Dec 27 21:57:27 volumio volumio[2869]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove 'a': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 21:57:27 volumio volumio[2869]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove 'a': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 27 21:57:27 volumio volumio[2869]: at checkExecSyncError (node:child_process:887:11) Dec 27 21:57:27 volumio volumio[2869]: at execSync (node:child_process:959:15) Dec 27 21:57:27 volumio volumio[2869]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 27 21:57:27 volumio volumio[2869]: at new Promise () Dec 27 21:57:27 volumio volumio[2869]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 27 21:57:27 volumio volumio[2869]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 27 21:57:27 volumio volumio[2869]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 27 21:57:27 volumio volumio[2869]: at Socket.emit (node:events:514:28) Dec 27 21:57:27 volumio volumio[2869]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 27 21:57:27 volumio volumio[2869]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 27 21:57:27 volumio volumio[2869]: status: 1, Dec 27 21:57:27 volumio volumio[2869]: signal: null, Dec 27 21:57:27 volumio volumio[2869]: output: [ Dec 27 21:57:27 volumio volumio[2869]: null, Dec 27 21:57:27 volumio volumio[2869]: , Dec 27 21:57:27 volumio volumio[2869]: Dec 27 21:57:27 volumio volumio[2869]: ], Dec 27 21:57:27 volumio volumio[2869]: pid: 11738, Dec 27 21:57:27 volumio volumio[2869]: stdout: , Dec 27 21:57:27 volumio volumio[2869]: stderr: Dec 27 21:57:27 volumio volumio[2869]: } Dec 27 21:57:27 volumio volumio[2869]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 21:57:27 volumio sudo[11755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 21:56' Dec 27 21:57:27 volumio sudo[11755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"