-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-07-10 19:36:07 CEST. -- Jul 10 19:35:00 volumio-alen ntpd[811]: Soliciting pool server 185.143.194.7 Jul 10 19:35:00 volumio-alen ntpd[811]: Soliciting pool server 161.53.128.17 Jul 10 19:35:10 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:35:10 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:35:10 volumio-alen volumio[1030]: info: Discovery: Getting this device information Jul 10 19:35:10 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 10 19:35:10 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:35:19 volumio-alen ntpd[811]: Soliciting pool server 185.103.216.7 Jul 10 19:35:38 volumio-alen volumiologrotate[631]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 10 19:35:38 volumio-alen volumiologrotate[631]: ls: cannot access 'ALEN': No such file or directory Jul 10 19:35:40 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 19:35:40 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 19:35:40 volumio-alen volumio[1030]: info: Discovery: Getting this device information Jul 10 19:35:40 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 10 19:35:40 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 19:36:03 volumio-alen ntpd[811]: Soliciting pool server 185.143.194.7 Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioPause Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::pause Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::servicePause Jul 10 19:36:05 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::servicePause Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 19:36:05 volumio-alen volumio[1030]: info: Jul 10 19:36:05 volumio-alen volumio[1030]: ---------------------------- MPD announces state update: player Jul 10 19:36:05 volumio-alen volumio[1030]: info: sendMpdCommand pause took 2 milliseconds Jul 10 19:36:05 volumio-alen volumio[1030]: info: ControllerMpd::getState Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::servicePushState Jul 10 19:36:05 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:05 volumio-alen volumio[1030]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received radio_paradise Jul 10 19:36:05 volumio-alen volumio[1030]: info: sendMpdCommand status took 1 milliseconds Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: ControllerMpd::parseState Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 19:36:05 volumio-alen volumio[1030]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 10 19:36:05 volumio-alen volumio[1030]: info: ControllerMpd::pushState Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::servicePushState Jul 10 19:36:05 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":96845,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"GoGo Penguin - Glimmerings","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Jul 10 19:36:05 volumio-alen volumio[1030]: verbose: CURRENT POSITION 0 Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::syncState stateService pause Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::syncState currentStatus pause Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::pushState Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 10 19:36:05 volumio-alen volumio[1030]: info: MRS: Pushing multiroomSync output update for this device Jul 10 19:36:05 volumio-alen volumio[1030]: info: MRS: Pushing multiroomSync output Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 10 19:36:05 volumio-alen volumio[1030]: info: CoreStateMachine::stPlaybackTimer Jul 10 19:36:05 volumio-alen volumio[1030]: info: ------------------------------ 21ms Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioPlay Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::play index undefined Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::startPlaybackTimer Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand play Jul 10 19:36:07 volumio-alen volumio[1030]: info: Jul 10 19:36:07 volumio-alen volumio[1030]: ---------------------------- MPD announces state update: player Jul 10 19:36:07 volumio-alen volumio[1030]: info: sendMpdCommand play took 4 milliseconds Jul 10 19:36:07 volumio-alen volumio[1030]: info: ControllerMpd::getState Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand status Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::servicePushState Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: STATE SERVICE {"status":"play","seek":95373} Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: CURRENT POSITION 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::syncState stateService play Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::syncState currentStatus pause Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreStateMachine::pushState Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 10 19:36:07 volumio-alen volumio[1030]: info: MRS: Pushing multiroomSync output update for this device Jul 10 19:36:07 volumio-alen volumio[1030]: info: MRS: Pushing multiroomSync output Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: [1752168967054] [RadioParadise] getMetadata started with url undefined Jul 10 19:36:07 volumio-alen volumio[1030]: info: sendMpdCommand status took 21 milliseconds Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: ControllerMpd::parseState Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 19:36:07 volumio-alen volumio[1030]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 19:36:07 volumio-alen volumio[1030]: verbose: ControllerMpd::parseTrackInfo Jul 10 19:36:07 volumio-alen volumio[1030]: info: ControllerMpd::pushState Jul 10 19:36:07 volumio-alen volumio[1030]: info: CoreCommandRouter::servicePushState Jul 10 19:36:07 volumio-alen volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 10 19:36:07 volumio-alen volumio[1030]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jul 10 19:36:07 volumio-alen volumio[1030]: info: ------------------------------ 30ms Jul 10 19:36:07 volumio-alen volumio[1030]: info: [1752168967075] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Jul 10 19:36:07 volumio-alen volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 19:36:07 volumio-alen volumio[1030]: TypeError: Cannot read property 'toUpperCase' of undefined Jul 10 19:36:07 volumio-alen volumio[1030]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Jul 10 19:36:07 volumio-alen volumio[1030]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Jul 10 19:36:07 volumio-alen volumio[1030]: at ClientRequest.emit (events.js:315:20) Jul 10 19:36:07 volumio-alen volumio[1030]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Jul 10 19:36:07 volumio-alen volumio[1030]: at TLSSocket.emit (events.js:315:20) Jul 10 19:36:07 volumio-alen volumio[1030]: at emitErrorNT (internal/streams/destroy.js:106:8) Jul 10 19:36:07 volumio-alen volumio[1030]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jul 10 19:36:07 volumio-alen volumio[1030]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 10 19:36:07 volumio-alen volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 19:36:07 volumio-alen sudo[4812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 19:35 Jul 10 19:36:07 volumio-alen sudo[4812]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"