May 25 21:43:08 volumio-pixy systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 25 21:43:08 volumio-pixy dbus-daemon[651]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.119' (uid=0 pid=9557 comm="timedatectl show --property=NTPSynchronized --valu") May 25 21:43:08 volumio-pixy systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 25 21:43:09 volumio-pixy dbus-daemon[651]: [system] Successfully activated service 'org.freedesktop.timedate1' May 25 21:43:09 volumio-pixy systemd[1]: Started systemd-timedated.service - Time & Date Service. May 25 21:43:09 volumio-pixy setdatetime-helper.sh[9556]: Time is already synchronized. May 25 21:43:09 volumio-pixy systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 25 21:43:09 volumio-pixy systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 25 21:43:26 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::ClearQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::stPlaybackTimer May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::serviceStop May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::serviceStop May 25 21:43:26 volumio-pixy volumio[2608]: info: [1748202206194] ControllerWebradio::stop May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::clearPlayQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::addQueueItems May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::addQueueItems May 25 21:43:26 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:43:26 volumio-pixy volumio[2608]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1849353 May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPlay May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::play index 0 May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::play index undefined May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::startPlaybackTimer May 25 21:43:26 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:26 volumio-pixy volumio[2608]: info: [1748202206204] ControllerWebradio::clearAddPlayTrack May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:43:26 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status stop May 25 21:43:26 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is not playing May 25 21:43:26 volumio-pixy volumio[2608]: info: FusionDsp - Clipped samples monitor stopped May 25 21:43:26 volumio-pixy volumio[2608]: info: May 25 21:43:26 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 31 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 26 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand clear May 25 21:43:26 volumio-pixy volumio[2608]: info: May 25 21:43:26 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:26 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand status took 2 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand clear took 2 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1849353" May 25 21:43:26 volumio-pixy volumio[2608]: error: updateQueue error: null May 25 21:43:26 volumio-pixy volumio[2608]: info: ------------------------------ 16ms May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 14 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:26 volumio-pixy volumio[2608]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 21:43:26 volumio-pixy volumio[2608]: info: ------------------------------ 26ms May 25 21:43:26 volumio-pixy volumio[2608]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 21:43:26 volumio-pixy sudo[9576]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:26 volumio-pixy sudo[9576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 25 21:43:26 volumio-pixy sudo[9576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:26 volumio-pixy systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 25 21:43:26 volumio-pixy systemd[1]: peppymeterbasic.service: Deactivated successfully. May 25 21:43:26 volumio-pixy systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:43:26 volumio-pixy systemd[1]: peppymeterbasic.service: Consumed 5min 30.120s CPU time. May 25 21:43:26 volumio-pixy sudo[9576]: pam_unix(sudo:session): session closed for user root May 25 21:43:26 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Stop May 25 21:43:26 volumio-pixy volumio[2608]: info: May 25 21:43:26 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:26 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1849353" took 231 milliseconds May 25 21:43:26 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 21:43:26 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand play May 25 21:43:26 volumio-pixy volumio[2608]: info: ------------------------------ 2ms May 25 21:43:26 volumio-pixy volumio[2608]: info: sendMpdCommand play took 2 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: info: May 25 21:43:27 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate from file: 44100 May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 21:43:27 volumio-pixy volumio[2608]: info: May 25 21:43:27 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand status took 8 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand status took 2 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:27 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"trance_64","artist":"1.FM - Absolute Trance Euro (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/trance_64","trackType":""} May 25 21:43:27 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus stop May 25 21:43:27 volumio-pixy volumio[2608]: info: ------------------------------ 12ms May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:27 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"trance_64","artist":"1.FM - Absolute Trance Euro (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/trance_64","trackType":""} May 25 21:43:27 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:43:27 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:27 volumio-pixy volumio[2608]: info: ------------------------------ 16ms May 25 21:43:27 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:27 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:27 volumio-pixy volumio[2608]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:27 volumio-pixy volumio[2608]: info: May 25 21:43:27 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:27 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:27 volumio-pixy volumio[2608]: info: May 25 21:43:27 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:27 volumio-pixy volumio[2608]: info: ------------------------------ 4ms May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand status took 5 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:27 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 21:43:27 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:27 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:27 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1205,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Arnej - They Need Us (club edit)","artist":"1.FM - Absolute Trance (Euro) Radio","album":null,"uri":"http://185.33.21.112:80/trance_64","trackType":""} May 25 21:43:27 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:43:27 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:27 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:27 volumio-pixy volumio[2608]: info: ------------------------------ 32ms May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:43:27 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:43:27 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:27 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:27 volumio-pixy sudo[9594]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:27 volumio-pixy sudo[9594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:27 volumio-pixy sudo[9594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:28 volumio-pixy sudo[9596]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:28 volumio-pixy sudo[9596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:28 volumio-pixy sudo[9596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:28 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:28 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:28 volumio-pixy systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:43:28 volumio-pixy sudo[9600]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:28 volumio-pixy sudo[9594]: pam_unix(sudo:session): session closed for user root May 25 21:43:28 volumio-pixy sudo[9600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:28 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:28 volumio-pixy sudo[9600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:28 volumio-pixy sudo[9603]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:28 volumio-pixy sudo[9603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:28 volumio-pixy sudo[9603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:28 volumio-pixy sudo[9596]: pam_unix(sudo:session): session closed for user root May 25 21:43:28 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:28 volumio-pixy sudo[9600]: pam_unix(sudo:session): session closed for user root May 25 21:43:28 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:28 volumio-pixy sudo[9603]: pam_unix(sudo:session): session closed for user root May 25 21:43:28 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:29 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 21:43:29 volumio-pixy volumio[2608]: info: Received Get System Info May 25 21:43:29 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:43:29 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:43:29 volumio-pixy volumio[2608]: info: Discovery: Getting this device information May 25 21:43:29 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioGetState May 25 21:43:29 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:43:29 volumio-pixy volumio[9606]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:43:29 volumio-pixy volumio[9606]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:43:33 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::ClearQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::stPlaybackTimer May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::serviceStop May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::serviceStop May 25 21:43:33 volumio-pixy volumio[2608]: info: [1748202213463] ControllerWebradio::stop May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::clearPlayQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::addQueueItems May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::addQueueItems May 25 21:43:33 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:43:33 volumio-pixy volumio[2608]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668 May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPlay May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::play index 0 May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::play index undefined May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::startPlaybackTimer May 25 21:43:33 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:33 volumio-pixy volumio[2608]: info: [1748202213476] ControllerWebradio::clearAddPlayTrack May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:43:33 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status stop May 25 21:43:33 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is not playing May 25 21:43:33 volumio-pixy volumio[2608]: info: FusionDsp - Clipped samples monitor stopped May 25 21:43:33 volumio-pixy volumio[2608]: info: May 25 21:43:33 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 35 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 22 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand clear May 25 21:43:33 volumio-pixy volumio[2608]: info: May 25 21:43:33 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:33 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand status took 29 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand clear took 30 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668" May 25 21:43:33 volumio-pixy volumio[2608]: error: updateQueue error: null May 25 21:43:33 volumio-pixy volumio[2608]: info: ------------------------------ 5ms May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:33 volumio-pixy volumio[2608]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 21:43:33 volumio-pixy volumio[2608]: info: ------------------------------ 36ms May 25 21:43:33 volumio-pixy volumio[2608]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 21:43:33 volumio-pixy sudo[9638]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:33 volumio-pixy sudo[9638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 25 21:43:33 volumio-pixy sudo[9638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:33 volumio-pixy systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 25 21:43:33 volumio-pixy volumio[2608]: info: May 25 21:43:33 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:33 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1783668" took 110 milliseconds May 25 21:43:33 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 21:43:33 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand play May 25 21:43:33 volumio-pixy volumio[2608]: info: ------------------------------ 2ms May 25 21:43:33 volumio-pixy volumio[2608]: info: sendMpdCommand play took 1 milliseconds May 25 21:43:33 volumio-pixy systemd[1]: peppymeterbasic.service: Deactivated successfully. May 25 21:43:33 volumio-pixy systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:43:33 volumio-pixy systemd[1]: peppymeterbasic.service: Consumed 3.873s CPU time. May 25 21:43:33 volumio-pixy sudo[9638]: pam_unix(sudo:session): session closed for user root May 25 21:43:33 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Stop May 25 21:43:34 volumio-pixy kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 5 May 25 21:43:34 volumio-pixy kernel: xhci_hcd 0000:01:00.0: @0000000428d1eb60 00000000 00000000 0e000000 02068000 May 25 21:43:35 volumio-pixy volumio[2608]: info: May 25 21:43:35 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate from file: 44100 May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 21:43:35 volumio-pixy volumio[2608]: info: May 25 21:43:35 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand status took 7 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand status took 1 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 2 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:35 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} May 25 21:43:35 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus stop May 25 21:43:35 volumio-pixy volumio[2608]: info: ------------------------------ 10ms May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:35 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"x_128","artist":"1.FM - Alternative Rock X Hits (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} May 25 21:43:35 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:43:35 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:35 volumio-pixy volumio[2608]: info: ------------------------------ 19ms May 25 21:43:35 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:35 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:35 volumio-pixy volumio[2608]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:35 volumio-pixy volumio[2608]: info: May 25 21:43:35 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:35 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:35 volumio-pixy volumio[2608]: info: May 25 21:43:35 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:35 volumio-pixy volumio[2608]: info: ------------------------------ 2ms May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand status took 2 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:35 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 21:43:35 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:35 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:35 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1159,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Limp Bizkit - Take a Look Around","artist":"1.FM - Alternative Rock X Hits Radio","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} May 25 21:43:35 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:43:35 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:35 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:35 volumio-pixy volumio[2608]: info: ------------------------------ 21ms May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:43:35 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:35 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:35 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:35 volumio-pixy sudo[9655]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:35 volumio-pixy sudo[9655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:35 volumio-pixy sudo[9655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:35 volumio-pixy sudo[9657]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:35 volumio-pixy sudo[9657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:35 volumio-pixy sudo[9657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:35 volumio-pixy sudo[9661]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:35 volumio-pixy sudo[9661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:35 volumio-pixy sudo[9661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:35 volumio-pixy sudo[9663]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:35 volumio-pixy sudo[9663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:35 volumio-pixy sudo[9663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:35 volumio-pixy systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:43:35 volumio-pixy sudo[9655]: pam_unix(sudo:session): session closed for user root May 25 21:43:35 volumio-pixy sudo[9657]: pam_unix(sudo:session): session closed for user root May 25 21:43:35 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:35 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:35 volumio-pixy sudo[9661]: pam_unix(sudo:session): session closed for user root May 25 21:43:35 volumio-pixy sudo[9663]: pam_unix(sudo:session): session closed for user root May 25 21:43:35 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:35 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:36 volumio-pixy volumio[2608]: info: May 25 21:43:36 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:43:36 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:43:36 volumio-pixy volumio[2608]: info: May 25 21:43:36 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:43:36 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:43:36 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:43:36 volumio-pixy volumio[2608]: info: ------------------------------ 1ms May 25 21:43:36 volumio-pixy volumio[2608]: info: sendMpdCommand status took 2 milliseconds May 25 21:43:36 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:43:36 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:43:36 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 21:43:36 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:43:36 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:43:36 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:43:36 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3358,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Weathers - Happy Pills","artist":"1.FM - Alternative Rock X Hits Radio","album":null,"uri":"http://185.33.21.112:80/x_128","trackType":""} May 25 21:43:36 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:43:36 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:43:36 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:43:36 volumio-pixy volumio[2608]: info: ------------------------------ 18ms May 25 21:43:36 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:36 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:43:36 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:36 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:43:36 volumio-pixy sudo[9684]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:36 volumio-pixy sudo[9684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:36 volumio-pixy sudo[9684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:36 volumio-pixy sudo[9686]: volumio : unable to resolve host volumio-pixy: System error May 25 21:43:36 volumio-pixy volumio[9669]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:43:36 volumio-pixy sudo[9686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:43:36 volumio-pixy sudo[9686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:43:36 volumio-pixy sudo[9686]: pam_unix(sudo:session): session closed for user root May 25 21:43:36 volumio-pixy sudo[9684]: pam_unix(sudo:session): session closed for user root May 25 21:43:36 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:36 volumio-pixy volumio[9669]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:43:36 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:43:39 volumio-pixy systemd[1]: systemd-timedated.service: Deactivated successfully. May 25 21:43:53 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 21:43:53 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 21:43:53 volumio-pixy volumio[2608]: info: Discovery: Getting this device information May 25 21:43:53 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioGetState May 25 21:43:53 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 21:43:59 volumio-pixy volumio[2608]: verbose: New Socket.io Connection to 192.168.0.120:3000 from 192.168.0.156 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 25 21:43:59 volumio-pixy volumio[2608]: verbose: New Socket.io Connection to 192.168.0.120:3000 from 192.168.0.156 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 May 25 21:44:05 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::ClearQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::stPlaybackTimer May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::serviceStop May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::serviceStop May 25 21:44:05 volumio-pixy volumio[2608]: info: [1748202245431] ControllerWebradio::stop May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::clearPlayQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::addQueueItems May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::addQueueItems May 25 21:44:05 volumio-pixy volumio[2608]: info: Preload queue cleared May 25 21:44:05 volumio-pixy volumio[2608]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99567258 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::saveQueue May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::updateTrackBlock May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrackBlock May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPlay May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::play index 0 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::stop May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::play index undefined May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::startPlaybackTimer May 25 21:44:05 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:05 volumio-pixy volumio[2608]: info: [1748202245446] ControllerWebradio::clearAddPlayTrack May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand stop May 25 21:44:05 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status stop May 25 21:44:05 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is not playing May 25 21:44:05 volumio-pixy volumio[2608]: info: FusionDsp - Clipped samples monitor stopped May 25 21:44:05 volumio-pixy volumio[2608]: info: May 25 21:44:05 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 35 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand stop took 19 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand clear May 25 21:44:05 volumio-pixy volumio[2608]: info: May 25 21:44:05 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:44:05 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand status took 1 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand clear took 1 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99567258" May 25 21:44:05 volumio-pixy volumio[2608]: error: updateQueue error: null May 25 21:44:05 volumio-pixy volumio[2608]: info: ------------------------------ 17ms May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 16 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:44:05 volumio-pixy volumio[2608]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 25 21:44:05 volumio-pixy volumio[2608]: info: ------------------------------ 20ms May 25 21:44:05 volumio-pixy volumio[2608]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 21:44:05 volumio-pixy sudo[9736]: volumio : unable to resolve host volumio-pixy: System error May 25 21:44:05 volumio-pixy sudo[9736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 25 21:44:05 volumio-pixy sudo[9736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:44:05 volumio-pixy systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... May 25 21:44:05 volumio-pixy volumio[2608]: info: May 25 21:44:05 volumio-pixy volumio[2608]: ---------------------------- MPD announces system playlist update May 25 21:44:05 volumio-pixy volumio[2608]: info: Ignoring MPD Status Update May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99567258" took 112 milliseconds May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 21:44:05 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand play May 25 21:44:05 volumio-pixy volumio[2608]: info: ------------------------------ 2ms May 25 21:44:05 volumio-pixy volumio[2608]: info: sendMpdCommand play took 1 milliseconds May 25 21:44:05 volumio-pixy systemd[1]: peppymeterbasic.service: Deactivated successfully. May 25 21:44:05 volumio-pixy systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:44:05 volumio-pixy systemd[1]: peppymeterbasic.service: Consumed 15.603s CPU time. May 25 21:44:05 volumio-pixy sudo[9736]: pam_unix(sudo:session): session closed for user root May 25 21:44:05 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Stop May 25 21:44:05 volumio-pixy volumio[2608]: verbose: New Socket.io Connection to 192.168.0.120 from 192.168.0.156 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioGetState May 25 21:44:05 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 21:44:05 volumio-pixy volumio[2608]: info: Listing playlists May 25 21:44:06 volumio-pixy volumio[2608]: info: May 25 21:44:06 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:44:06 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:44:06 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 25 21:44:06 volumio-pixy volumio[2608]: info: FusionDsp - ---- read samplerate from file: 44100 May 25 21:44:06 volumio-pixy volumio[2608]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 21:44:06 volumio-pixy volumio[2608]: info: May 25 21:44:06 volumio-pixy volumio[2608]: ---------------------------- MPD announces state update: player May 25 21:44:06 volumio-pixy volumio[2608]: info: sendMpdCommand status took 5 milliseconds May 25 21:44:06 volumio-pixy volumio[2608]: info: ControllerMpd::getState May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand status May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:44:06 volumio-pixy volumio[2608]: info: sendMpdCommand status took 1 milliseconds May 25 21:44:06 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 0 milliseconds May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseState May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:44:06 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:06 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":"(#1 - 227/600) Soundstorm Radio","album":null,"uri":"https://stream.soundstorm-radio.com:8000/radio.mp3","trackType":"mp3"} May 25 21:44:06 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus stop May 25 21:44:06 volumio-pixy volumio[2608]: info: ------------------------------ 8ms May 25 21:44:06 volumio-pixy volumio[2608]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 21:44:06 volumio-pixy volumio[2608]: verbose: ControllerMpd::parseTrackInfo May 25 21:44:06 volumio-pixy volumio[2608]: info: ControllerMpd::pushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::servicePushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:06 volumio-pixy volumio[2608]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":"(#1 - 227/600) Soundstorm Radio","album":null,"uri":"https://stream.soundstorm-radio.com:8000/radio.mp3","trackType":"mp3"} May 25 21:44:06 volumio-pixy volumio[2608]: verbose: CURRENT POSITION 0 May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState stateService play May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::syncState currentStatus play May 25 21:44:06 volumio-pixy volumio[2608]: info: Received an update from plugin. extracting info from payload May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CorePlayQueue::getTrack 0 May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreStateMachine::pushState May 25 21:44:06 volumio-pixy volumio[2608]: info: CoreCommandRouter::volumioPushState May 25 21:44:06 volumio-pixy volumio[2608]: info: ------------------------------ 13ms May 25 21:44:06 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:44:06 volumio-pixy volumio[2608]: info: PeppyMeterBasic ---peppymeterbasic status play May 25 21:44:06 volumio-pixy kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 5 May 25 21:44:06 volumio-pixy kernel: xhci_hcd 0000:01:00.0: @0000000428d1a480 00000000 00000000 0e000000 02068000 May 25 21:44:06 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:44:06 volumio-pixy volumio[2608]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 25 21:44:06 volumio-pixy volumio[2608]: info: FusionDsp - Volumio is playing May 25 21:44:06 volumio-pixy volumio[2608]: error: FusionDsp - Monitor WebSocket error: [object Object] May 25 21:44:06 volumio-pixy volumio[2608]: error: FusionDsp - Monitor WebSocket error: [object Object] May 25 21:44:06 volumio-pixy sudo[9743]: volumio : unable to resolve host volumio-pixy: System error May 25 21:44:06 volumio-pixy sudo[9743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:44:06 volumio-pixy sudo[9745]: volumio : unable to resolve host volumio-pixy: System error May 25 21:44:06 volumio-pixy sudo[9745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 25 21:44:06 volumio-pixy sudo[9745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:44:06 volumio-pixy sudo[9743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 21:44:06 volumio-pixy systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. May 25 21:44:06 volumio-pixy sudo[9745]: pam_unix(sudo:session): session closed for user root May 25 21:44:06 volumio-pixy sudo[9743]: pam_unix(sudo:session): session closed for user root May 25 21:44:06 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:44:06 volumio-pixy volumio[2608]: info: peppymeterbasic Daemon Started May 25 21:44:07 volumio-pixy volumio[9758]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:44:07 volumio-pixy volumio[9758]: libpng warning: iCCP: known incorrect sRGB profile May 25 21:44:08 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:44:08 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:44:08 volumio-pixy volumio[2608]: info: FusionDsp - Clipping Monitor started May 25 21:44:13 volumio-pixy volumio[2608]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 25 21:44:13 volumio-pixy volumio[2608]: Use XMLElement.setAttribute instead May 25 21:44:13 volumio-pixy volumio[2608]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:44:13 volumio-pixy volumio[2608]: TypeError: children[i].attr(...).value is not a function May 25 21:44:13 volumio-pixy volumio[2608]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 May 25 21:44:13 volumio-pixy volumio[2608]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) May 25 21:44:13 volumio-pixy volumio[2608]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 21:44:14 volumio-pixy sudo[9805]: volumio : unable to resolve host volumio-pixy: System error May 25 21:44:14 volumio-pixy sudo[9805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-25 21:43' May 25 21:44:14 volumio-pixy sudo[9805]: 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"