-- Logs begin at Wed 2024-04-17 00:10:22 UTC, end at Sun 2024-08-18 23:12:36 UTC. -- Aug 18 23:11:01 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:06 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:12 volumio volumio[1865]: info: VolumeController::SetAlsaVolume- Aug 18 23:11:12 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:11:12 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:11:12 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:11:12 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:11:12 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:17 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:21 volumio volumio[1865]: info: VolumeController::SetAlsaVolume+ Aug 18 23:11:21 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:11:21 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:11:21 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:11:21 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:11:23 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:29 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:34 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:39 volumio volumio[1865]: info: VolumeController::SetAlsaVolume- Aug 18 23:11:39 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:11:39 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:11:39 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:11:39 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:11:40 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:46 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:47 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 23:11:51 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:11:51 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 23:11:57 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:02 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:08 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:10 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 18 23:12:10 volumio volumio[1865]: info: Preload queue cleared Aug 18 23:12:13 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Aug 18 23:12:13 volumio volumio[1865]: info: Preload queue cleared Aug 18 23:12:14 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:16 volumio volumio[1865]: info: Preload queue cleared Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::ClearQueue Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::stPlaybackTimer Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::updateTrackBlock Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrackBlock Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::serviceStop Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::serviceStop Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::stop Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::clearPlayQueue Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::saveQueue Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushQueue Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::addQueueItems Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::addQueueItems Aug 18 23:12:16 volumio volumio[1865]: info: Preload queue cleared Aug 18 23:12:16 volumio volumio[1865]: info: Adding Item to queue: webkbs/0 Aug 18 23:12:16 volumio volumio[1865]: info: Exploding uri webkbs/0 in service personal_radio Aug 18 23:12:16 volumio volumio[1865]: info: Aug 18 23:12:16 volumio volumio[1865]: ---------------------------- MPD announces state update: player Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand stop took 33 milliseconds Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::getState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand status Aug 18 23:12:16 volumio volumio[1865]: info: Aug 18 23:12:16 volumio volumio[1865]: ---------------------------- MPD announces state update: player Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::getState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand status Aug 18 23:12:16 volumio volumio[1865]: info: Aug 18 23:12:16 volumio volumio[1865]: ---------------------------- MPD announces state update: player Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::getState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand status Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand status took 6 milliseconds Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand status took 4 milliseconds Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand status took 3 milliseconds Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseState Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 18 23:12:16 volumio volumio[1865]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseTrackInfo Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseTrackInfo Aug 18 23:12:16 volumio volumio[1865]: verbose: ControllerMpd::parseTrackInfo Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::servicePushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love poem","artist":"IU","album":"Love poem","uri":"INTERNAL/IU (아이유) - Love poem [2019.11.18] [EP] [WEB-FLAC]/1-06. Love poem.flac","trackType":"flac"} Aug 18 23:12:16 volumio volumio[1865]: verbose: CURRENT POSITION 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState stateService stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState currentStatus stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: No code Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::servicePushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love poem","artist":"IU","album":"Love poem","uri":"INTERNAL/IU (아이유) - Love poem [2019.11.18] [EP] [WEB-FLAC]/1-06. Love poem.flac","trackType":"flac"} Aug 18 23:12:16 volumio volumio[1865]: verbose: CURRENT POSITION 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState stateService stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState currentStatus stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: No code Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: ControllerMpd::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::servicePushState Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Love poem","artist":"IU","album":"Love poem","uri":"INTERNAL/IU (아이유) - Love poem [2019.11.18] [EP] [WEB-FLAC]/1-06. Love poem.flac","trackType":"flac"} Aug 18 23:12:16 volumio volumio[1865]: verbose: CURRENT POSITION 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState stateService stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::syncState currentStatus stop Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: No code Aug 18 23:12:16 volumio volumio[1865]: info: CoreStateMachine::pushState Aug 18 23:12:16 volumio volumio[1865]: info: CorePlayQueue::getTrack 5 Aug 18 23:12:16 volumio volumio[1865]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:16 volumio volumio[1865]: info: ------------------------------ 72ms Aug 18 23:12:16 volumio volumio[1865]: info: ------------------------------ 72ms Aug 18 23:12:16 volumio volumio[1865]: info: ------------------------------ 72ms Aug 18 23:12:16 volumio volumio[1865]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:12:16 volumio volumio[1865]: ReferenceError: self is not defined Aug 18 23:12:16 volumio volumio[1865]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Aug 18 23:12:16 volumio volumio[1865]: at /data/plugins/music_service/personal_radio/index.js:487:12 Aug 18 23:12:16 volumio volumio[1865]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:463:26) Aug 18 23:12:16 volumio volumio[1865]: at Request.self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Aug 18 23:12:16 volumio volumio[1865]: at Request.emit (events.js:315:20) Aug 18 23:12:16 volumio volumio[1865]: at Request. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:1044:10) Aug 18 23:12:16 volumio volumio[1865]: at Request.emit (events.js:315:20) Aug 18 23:12:16 volumio volumio[1865]: at IncomingMessage. (/data/plugins/music_service/personal_radio/node_modules/request/request.js:965:12) Aug 18 23:12:16 volumio volumio[1865]: at IncomingMessage.emit (events.js:327:22) Aug 18 23:12:16 volumio volumio[1865]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 18 23:12:16 volumio volumio[1865]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 18 23:12:16 volumio volumio[1865]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:12:17 volumio sudo[2506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 23:11 Aug 18 23:12:17 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:17 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:17 volumio volumio-remote-updater[505]: [2024-08-18 23:12:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 18 23:12:17 volumio volumio-remote-updater[505]: [2024-08-18 23:12:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 18 23:12:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 18 23:12:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 18 23:12:17 volumio systemd[1]: Started dynamicswap service. Aug 18 23:12:17 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 18 23:12:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 18 23:12:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Aug 18 23:12:17 volumio systemd[1]: Stopped Volumio Backend Module. Aug 18 23:12:17 volumio systemd[1]: Started Volumio Backend Module. Aug 18 23:12:17 volumio systemd[1]: Started dynamicswap service. Aug 18 23:12:17 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 18 23:12:19 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:20 volumio volumio[2518]: info: ------------------------------------------- Aug 18 23:12:20 volumio volumio[2518]: info: ----- Volumio3 ---- Aug 18 23:12:20 volumio volumio[2518]: info: ------------------------------------------- Aug 18 23:12:20 volumio volumio[2518]: info: ----- System startup ---- Aug 18 23:12:20 volumio volumio[2518]: info: ------------------------------------------- Aug 18 23:12:20 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 23:12:21 volumio volumio[2518]: info: MYVOLUMIO Environment detected Aug 18 23:12:21 volumio volumio[2518]: info: Plugin folders cleanup Aug 18 23:12:21 volumio volumio[2518]: info: Scanning into folder /volumio/app/plugins/ Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category audio_interface Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category miscellanea Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category music_service Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category plugins.json Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category system_controller Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category user_interface Aug 18 23:12:21 volumio volumio[2518]: info: Scanning into folder /data/plugins/ Aug 18 23:12:21 volumio volumio[2518]: info: Scanning category music_service Aug 18 23:12:21 volumio volumio[2518]: info: Plugin folders cleanup completed Aug 18 23:12:21 volumio volumio[2518]: info: ------------------------------------------- Aug 18 23:12:21 volumio volumio[2518]: info: ----- Core plugins startup ---- Aug 18 23:12:21 volumio volumio[2518]: info: ------------------------------------------- Aug 18 23:12:21 volumio volumio[2518]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 23:12:21 volumio volumio[2518]: info: Adding plugin upnp to MyMusic Plugins Aug 18 23:12:21 volumio volumio[2518]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 23:12:21 volumio volumio[2518]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 23:12:21 volumio volumio[2518]: info: Loading plugins from folder /data/plugins/ Aug 18 23:12:21 volumio volumio[2518]: info: Loading plugin "system"... Aug 18 23:12:21 volumio volumio[2518]: info: Loading plugin "appearance"... Aug 18 23:12:22 volumio volumio-remote-updater[505]: [2024-08-18 23:12:22] [connect] Successful connection Aug 18 23:12:22 volumio volumio[2518]: info: Loading plugin "network"... Aug 18 23:12:23 volumio volumio[2518]: info: Refreshing Cached IP Addresses Aug 18 23:12:23 volumio sudo[2546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 23:12:23 volumio sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:23 volumio sudo[2546]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:23 volumio sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "services"... Aug 18 23:12:23 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "alsa_controller"... Aug 18 23:12:23 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:23 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 18 23:12:23 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:23 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "wizard"... Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "networkfs"... Aug 18 23:12:23 volumio volumio[2518]: info: Starting Udev Watcher for removable devices Aug 18 23:12:23 volumio volumio[2518]: info: Ignoring mount for partition: boot Aug 18 23:12:23 volumio volumio[2518]: info: Ignoring mount for partition: volumio Aug 18 23:12:23 volumio volumio[2518]: info: Ignoring mount for partition: volumio_data Aug 18 23:12:23 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "volumio_command_line_client"... Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "upnp"... Aug 18 23:12:23 volumio volumio[2518]: info: [1724022743276] Starting Upmpd Daemon Aug 18 23:12:23 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "my_music"... Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "mpd"... Aug 18 23:12:23 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:23 volumio volumio[2518]: info: Loading plugin "upnp_browser"... Aug 18 23:12:24 volumio volumio[2518]: info: Loading plugin "alarm-clock"... Aug 18 23:12:24 volumio volumio[2518]: info: Loading plugin "airplay_emulation"... Aug 18 23:12:24 volumio volumio[2518]: info: Starting Shairport Sync Aug 18 23:12:24 volumio volumio[2518]: info: Loading plugin "last_100"... Aug 18 23:12:24 volumio volumio[2518]: info: Loading plugin "webradio"... Aug 18 23:12:24 volumio volumio[2518]: info: Loading plugin "i2s_dacs"... Aug 18 23:12:25 volumio volumio[2518]: info: I2S DAC not set, start Auto-detection Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "volumiodiscovery"... Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** For more information see Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 23:12:25 volumio node[2518]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 23:12:25 volumio volumio[2518]: *** WARNING *** For more information see Aug 18 23:12:25 volumio node[2518]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 23:12:25 volumio node[2518]: *** WARNING *** For more information see Aug 18 23:12:25 volumio node[2518]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 23:12:25 volumio node[2518]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 23:12:25 volumio node[2518]: *** WARNING *** For more information see Aug 18 23:12:25 volumio volumio[2518]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 23:12:25 volumio volumio[2518]: info: Discovery: Started advertising with name: Volumio Aug 18 23:12:25 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "outputs"... Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "albumart"... Aug 18 23:12:25 volumio volumio[2518]: info: Plugin example_plugin is not enabled Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "inputs"... Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "updater_comm"... Aug 18 23:12:25 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:25 volumio volumio[2518]: info: Plugin mpdemulation is not enabled Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "rest_api"... Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "websocket"... Aug 18 23:12:25 volumio volumio[2518]: info: Loading plugin "personal_radio"... Aug 18 23:12:26 volumio volumio[2518]: Forking 3 albumart workers Aug 18 23:12:27 volumio volumio[2518]: info: ControllerPersonalRadio::constructor Aug 18 23:12:27 volumio volumio[2518]: info: Loading plugin "radio_paradise"... Aug 18 23:12:27 volumio volumio[2518]: Starting albumart workers Aug 18 23:12:27 volumio volumio[2518]: Starting albumart workers Aug 18 23:12:27 volumio volumio[2518]: Starting albumart workers Aug 18 23:12:27 volumio volumio[2518]: info: Applying required configuration parameters for plugin radio_paradise Aug 18 23:12:27 volumio volumio[2518]: info: [1724022747364] [RadioParadise] API delay: 5 Aug 18 23:12:27 volumio volumio[2518]: info: Loading i18n strings for locale ko Aug 18 23:12:27 volumio volumio[2518]: Updating browse sources language Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::initPlayerControls Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: Express server listening on port 3000 Aug 18 23:12:27 volumio volumio[2518]: [Metrics] WebUI: 8s 588.65ms Aug 18 23:12:27 volumio volumio[2518]: info: CoreStateMachine::resetVolumioState Aug 18 23:12:27 volumio volumio[2518]: info: CoreStateMachine::getcurrentVolume Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 23:12:27 volumio volumio[2518]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.18 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/25.0 Chrome/121.0.0.0 Safari/537.36 Total Clients: 1 Aug 18 23:12:27 volumio volumio[2518]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.18 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/25.0 Chrome/121.0.0.0 Safari/537.36 Total Clients: 2 Aug 18 23:12:27 volumio volumio[2518]: info: CoreStateMachine::pushState Aug 18 23:12:27 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 23:12:27 volumio volumio[2518]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::updateTrackBlock Aug 18 23:12:28 volumio volumio[2518]: info: CorePlayQueue::getTrackBlock Aug 18 23:12:28 volumio volumio[2518]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 23:12:28 volumio volumio[2518]: info: Reloading queue from file Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::setRepeat null single undefined Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::pushState Aug 18 23:12:28 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:28 volumio volumio[2518]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::setRandom null Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::pushState Aug 18 23:12:28 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:28 volumio volumio[2518]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:28 volumio volumio[2518]: info: Setting Device type: Raspberry PI Aug 18 23:12:28 volumio volumio[2518]: info: CoreStateMachine::pushState Aug 18 23:12:28 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:28 volumio volumio[2518]: info: CoreCommandRouter::volumioPushState Aug 18 23:12:28 volumio volumio[2518]: info: Discovery: adding 56f010e1-d353-4489-be0e-e56d13c738fd Aug 18 23:12:28 volumio volumio[2518]: info: Discovery: Found device Volumio Aug 18 23:12:28 volumio volumio[2518]: info: CoreCommandRouter::volumioGetState Aug 18 23:12:28 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:29 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 23:12:30 volumio wpa_supplicant[759]: wlan0: Failed to initiate sched scan Aug 18 23:12:31 volumio volumio[2518]: verbose: New Socket.io Connection to 192.168.0.8 from 192.168.0.18 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/25.0 Chrome/121.0.0.0 Safari/537.36 Total Clients: 3 Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetState Aug 18 23:12:31 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetState Aug 18 23:12:31 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 23:12:31 volumio volumio[2518]: info: Received Get System Info Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 23:12:31 volumio volumio[2518]: info: Discovery: Getting this device information Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetState Aug 18 23:12:31 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetState Aug 18 23:12:31 volumio volumio[2518]: info: CorePlayQueue::getTrack 0 Aug 18 23:12:31 volumio volumio[2518]: info: Listing playlists Aug 18 23:12:31 volumio volumio[2518]: info: CoreCommandRouter::volumioGetQueue Aug 18 23:12:31 volumio volumio[2518]: info: CoreStateMachine::getQueue Aug 18 23:12:31 volumio volumio[2518]: info: CorePlayQueue::getQueue Aug 18 23:12:33 volumio sudo[2633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 23:12:33 volumio sudo[2633]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:33 volumio sudo[2633]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:33 volumio sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 23:12:33 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:33 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:33 volumio sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 18 23:12:33 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 23:12:33 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root Aug 18 23:12:33 volumio volumio[2518]: info: Upmpdcli Daemon Started Aug 18 23:12:34 volumio volumio-remote-updater[505]: [2024-08-18 23:12:34] [connect] Successful connection Aug 18 23:12:34 volumio volumio-remote-updater[505]: [2024-08-18 23:12:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724022754 101 Aug 18 23:12:34 volumio volumio[2518]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Aug 18 23:12:35 volumio volumio[2518]: info: Preload queue cleared Aug 18 23:12:35 volumio volumio[2518]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 18 23:12:35 volumio volumio[2518]: info: CoreStateMachine::ClearQueue Aug 18 23:12:35 volumio volumio[2518]: info: CoreStateMachine::stop Aug 18 23:12:35 volumio volumio[2518]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 23:12:35 volumio volumio[2518]: info: CorePlayQueue::clearPlayQueue Aug 18 23:12:35 volumio volumio[2518]: info: CorePlayQueue::saveQueue Aug 18 23:12:35 volumio volumio[2518]: info: CoreCommandRouter::volumioPushQueue Aug 18 23:12:35 volumio volumio[2518]: info: CoreStateMachine::addQueueItems Aug 18 23:12:35 volumio volumio[2518]: info: CorePlayQueue::addQueueItems Aug 18 23:12:35 volumio volumio[2518]: info: Preload queue cleared Aug 18 23:12:35 volumio volumio[2518]: info: Adding Item to queue: webkbs/0 Aug 18 23:12:35 volumio volumio[2518]: info: Exploding uri webkbs/0 in service personal_radio Aug 18 23:12:35 volumio volumio[2518]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:12:35 volumio volumio[2518]: TypeError: Cannot read property 'PLUGIN_NAME' of undefined Aug 18 23:12:35 volumio volumio[2518]: at ControllerPersonalRadio.getRadioI18nString (/data/plugins/music_service/personal_radio/index.js:558:23) Aug 18 23:12:35 volumio volumio[2518]: at ControllerPersonalRadio.explodeUri (/data/plugins/music_service/personal_radio/index.js:347:21) Aug 18 23:12:35 volumio volumio[2518]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1359:16) Aug 18 23:12:35 volumio volumio[2518]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Aug 18 23:12:35 volumio volumio[2518]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Aug 18 23:12:35 volumio volumio[2518]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Aug 18 23:12:35 volumio volumio[2518]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) Aug 18 23:12:35 volumio volumio[2518]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:507:23) Aug 18 23:12:35 volumio volumio[2518]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:477:17) Aug 18 23:12:35 volumio volumio[2518]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:118:33) Aug 18 23:12:35 volumio volumio[2518]: at Socket.emit (events.js:315:20) Aug 18 23:12:35 volumio volumio[2518]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 18 23:12:35 volumio volumio[2518]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 18 23:12:35 volumio volumio[2518]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 23:12:36 volumio sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 23:11 Aug 18 23:12:36 volumio sudo[2650]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"