-- Logs begin at Sun 2025-07-06 08:17:26 UTC, end at Sun 2025-07-06 08:52:24 UTC. --
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPlay
Jul 06 08:52:09 volumio-zerow volumio[839]: UNSET VOLATILE
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::play index 25
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::stop
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 08:52:09 volumio-zerow volumio[839]: UNSET VOLATILE
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::stPlaybackTimer
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::updateTrackBlock
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrackBlock
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::serviceStop
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::serviceStop
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ControllerMpd::stop
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand stop
Jul 06 08:52:09 volumio-zerow volumio[839]: info:
Jul 06 08:52:09 volumio-zerow volumio[839]: ---------------------------- MPD announces state update: player
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ControllerMpd::getState
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ControllerMpd::getState
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::parseState
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::parseState
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::parseTrackInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::parseTrackInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ControllerMpd::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::servicePushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Clark Tracey - Black Coffee [5J]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":""}
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: CURRENT POSITION 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::syncState stateService stop
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::syncState currentStatus stop
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: No code
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 23
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ------------------------------ 229ms
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::play index undefined
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 25
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::startPlaybackTimer
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 25
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand stop
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand clear
Jul 06 08:52:09 volumio-zerow volumio[839]: info:
Jul 06 08:52:09 volumio-zerow volumio[839]: ---------------------------- MPD announces system playlist update
Jul 06 08:52:09 volumio-zerow volumio[839]: info: Ignoring MPD Status Update
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8004/stream"
Jul 06 08:52:09 volumio-zerow volumio[839]: error: updateQueue error: null
Jul 06 08:52:09 volumio-zerow volumio[839]: info:
Jul 06 08:52:09 volumio-zerow volumio[839]: ---------------------------- MPD announces system playlist update
Jul 06 08:52:09 volumio-zerow volumio[839]: info: Ignoring MPD Status Update
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ------------------------------ 51ms
Jul 06 08:52:09 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand play
Jul 06 08:52:09 volumio-zerow volumio[839]: info: ------------------------------ 71ms
Jul 06 08:52:09 volumio-zerow volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd
Jul 06 08:52:11 volumio-zerow volumio[839]: info:
Jul 06 08:52:11 volumio-zerow volumio[839]: ---------------------------- MPD announces state update: player
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::getState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Jul 06 08:52:11 volumio-zerow volumio[839]: info:
Jul 06 08:52:11 volumio-zerow volumio[839]: ---------------------------- MPD announces system playlist update
Jul 06 08:52:11 volumio-zerow volumio[839]: info: Ignoring MPD Status Update
Jul 06 08:52:11 volumio-zerow volumio[839]: info:
Jul 06 08:52:11 volumio-zerow volumio[839]: ---------------------------- MPD announces state update: player
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::getState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Jul 06 08:52:11 volumio-zerow volumio[839]: info:
Jul 06 08:52:11 volumio-zerow volumio[839]: ---------------------------- MPD announces system playlist update
Jul 06 08:52:11 volumio-zerow volumio[839]: info: Ignoring MPD Status Update
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info:
Jul 06 08:52:11 volumio-zerow volumio[839]: ---------------------------- MPD announces state update: player
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ------------------------------ 183ms
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::getState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand status
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ------------------------------ 73ms
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseTrackInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseState
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::servicePushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 25
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ensemble Dumont - Agnus Dei [19C]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: CURRENT POSITION 25
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState stateService play
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState currentStatus stop
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ------------------------------ 500ms
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseTrackInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: ControllerMpd::parseTrackInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::servicePushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 25
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":394,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ensemble Dumont - Agnus Dei [19C]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: CURRENT POSITION 25
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState stateService play
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState currentStatus play
Jul 06 08:52:11 volumio-zerow volumio[839]: info: Received an update from plugin. extracting info from payload
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ControllerMpd::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::servicePushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CorePlayQueue::getTrack 25
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":417,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ensemble Dumont - Agnus Dei [19C]","artist":"Linn Classical","album":null,"uri":"http://radio.linnrecords.com:8004/stream","trackType":""}
Jul 06 08:52:11 volumio-zerow volumio[839]: verbose: CURRENT POSITION 25
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState stateService play
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::syncState currentStatus play
Jul 06 08:52:11 volumio-zerow volumio[839]: info: Received an update from plugin. extracting info from payload
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreStateMachine::pushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioPushState
Jul 06 08:52:11 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 08:52:11 volumio-zerow volumio[839]: info: ------------------------------ 746ms
Jul 06 08:52:12 volumio-zerow volumio[839]: info: ------------------------------ 574ms
Jul 06 08:52:17 volumio-zerow volumio[839]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri
Jul 06 08:52:17 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioAddQueueItems
Jul 06 08:52:17 volumio-zerow volumio[839]: info: CoreStateMachine::addQueueItems
Jul 06 08:52:17 volumio-zerow volumio[839]: info: CorePlayQueue::addQueueItems
Jul 06 08:52:17 volumio-zerow volumio[839]: info: Adding Item to queue: webkbs/0
Jul 06 08:52:17 volumio-zerow volumio[839]: info: Exploding uri webkbs/0 in service personal_radio
Jul 06 08:52:18 volumio-zerow volumio[839]: info: CoreCommandRouter::volumioAddQueueItems
Jul 06 08:52:18 volumio-zerow volumio[839]: info: CoreStateMachine::addQueueItems
Jul 06 08:52:18 volumio-zerow volumio[839]: info: CorePlayQueue::addQueueItems
Jul 06 08:52:18 volumio-zerow volumio[839]: info: Adding Item to queue: webkbs/5
Jul 06 08:52:18 volumio-zerow volumio[839]: info: Exploding uri webkbs/5 in service personal_radio
Jul 06 08:52:19 volumio-zerow volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 06 08:52:19 volumio-zerow volumio[839]: ReferenceError: self is not defined
Jul 06 08:52:19 volumio-zerow volumio[839]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22)
Jul 06 08:52:19 volumio-zerow volumio[839]: at /data/plugins/music_service/personal_radio/index.js:487:12
Jul 06 08:52:19 volumio-zerow volumio[839]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15)
Jul 06 08:52:19 volumio-zerow volumio[839]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22)
Jul 06 08:52:19 volumio-zerow volumio[839]: at emitOne (events.js:116:13)
Jul 06 08:52:19 volumio-zerow volumio[839]: at Request.emit (events.js:211:7)
Jul 06 08:52:19 volumio-zerow volumio[839]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8)
Jul 06 08:52:19 volumio-zerow volumio[839]: at emitOne (events.js:116:13)
Jul 06 08:52:19 volumio-zerow volumio[839]: at ClientRequest.emit (events.js:211:7)
Jul 06 08:52:19 volumio-zerow volumio[839]: at Socket.socketErrorListener (_http_client.js:387:9)
Jul 06 08:52:19 volumio-zerow volumio[839]: at emitOne (events.js:116:13)
Jul 06 08:52:19 volumio-zerow volumio[839]: at Socket.emit (events.js:211:7)
Jul 06 08:52:19 volumio-zerow volumio[839]: at emitErrorNT (internal/streams/destroy.js:64:8)
Jul 06 08:52:19 volumio-zerow volumio[839]: at _combinedTickCallback (internal/process/next_tick.js:138:11)
Jul 06 08:52:19 volumio-zerow volumio[839]: at process._tickCallback (internal/process/next_tick.js:180:9)
Jul 06 08:52:19 volumio-zerow volumio[839]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 06 08:52:24 volumio-zerow sudo[4618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 08:51
Jul 06 08:52:24 volumio-zerow sudo[4618]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
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="79c2e7e29d04526018e922852ec460ffc0707dd1"
VOLUMIO_FE_VERSION="77c86e8a1bfb19b724ead35c47d417c4832cf290"
VOLUMIO_BE_VERSION="f30dbcf35403287bd1b5d6a60fa82e3da7871c6c"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Jul 16 18:40:05 CEST 2020"
VOLUMIO_VERSION="2.799"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="3640dc4c81092e5cb33682a0d71ffe92"