-- Logs begin at Tue 2025-01-14 01:22:31 UTC, end at Sun 2025-02-16 00:25:14 UTC. -- Feb 16 00:24:01 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:06 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:12 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:18 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:23 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:29 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:34 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:40 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:45 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:51 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:52 volumio volumio[1267]: Searching all installed plugins Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: , search Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Feb 16 00:24:52 volumio volumio[1267]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: webradio , search Feb 16 00:24:52 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: personal_radio , search Feb 16 00:24:52 volumio volumio[1267]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin personal_radio Feb 16 00:24:53 volumio volumio[1267]: info: All search sources collected, pushing search results Feb 16 00:24:56 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::addQueueItems Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::addQueueItems Feb 16 00:24:59 volumio volumio[1267]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1889196 Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::saveQueue Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::volumioPushQueue Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::updateTrackBlock Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrackBlock Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::volumioPlay Feb 16 00:24:59 volumio volumio[1267]: UNSET VOLATILE Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::play index 1 Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::stop Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 00:24:59 volumio volumio[1267]: UNSET VOLATILE Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::stPlaybackTimer Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::updateTrackBlock Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrackBlock Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::pushState Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrack 0 Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::serviceStop Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrack 0 Feb 16 00:24:59 volumio volumio[1267]: info: CoreCommandRouter::serviceStop Feb 16 00:24:59 volumio volumio[1267]: info: [1739665499693] ControllerWebradio::stop Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::play index undefined Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrack 1 Feb 16 00:24:59 volumio volumio[1267]: info: CoreStateMachine::startPlaybackTimer Feb 16 00:24:59 volumio volumio[1267]: info: CorePlayQueue::getTrack 1 Feb 16 00:24:59 volumio volumio[1267]: info: [1739665499768] ControllerWebradio::clearAddPlayTrack Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand stop Feb 16 00:24:59 volumio volumio[1267]: info: Feb 16 00:24:59 volumio volumio[1267]: ---------------------------- MPD announces state update: player Feb 16 00:24:59 volumio volumio[1267]: info: ControllerMpd::getState Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand clear Feb 16 00:24:59 volumio volumio[1267]: info: Feb 16 00:24:59 volumio volumio[1267]: ---------------------------- MPD announces system playlist update Feb 16 00:24:59 volumio volumio[1267]: info: Ignoring MPD Status Update Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::parseState Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1889196" Feb 16 00:24:59 volumio volumio[1267]: error: updateQueue error: null Feb 16 00:24:59 volumio volumio[1267]: info: ------------------------------ 9ms Feb 16 00:24:59 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo Feb 16 00:24:59 volumio volumio[1267]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 16 00:24:59 volumio volumio[1267]: info: ------------------------------ 22ms Feb 16 00:25:00 volumio volumio[1267]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 16 00:25:00 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand play Feb 16 00:25:00 volumio volumio[1267]: info: Feb 16 00:25:00 volumio volumio[1267]: ---------------------------- MPD announces system playlist update Feb 16 00:25:00 volumio volumio[1267]: info: Ignoring MPD Status Update Feb 16 00:25:00 volumio volumio[1267]: info: ------------------------------ 5ms Feb 16 00:25:02 volumio volumio[1267]: info: Feb 16 00:25:02 volumio volumio[1267]: ---------------------------- MPD announces state update: player Feb 16 00:25:02 volumio volumio[1267]: info: ControllerMpd::getState Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::parseState Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 00:25:02 volumio volumio[1267]: info: Feb 16 00:25:02 volumio volumio[1267]: ---------------------------- MPD announces system playlist update Feb 16 00:25:02 volumio volumio[1267]: info: Ignoring MPD Status Update Feb 16 00:25:02 volumio volumio[1267]: info: Feb 16 00:25:02 volumio volumio[1267]: ---------------------------- MPD announces state update: player Feb 16 00:25:02 volumio volumio[1267]: info: ControllerMpd::getState Feb 16 00:25:02 volumio wpa_supplicant[1047]: wlan0: Failed to initiate sched scan Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand status Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo Feb 16 00:25:02 volumio volumio[1267]: info: ControllerMpd::pushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Feb 16 00:25:02 volumio volumio[1267]: info: CorePlayQueue::getTrack 1 Feb 16 00:25:02 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"MERULA - Viaggio Musicale - Merula / Canzon 'la Cattarina'","artist":"baroque","album":null,"uri":"http://185.33.21.112:80/baroque_mobile_aac","trackType":""} Feb 16 00:25:02 volumio volumio[1267]: verbose: CURRENT POSITION 1 Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::syncState stateService play Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::syncState currentStatus stop Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::pushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 00:25:02 volumio volumio[1267]: info: ------------------------------ 74ms Feb 16 00:25:02 volumio volumio[1267]: info: ------------------------------ 59ms Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::parseState Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 00:25:02 volumio volumio[1267]: verbose: ControllerMpd::parseTrackInfo Feb 16 00:25:02 volumio volumio[1267]: info: ControllerMpd::pushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::servicePushState Feb 16 00:25:02 volumio volumio[1267]: info: CorePlayQueue::getTrack 1 Feb 16 00:25:02 volumio volumio[1267]: verbose: STATE SERVICE {"status":"play","position":0,"seek":490,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"MERULA - Viaggio Musicale - Merula / Canzon 'la Cattarina'","artist":"baroque","album":null,"uri":"http://185.33.21.112:80/baroque_mobile_aac","trackType":""} Feb 16 00:25:02 volumio volumio[1267]: verbose: CURRENT POSITION 1 Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::syncState stateService play Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::syncState currentStatus play Feb 16 00:25:02 volumio volumio[1267]: info: Received an update from plugin. extracting info from payload Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::pushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 00:25:02 volumio volumio[1267]: info: CoreStateMachine::pushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::volumioPushState Feb 16 00:25:02 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 00:25:02 volumio volumio[1267]: info: ------------------------------ 104ms Feb 16 00:25:07 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="U+Net3B2C" Feb 16 00:25:07 volumio wpa_supplicant[1047]: wlan0: Trying to associate with SSID 'U+Net3B2C' Feb 16 00:25:08 volumio wpa_supplicant[1047]: wlan0: Associated with 08:5d:dd:51:3b:2a Feb 16 00:25:08 volumio wpa_supplicant[1047]: wlan0: WPA: Key negotiation completed with 08:5d:dd:51:3b:2a [PTK=CCMP GTK=CCMP] Feb 16 00:25:08 volumio wpa_supplicant[1047]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:5d:dd:51:3b:2a completed [id=0 id_str=] Feb 16 00:25:08 volumio dhcpcd[1069]: wlan0: carrier acquired Feb 16 00:25:08 volumio dhcpcd[1069]: wlan0: soliciting an IPv6 router Feb 16 00:25:08 volumio dhcpcd[1069]: wlan0: soliciting a DHCP lease Feb 16 00:25:08 volumio dhcpcd[1069]: wlan0: offered 192.168.219.147 from 192.168.219.1 Feb 16 00:25:09 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Feb 16 00:25:11 volumio volumio[1267]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Feb 16 00:25:12 volumio volumio[1267]: info: CoreCommandRouter::volumioAddQueueItems Feb 16 00:25:12 volumio volumio[1267]: info: CoreStateMachine::addQueueItems Feb 16 00:25:12 volumio volumio[1267]: info: CorePlayQueue::addQueueItems Feb 16 00:25:12 volumio volumio[1267]: info: Adding Item to queue: webkbs/0 Feb 16 00:25:12 volumio volumio[1267]: info: Exploding uri webkbs/0 in service personal_radio Feb 16 00:25:13 volumio volumio[1267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 00:25:13 volumio volumio[1267]: ReferenceError: self is not defined Feb 16 00:25:13 volumio volumio[1267]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Feb 16 00:25:13 volumio volumio[1267]: at /data/plugins/music_service/personal_radio/index.js:487:12 Feb 16 00:25:13 volumio volumio[1267]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Feb 16 00:25:13 volumio volumio[1267]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Feb 16 00:25:13 volumio volumio[1267]: at emitOne (events.js:116:13) Feb 16 00:25:13 volumio volumio[1267]: at Request.emit (events.js:211:7) Feb 16 00:25:13 volumio volumio[1267]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Feb 16 00:25:13 volumio volumio[1267]: at emitOne (events.js:116:13) Feb 16 00:25:13 volumio volumio[1267]: at ClientRequest.emit (events.js:211:7) Feb 16 00:25:13 volumio volumio[1267]: at Socket.socketErrorListener (_http_client.js:387:9) Feb 16 00:25:13 volumio volumio[1267]: at emitOne (events.js:116:13) Feb 16 00:25:13 volumio volumio[1267]: at Socket.emit (events.js:211:7) Feb 16 00:25:13 volumio volumio[1267]: at emitErrorNT (internal/streams/destroy.js:64:8) Feb 16 00:25:13 volumio volumio[1267]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Feb 16 00:25:13 volumio volumio[1267]: at process._tickCallback (internal/process/next_tick.js:180:9) Feb 16 00:25:13 volumio volumio[1267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 00:25:14 volumio dhcpcd[1069]: wlan0: leased 192.168.219.147 for 86400 seconds Feb 16 00:25:14 volumio avahi-daemon[1320]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.219.147. Feb 16 00:25:14 volumio dhcpcd[1069]: eth0: adding route to 192.168.219.0/24 Feb 16 00:25:14 volumio dhcpcd[1069]: eth0: adding default route via 192.168.219.1 Feb 16 00:25:14 volumio dhcpcd[1069]: wlan0: adding host route to 192.168.219.147 via 127.0.0.1 Feb 16 00:25:14 volumio dhcpcd[1069]: wlan0: adding route to 192.168.219.0/24 Feb 16 00:25:14 volumio avahi-daemon[1320]: New relevant interface wlan0.IPv4 for mDNS. Feb 16 00:25:14 volumio dhcpcd[1069]: wlan0: adding default route via 192.168.219.1 Feb 16 00:25:14 volumio avahi-daemon[1320]: Registering new address record for 192.168.219.147 on wlan0.IPv4. Feb 16 00:25:14 volumio dhcpcd[1069]: eth0: removing route to 192.168.219.0/24 Feb 16 00:25:14 volumio dhcpcd[1069]: eth0: removing default route via 192.168.219.1 Feb 16 00:25:14 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 00:24 Feb 16 00:25:14 volumio sudo[1900]: 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="b9cd485c2d7861a79d18f42ff0fdb052338b2239" VOLUMIO_FE_VERSION="002b92c82c889cd6d6dba7b53841c5ae23bbbe3e" VOLUMIO_BE_VERSION="7d2ba3a94da666f8c268b206dcaf6a466691b221" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 8 18:17:29 CEST 2020" VOLUMIO_VERSION="2.779" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d3e1021b8bd596622d29cc9abe301111"