-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Wed 2025-03-05 16:46:44 +07. -- Mar 05 16:45:01 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:09 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:15 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:22 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:25 volumio volumio[1024]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 16:45:29 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:36 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:44 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:45:51 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:00 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:07 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:11 volumio volumio[1024]: info: CALLMETHOD: audio_interface mpdoutput updateConfig [object Object] Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfig Mar 05 16:46:11 volumio volumio[1024]: info: mpdhttpout ---Received data for http: {"servername":"localhost","encoder":{"value":"lame","label":"lame"},"bitrate":{"value":"320","label":"320"},"format":{"value":"44100:16:2","label":"44100:16:2"},"portn":"8000","httpadd":false,"max_clients":"5","buffer_time":"5000","outburst_time":"2000"} Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::volumioPause Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::pause Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::stPlaybackTimer Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::servicePause Mar 05 16:46:11 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::servicePause Mar 05 16:46:11 volumio volumio[1024]: verbose: ControllerMpd::sendMpdCommand pause Mar 05 16:46:11 volumio volumio[1024]: info: mpdhttpout --- Volumio set on pause Mar 05 16:46:11 volumio volumio[1024]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Mar 05 16:46:11 volumio volumio[1024]: info: Mar 05 16:46:11 volumio volumio[1024]: ---------------------------- MPD announces state update: player Mar 05 16:46:11 volumio volumio[1024]: info: sendMpdCommand pause took 2 milliseconds Mar 05 16:46:11 volumio volumio[1024]: info: ControllerMpd::getState Mar 05 16:46:11 volumio volumio[1024]: verbose: ControllerMpd::sendMpdCommand status Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Mar 05 16:46:11 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:11 volumio volumio[1024]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received radio_paradise Mar 05 16:46:11 volumio volumio[1024]: info: sendMpdCommand status took 2 milliseconds Mar 05 16:46:11 volumio volumio[1024]: verbose: ControllerMpd::parseState Mar 05 16:46:11 volumio volumio[1024]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 05 16:46:11 volumio volumio[1024]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 05 16:46:11 volumio volumio[1024]: verbose: ControllerMpd::parseTrackInfo Mar 05 16:46:11 volumio volumio[1024]: info: ControllerMpd::pushState Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Mar 05 16:46:11 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:11 volumio volumio[1024]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":238308,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"324 Kbps","isStreaming":false,"title":"Father John Misty - I Guess Time Just Makes Fools of Us All","artist":"Radio Paradise (320k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-320","trackType":""} Mar 05 16:46:11 volumio volumio[1024]: verbose: CURRENT POSITION 0 Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::syncState stateService pause Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::pushState Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 16:46:11 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Mar 05 16:46:11 volumio volumio[1024]: info: CoreStateMachine::stPlaybackTimer Mar 05 16:46:11 volumio volumio[1024]: info: ------------------------------ 25ms Mar 05 16:46:11 volumio volumio[1024]: info: touch_display: Setting screensaver timeout to 36000 seconds. Mar 05 16:46:13 volumio volumio[1024]: info: peppy_screensaver: Start PeppyMeter Mar 05 16:46:13 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 05 16:46:13 volumio sudo[25264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 16:46:13 volumio sudo[25264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 16:46:13 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 16:46:13 volumio systemd[1]: mpd.service: Succeeded. Mar 05 16:46:13 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 16:46:13 volumio systemd[1]: Starting Music Player Daemon... Mar 05 16:46:13 volumio sudo[25266]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 16:46:13 volumio sudo[25266]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 16:46:13 volumio sudo[25266]: pam_unix(sudo:session): session closed for user root Mar 05 16:46:14 volumio volumio[1024]: error: Upnp client error: Error: This socket has been ended by the other party Mar 05 16:46:14 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:14 volumio mpd[25268]: Mar 05 16:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 16:46:14 volumio systemd[1]: Started Music Player Daemon. Mar 05 16:46:14 volumio sudo[25264]: pam_unix(sudo:session): session closed for user root Mar 05 16:46:14 volumio volumio[1024]: error: updateQueue error: null Mar 05 16:46:21 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:24 volumio volumio[1024]: info: CALLMETHOD: audio_interface mpdoutput updateConfigIce [object Object] Mar 05 16:46:24 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpdoutput , updateConfigIce Mar 05 16:46:24 volumio volumio[1024]: info: mpdhttpout ---Received data for icecast: {"iceservername":"ice.example.com","icepublishport":"8015","iceprotocol":{"value":"icecast2","label":"icecast2"},"icemountp":"/volumio.ogg","iceencoder":{"value":"vorbis","label":"vorbis"},"icebitrate":{"value":"320","label":"320"},"iceformat":{"value":"44100:16:2","label":"44100:16:2"},"iceuser":"source","icepassword":"hackme","icestreamname":"My Ice stream","icepublic":{"value":"no","label":"no"},"icedescription":"My music stream","icegenre":"various","iceurl":"","iceadd":false} Mar 05 16:46:24 volumio volumio[1024]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 05 16:46:24 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Mar 05 16:46:24 volumio volumio[1024]: info: mpdhttpout --- Volumio set on pause Mar 05 16:46:26 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 05 16:46:26 volumio sudo[25315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 16:46:26 volumio sudo[25315]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 16:46:26 volumio systemd[1]: Stopping Music Player Daemon... Mar 05 16:46:26 volumio systemd[1]: mpd.service: Succeeded. Mar 05 16:46:26 volumio systemd[1]: Stopped Music Player Daemon. Mar 05 16:46:26 volumio systemd[1]: Starting Music Player Daemon... Mar 05 16:46:26 volumio sudo[25317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 16:46:26 volumio sudo[25317]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 16:46:26 volumio sudo[25317]: pam_unix(sudo:session): session closed for user root Mar 05 16:46:27 volumio volumio[1024]: error: Upnp client error: Error: This socket has been ended by the other party Mar 05 16:46:27 volumio mpd[25320]: Mar 05 16:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 16:46:27 volumio systemd[1]: Started Music Player Daemon. Mar 05 16:46:27 volumio sudo[25315]: pam_unix(sudo:session): session closed for user root Mar 05 16:46:27 volumio volumio[1024]: error: updateQueue error: null Mar 05 16:46:30 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:37 volumio wpa_supplicant[920]: RRM: Ignoring radio measurement request: Not RRM network Mar 05 16:46:44 volumio volumio[1024]: info: CoreCommandRouter::volumioPlay Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::play index undefined Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 16:46:44 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::startPlaybackTimer Mar 05 16:46:44 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:44 volumio volumio[1024]: verbose: ControllerMpd::sendMpdCommand play Mar 05 16:46:44 volumio volumio[1024]: info: sendMpdCommand play took 0 milliseconds Mar 05 16:46:44 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Mar 05 16:46:44 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:44 volumio volumio[1024]: verbose: STATE SERVICE {"status":"play","seek":237626} Mar 05 16:46:44 volumio volumio[1024]: verbose: CURRENT POSITION 0 Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::syncState stateService play Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::syncState currentStatus pause Mar 05 16:46:44 volumio volumio[1024]: info: CoreStateMachine::pushState Mar 05 16:46:44 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Mar 05 16:46:44 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 05 16:46:44 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Mar 05 16:46:44 volumio volumio[1024]: info: [1741168004107] [RadioParadise] getMetadata started with url undefined Mar 05 16:46:44 volumio volumio[1024]: info: [1741168004124] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Mar 05 16:46:44 volumio volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 16:46:44 volumio volumio[1024]: TypeError: Cannot read property 'toUpperCase' of undefined Mar 05 16:46:44 volumio volumio[1024]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Mar 05 16:46:44 volumio volumio[1024]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Mar 05 16:46:44 volumio volumio[1024]: at ClientRequest.emit (events.js:315:20) Mar 05 16:46:44 volumio volumio[1024]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Mar 05 16:46:44 volumio volumio[1024]: at TLSSocket.emit (events.js:315:20) Mar 05 16:46:44 volumio volumio[1024]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 05 16:46:44 volumio volumio[1024]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 05 16:46:44 volumio volumio[1024]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 05 16:46:44 volumio volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 16:46:44 volumio sudo[25438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 16:45 Mar 05 16:46:44 volumio sudo[25438]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"