-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-05-11 06:17:02 UTC. -- May 11 06:16:00 volumio volumio[894]: info: ------------------------------ 6ms May 11 06:16:00 volumio volumio[894]: info: sendMpdCommand play took 4 milliseconds May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::ClearQueue May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::stop May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 06:16:01 volumio volumio[894]: verbose: UNSET VOLATILE May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::saveQueue May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::addQueueItems May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::addQueueItems May 11 06:16:01 volumio volumio[894]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::saveQueue May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::getTrackBlock May 11 06:16:01 volumio volumio[894]: info: CoreCommandRouter::volumioPlay May 11 06:16:01 volumio volumio[894]: verbose: UNSET VOLATILE May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::play index 0 May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::stop May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 06:16:01 volumio volumio[894]: verbose: UNSET VOLATILE May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::play index undefined May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::startPlaybackTimer May 11 06:16:01 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:16:01 volumio volumio[894]: info: [1715408161341] ControllerWebradio::clearAddPlayTrack May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop May 11 06:16:01 volumio volumio[894]: info: May 11 06:16:01 volumio volumio[894]: ---------------------------- MPD announces state update: player May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand stop took 290 milliseconds May 11 06:16:01 volumio volumio[894]: info: ControllerMpd::getState May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand clear May 11 06:16:01 volumio volumio[894]: info: May 11 06:16:01 volumio volumio[894]: ---------------------------- MPD announces system playlist update May 11 06:16:01 volumio volumio[894]: info: Ignoring MPD Status Update May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand status took 4 milliseconds May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand clear took 3 milliseconds May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::parseState May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" May 11 06:16:01 volumio volumio[894]: error: updateQueue error: null May 11 06:16:01 volumio volumio[894]: info: ------------------------------ 8ms May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo May 11 06:16:01 volumio volumio[894]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 11 06:16:01 volumio volumio[894]: info: ------------------------------ 16ms May 11 06:16:01 volumio volumio[894]: info: May 11 06:16:01 volumio volumio[894]: ---------------------------- MPD announces system playlist update May 11 06:16:01 volumio volumio[894]: info: Ignoring MPD Status Update May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" took 19 milliseconds May 11 06:16:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd May 11 06:16:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand play May 11 06:16:01 volumio volumio[894]: info: ------------------------------ 4ms May 11 06:16:01 volumio volumio[894]: info: sendMpdCommand play took 3 milliseconds May 11 06:16:02 volumio volumio[894]: info: May 11 06:16:02 volumio volumio[894]: ---------------------------- MPD announces state update: player May 11 06:16:02 volumio volumio[894]: info: ControllerMpd::getState May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status May 11 06:16:02 volumio volumio[894]: info: May 11 06:16:02 volumio volumio[894]: ---------------------------- MPD announces state update: player May 11 06:16:02 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 11 06:16:02 volumio volumio[894]: info: sendMpdCommand status took 11 milliseconds May 11 06:16:02 volumio volumio[894]: info: ControllerMpd::getState May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand status May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::parseState May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 06:16:02 volumio volumio[894]: info: sendMpdCommand status took 2 milliseconds May 11 06:16:02 volumio volumio[894]: info: sendMpdCommand playlistinfo took 3 milliseconds May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::parseState May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo May 11 06:16:02 volumio volumio[894]: info: ControllerMpd::pushState May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::servicePushState May 11 06:16:02 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:16:02 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1715579960&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1NTc5OTYwfX19XX0_&Signature=Qv-EYWfqncv5ycESHKUKYZbwzUw0p1pvf0982JtYwR~6yUtHmzDehzN2pil7vZRSr2gX1CqiNQRXH-03ex~t1FYnxMikTs5IcO21MdcOvXTKq0DZlVPtE6mS7dytDDFYSuh2iR5QUIgtywWy2aMr0xbfYnwEVAtGgloXoxpyv5CZ6EvOGHBLBv3~R6WFAjzODTKhGrUVQCGgrTU7ujNDTGw2VMp18Ri~eRjqz5caMF-aSmXB6haVO-mfUCnMpY~zhhlsuTH9caFEibw~8VHUc2ajIJhHcnPMQygkXTxl~PFedIQwN~GIe3kQMbnWBiuPPtAcBoRKNmlawL~bX52fYw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} May 11 06:16:02 volumio volumio[894]: verbose: CURRENT POSITION 0 May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::syncState stateService play May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus stop May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::pushState May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:16:02 volumio volumio[894]: info: ------------------------------ 44ms May 11 06:16:02 volumio volumio[894]: info: sendMpdCommand playlistinfo took 26 milliseconds May 11 06:16:02 volumio volumio[894]: verbose: ControllerMpd::parseTrackInfo May 11 06:16:02 volumio volumio[894]: info: ControllerMpd::pushState May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::servicePushState May 11 06:16:02 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:16:02 volumio volumio[894]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1715579960&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzE1NTc5OTYwfX19XX0_&Signature=Qv-EYWfqncv5ycESHKUKYZbwzUw0p1pvf0982JtYwR~6yUtHmzDehzN2pil7vZRSr2gX1CqiNQRXH-03ex~t1FYnxMikTs5IcO21MdcOvXTKq0DZlVPtE6mS7dytDDFYSuh2iR5QUIgtywWy2aMr0xbfYnwEVAtGgloXoxpyv5CZ6EvOGHBLBv3~R6WFAjzODTKhGrUVQCGgrTU7ujNDTGw2VMp18Ri~eRjqz5caMF-aSmXB6haVO-mfUCnMpY~zhhlsuTH9caFEibw~8VHUc2ajIJhHcnPMQygkXTxl~PFedIQwN~GIe3kQMbnWBiuPPtAcBoRKNmlawL~bX52fYw__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} May 11 06:16:02 volumio volumio[894]: verbose: CURRENT POSITION 0 May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::syncState stateService play May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::syncState currentStatus play May 11 06:16:02 volumio volumio[894]: info: Received an update from plugin. extracting info from payload May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::pushState May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:16:02 volumio volumio[894]: info: CoreStateMachine::pushState May 11 06:16:02 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:16:02 volumio volumio[894]: info: ------------------------------ 76ms May 11 06:16:31 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 06:16:31 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 06:16:31 volumio volumio[894]: info: Discovery: Getting this device information May 11 06:16:31 volumio volumio[894]: info: CoreCommandRouter::volumioGetState May 11 06:16:31 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 06:16:40 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 11 06:16:40 volumio volumio[894]: info: CURURI: music-library May 11 06:16:40 volumio volumio[894]: error: Failed LSINFO: null May 11 06:16:52 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 11 06:16:57 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::volumioReplaceandPlayItems May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::ClearQueue May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::stop May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined May 11 06:17:01 volumio volumio[894]: verbose: UNSET VOLATILE May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::stPlaybackTimer May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::updateTrackBlock May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::getTrackBlock May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::pushState May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::serviceStop May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::getTrack 0 May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::serviceStop May 11 06:17:01 volumio volumio[894]: info: [1715408221562] ControllerWebradio::stop May 11 06:17:01 volumio volumio[894]: verbose: ControllerMpd::sendMpdCommand stop May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::clearPlayQueue May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::saveQueue May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushState May 11 06:17:01 volumio volumio[894]: info: CoreCommandRouter::volumioPushQueue May 11 06:17:01 volumio volumio[894]: info: CoreStateMachine::addQueueItems May 11 06:17:01 volumio volumio[894]: info: CorePlayQueue::addQueueItems May 11 06:17:01 volumio volumio[894]: info: Adding Item to queue: webmbc/1 May 11 06:17:01 volumio volumio[894]: info: Exploding uri webmbc/1 in service personal_radio May 11 06:17:01 volumio volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 06:17:01 volumio volumio[894]: ReferenceError: self is not defined May 11 06:17:01 volumio volumio[894]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) May 11 06:17:01 volumio volumio[894]: at /data/plugins/music_service/personal_radio/index.js:487:12 May 11 06:17:01 volumio volumio[894]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) May 11 06:17:01 volumio volumio[894]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) May 11 06:17:01 volumio volumio[894]: at Request.emit (events.js:315:20) May 11 06:17:01 volumio volumio[894]: at Request.init (/data/plugins/music_service/personal_radio/node_modules/request/request.js:275:17) May 11 06:17:01 volumio volumio[894]: at new Request (/data/plugins/music_service/personal_radio/node_modules/request/request.js:129:8) May 11 06:17:01 volumio volumio[894]: at Function.request (/data/plugins/music_service/personal_radio/node_modules/request/index.js:55:10) May 11 06:17:01 volumio volumio[894]: at Object.end (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:595:27) May 11 06:17:01 volumio volumio[894]: at ControllerPersonalRadio.getStreamUrl (/data/plugins/music_service/personal_radio/index.js:482:4) May 11 06:17:01 volumio volumio[894]: at ControllerPersonalRadio.explodeUri (/data/plugins/music_service/personal_radio/index.js:420:12) May 11 06:17:01 volumio volumio[894]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1324:16) May 11 06:17:01 volumio volumio[894]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) May 11 06:17:01 volumio volumio[894]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:243:25) May 11 06:17:01 volumio volumio[894]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:487:23) May 11 06:17:01 volumio volumio[894]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:457:17) May 11 06:17:01 volumio volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 06:17:02 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 06:16 May 11 06:17:02 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"