-- Logs begin at Fri 2024-08-30 16:11:57 UTC, end at Fri 2024-08-30 16:51:21 UTC. -- Aug 30 16:51:04 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioGetState Aug 30 16:51:04 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:04 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 16:51:04 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 16:51:04 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 16:51:04 volumio-kamet volumio[812]: info: Listing playlists Aug 30 16:51:11 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 30 16:51:11 volumio-kamet volumio[812]: info: [1725036671947] [80s80s] handleBrowseUri curUri: 80s80s Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioGetState Aug 30 16:51:12 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 16:51:12 volumio-kamet volumio[812]: info: Listing playlists Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioGetQueue Aug 30 16:51:12 volumio-kamet volumio[812]: info: CoreStateMachine::getQueue Aug 30 16:51:12 volumio-kamet volumio[812]: info: CorePlayQueue::getQueue Aug 30 16:51:15 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 30 16:51:15 volumio-kamet volumio[812]: info: [1725036675168] [80s80s] handleBrowseUri curUri: 80s80s/eighties Aug 30 16:51:15 volumio-kamet volumio[812]: info: [1725036675168] [80s80s] getRadioContent url: eighties Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::ClearQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::stop Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:51:18 volumio-kamet volumio[812]: verbose: UNSET VOLATILE Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::clearPlayQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::saveQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioPushState Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::addQueueItems Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::addQueueItems Aug 30 16:51:18 volumio-kamet volumio[812]: info: Adding Item to queue: webeighties/4 Aug 30 16:51:18 volumio-kamet volumio[812]: info: Exploding uri webeighties/4 in service 80s80s Aug 30 16:51:18 volumio-kamet volumio[812]: info: [1725036678672] [80s80s] explodeUri: webeighties/4 Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::saveQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::updateTrackBlock Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::getTrackBlock Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioPlay Aug 30 16:51:18 volumio-kamet volumio[812]: verbose: UNSET VOLATILE Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::play index 0 Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::stop Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:51:18 volumio-kamet volumio[812]: verbose: UNSET VOLATILE Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::play index undefined Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:18 volumio-kamet volumio[812]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:51:18 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:18 volumio-kamet volumio[812]: info: [1725036678681] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 30 16:51:18 volumio-kamet volumio[812]: info: [1725036678681] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Aug 30 16:51:18 volumio-kamet volumio[812]: info: [1725036678998] [80s80s] received new event containing 2 songs. Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:51:19 volumio-kamet volumio[812]: info: sendMpdCommand stop took 1 milliseconds Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 16:51:19 volumio-kamet volumio[812]: info: Aug 30 16:51:19 volumio-kamet volumio[812]: ---------------------------- MPD announces system playlist update Aug 30 16:51:19 volumio-kamet volumio[812]: info: Ignoring MPD Status Update Aug 30 16:51:19 volumio-kamet volumio[812]: info: sendMpdCommand clear took 3 milliseconds Aug 30 16:51:19 volumio-kamet volumio[812]: info: [1725036679008] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Aug 30 16:51:19 volumio-kamet volumio[812]: error: updateQueue error: null Aug 30 16:51:19 volumio-kamet volumio[812]: info: Aug 30 16:51:19 volumio-kamet volumio[812]: ---------------------------- MPD announces system playlist update Aug 30 16:51:19 volumio-kamet volumio[812]: info: Ignoring MPD Status Update Aug 30 16:51:19 volumio-kamet volumio[812]: info: ------------------------------ 8ms Aug 30 16:51:19 volumio-kamet volumio[812]: info: sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" took 4 milliseconds Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand play Aug 30 16:51:19 volumio-kamet volumio[812]: info: ------------------------------ 8ms Aug 30 16:51:19 volumio-kamet volumio[812]: info: sendMpdCommand play took 2 milliseconds Aug 30 16:51:19 volumio-kamet volumio[812]: info: [1725036679021] [80s80s] Pushing the next song state: Re-Flex - How Much Longer Aug 30 16:51:19 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreCommandRouter::servicePushState Aug 30 16:51:19 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music124/v4/7f/0e/54/7f0e54cd-7afd-e56e-a2f3-67c42dcaa6d5/source/600x600bb.jpg","name":"Re-Flex - How Much Longer","title":"How Much Longer","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"264","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 30 16:51:19 volumio-kamet volumio[812]: verbose: CURRENT POSITION 0 Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreStateMachine::syncState stateService play Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreStateMachine::syncState currentStatus stop Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreStateMachine::pushState Aug 30 16:51:19 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreCommandRouter::volumioPushState Aug 30 16:51:19 volumio-kamet volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:51:19 volumio-kamet volumio[812]: info: [1725036679038] [80s80s] getContentOfUrl started with url http://ntp-a4.nict.go.jp/cgi-bin/json Aug 30 16:51:19 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:19 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 1 Aug 30 16:51:20 volumio-kamet volumio[812]: info: Aug 30 16:51:20 volumio-kamet volumio[812]: ---------------------------- MPD announces state update: player Aug 30 16:51:20 volumio-kamet volumio[812]: info: ControllerMpd::getState Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:51:20 volumio-kamet volumio[812]: info: Aug 30 16:51:20 volumio-kamet volumio[812]: ---------------------------- MPD announces state update: player Aug 30 16:51:20 volumio-kamet volumio[812]: info: sendMpdCommand status took 10 milliseconds Aug 30 16:51:20 volumio-kamet volumio[812]: info: ControllerMpd::getState Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand status Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::parseState Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:51:20 volumio-kamet volumio[812]: info: Aug 30 16:51:20 volumio-kamet volumio[812]: ---------------------------- MPD announces system playlist update Aug 30 16:51:20 volumio-kamet volumio[812]: info: Ignoring MPD Status Update Aug 30 16:51:20 volumio-kamet volumio[812]: info: sendMpdCommand status took 3 milliseconds Aug 30 16:51:20 volumio-kamet volumio[812]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::parseState Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:51:20 volumio-kamet volumio[812]: info: ControllerMpd::pushState Aug 30 16:51:20 volumio-kamet volumio[812]: info: CoreCommandRouter::servicePushState Aug 30 16:51:20 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:20 volumio-kamet volumio[812]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 16:51:20 volumio-kamet volumio[812]: info: ------------------------------ 19ms Aug 30 16:51:20 volumio-kamet volumio[812]: info: ------------------------------ 6ms Aug 30 16:51:20 volumio-kamet volumio[812]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 16:51:20 volumio-kamet volumio[812]: verbose: ControllerMpd::parseTrackInfo Aug 30 16:51:20 volumio-kamet volumio[812]: info: ControllerMpd::pushState Aug 30 16:51:20 volumio-kamet volumio[812]: info: CoreCommandRouter::servicePushState Aug 30 16:51:20 volumio-kamet volumio[812]: info: CorePlayQueue::getTrack 0 Aug 30 16:51:20 volumio-kamet volumio[812]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 16:51:20 volumio-kamet volumio[812]: info: ------------------------------ 10ms Aug 30 16:51:20 volumio-kamet volumio[812]: info: [1725036680744] [80s80s] Failed to query api, status code: 404 Aug 30 16:51:20 volumio-kamet volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:51:20 volumio-kamet volumio[812]: ReferenceError: station is not defined Aug 30 16:51:20 volumio-kamet volumio[812]: at ClientRequest.http.get (/data/plugins/music_service/80s80s/index.js:478:26) Aug 30 16:51:20 volumio-kamet volumio[812]: at Object.onceWrapper (events.js:315:30) Aug 30 16:51:20 volumio-kamet volumio[812]: at emitOne (events.js:116:13) Aug 30 16:51:20 volumio-kamet volumio[812]: at ClientRequest.emit (events.js:211:7) Aug 30 16:51:20 volumio-kamet volumio[812]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Aug 30 16:51:20 volumio-kamet volumio[812]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Aug 30 16:51:20 volumio-kamet volumio[812]: at Socket.socketOnData (_http_client.js:440:20) Aug 30 16:51:20 volumio-kamet volumio[812]: at emitOne (events.js:116:13) Aug 30 16:51:20 volumio-kamet volumio[812]: at Socket.emit (events.js:211:7) Aug 30 16:51:20 volumio-kamet volumio[812]: at addChunk (_stream_readable.js:263:12) Aug 30 16:51:20 volumio-kamet volumio[812]: at readableAddChunk (_stream_readable.js:250:11) Aug 30 16:51:20 volumio-kamet volumio[812]: at Socket.Readable.push (_stream_readable.js:208:10) Aug 30 16:51:20 volumio-kamet volumio[812]: at TCP.onread (net.js:607:20) Aug 30 16:51:20 volumio-kamet volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:51:21 volumio-kamet sudo[7031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 16:50 Aug 30 16:51:21 volumio-kamet sudo[7031]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"