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"