-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-05-03 11:16:48 CEST. -- May 03 11:15:09 volumio kernel: hwmon hwmon1: Undervoltage detected! May 03 11:15:12 volumio volumio[27316]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 4 May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetVisibleSources May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetState May 03 11:15:13 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetState May 03 11:15:13 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 03 11:15:13 volumio volumio[27316]: info: Received Get System Info May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:15:13 volumio volumio[27316]: info: Discovery: Getting this device information May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetState May 03 11:15:13 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetState May 03 11:15:13 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:13 volumio volumio[27316]: info: Listing playlists May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::volumioGetQueue May 03 11:15:13 volumio volumio[27316]: info: CoreStateMachine::getQueue May 03 11:15:13 volumio volumio[27316]: info: CorePlayQueue::getQueue May 03 11:15:13 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 03 11:15:16 volumio dnsmasq-dhcp[754]: no address range available for DHCP request via wlan0 May 03 11:15:17 volumio dnsmasq-dhcp[754]: no address range available for DHCP request via wlan0 May 03 11:15:19 volumio kernel: hwmon hwmon1: Voltage normalised May 03 11:15:25 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:15:25 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:28 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:15:28 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:34 volumio dnsmasq-dhcp[754]: no address range available for DHCP request via wlan0 May 03 11:15:35 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 03 11:15:35 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand update May 03 11:15:44 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioReplaceandPlayItems May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::ClearQueue May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::stPlaybackTimer May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::updateTrackBlock May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrackBlock May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::serviceStop May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::serviceStop May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::stop May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand stop May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::clearPlayQueue May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::addQueueItems May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::addQueueItems May 03 11:15:44 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:44 volumio volumio[27316]: info: Adding Item to queue: upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 May 03 11:15:44 volumio volumio[27316]: info: Exploding uri upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 in service upnp_browser May 03 11:15:44 volumio volumio[27316]: info: May 03 11:15:44 volumio volumio[27316]: ---------------------------- MPD announces state update: player May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand stop took 13 milliseconds May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::getState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand status May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand status took 1 milliseconds May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:15:44 volumio volumio[27316]: info: May 03 11:15:44 volumio volumio[27316]: ---------------------------- MPD announces state update: player May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::getState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand status May 03 11:15:44 volumio volumio[27316]: info: May 03 11:15:44 volumio volumio[27316]: ---------------------------- MPD announces state update: player May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::getState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand status May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand playlistinfo took 3 milliseconds May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand status took 2 milliseconds May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand status took 1 milliseconds May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseTrackInfo May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseState May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::pushState May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::servicePushState May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: 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":"Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","artist":null,"album":null,"uri":"USB/94E1-235E/stanjel_glasba/Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","trackType":"m4a"} May 03 11:15:44 volumio volumio[27316]: verbose: CURRENT POSITION 4 May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState stateService stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState currentStatus stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: No code May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 4 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: ------------------------------ 27ms May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::updateTrackBlock May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrackBlock May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPlay May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::play index 0 May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::play index undefined May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand playlistinfo took 31 milliseconds May 03 11:15:44 volumio volumio[27316]: info: sendMpdCommand playlistinfo took 32 milliseconds May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseTrackInfo May 03 11:15:44 volumio volumio[27316]: verbose: ControllerMpd::parseTrackInfo May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::pushState May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::servicePushState May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: 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":"Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","artist":null,"album":null,"uri":"USB/94E1-235E/stanjel_glasba/Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","trackType":"m4a"} May 03 11:15:44 volumio volumio[27316]: verbose: CURRENT POSITION 0 May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState stateService stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState currentStatus stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: No code May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: ControllerMpd::pushState May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::servicePushState May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: 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":"Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","artist":null,"album":null,"uri":"USB/94E1-235E/stanjel_glasba/Whiskey Blues _ Best of Slow Blues_Rock #1-1eNSWZ4x2ZU.m4a","trackType":"m4a"} May 03 11:15:44 volumio volumio[27316]: verbose: CURRENT POSITION 0 May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState stateService stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::syncState currentStatus stop May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: No code May 03 11:15:44 volumio volumio[27316]: info: CoreStateMachine::pushState May 03 11:15:44 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:44 volumio volumio[27316]: info: CoreCommandRouter::volumioPushState May 03 11:15:44 volumio volumio[27316]: info: ------------------------------ 75ms May 03 11:15:44 volumio volumio[27316]: info: ------------------------------ 75ms May 03 11:15:46 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:46 volumio volumio[27316]: info: CoreCommandRouter::volumioReplaceandPlayItems May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::ClearQueue May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::clearPlayQueue May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:46 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::addQueueItems May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::addQueueItems May 03 11:15:46 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:46 volumio volumio[27316]: info: Adding Item to queue: upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 May 03 11:15:46 volumio volumio[27316]: info: Using cached record of: upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 May 03 11:15:46 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::updateTrackBlock May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::getTrackBlock May 03 11:15:46 volumio volumio[27316]: info: CoreCommandRouter::volumioPlay May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::play index 0 May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::play index undefined May 03 11:15:46 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:46 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:15:51 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:51 volumio volumio[27316]: info: CoreCommandRouter::volumioReplaceandPlayItems May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::ClearQueue May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::clearPlayQueue May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:51 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::addQueueItems May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::addQueueItems May 03 11:15:51 volumio volumio[27316]: info: Preload queue cleared May 03 11:15:51 volumio volumio[27316]: info: Adding Item to queue: upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 May 03 11:15:51 volumio volumio[27316]: info: Using cached record of: upnp/folder/http://10.5.5.179:8200/ctl/ContentDir@1 May 03 11:15:51 volumio volumio[27316]: info: CoreCommandRouter::volumioPushQueue May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::saveQueue May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::updateTrackBlock May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::getTrackBlock May 03 11:15:51 volumio volumio[27316]: info: CoreCommandRouter::volumioPlay May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::play index 0 May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::stop May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::play index undefined May 03 11:15:51 volumio volumio[27316]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:15:51 volumio volumio[27316]: info: CorePlayQueue::getTrack 0 May 03 11:16:02 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:16:02 volumio volumio[27316]: info: Preload queue cleared May 03 11:16:02 volumio volumio[27316]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:16:02 volumio volumio[27316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:02 volumio volumio[27316]: TypeError: Cannot read property 'length' of undefined May 03 11:16:02 volumio volumio[27316]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 03 11:16:02 volumio volumio[27316]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 03 11:16:02 volumio volumio[27316]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 03 11:16:02 volumio volumio[27316]: at Parser.emit (events.js:315:20) May 03 11:16:02 volumio volumio[27316]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 03 11:16:02 volumio volumio[27316]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 03 11:16:02 volumio volumio[27316]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 03 11:16:02 volumio volumio[27316]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 03 11:16:02 volumio volumio[27316]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 03 11:16:02 volumio volumio[27316]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 03 11:16:02 volumio volumio[27316]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 03 11:16:02 volumio volumio[27316]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 03 11:16:02 volumio volumio[27316]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 03 11:16:02 volumio volumio[27316]: at IncomingMessage.emit (events.js:327:22) May 03 11:16:02 volumio volumio[27316]: at endReadableNT (internal/streams/readable.js:1327:12) May 03 11:16:02 volumio volumio[27316]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 03 11:16:02 volumio volumio[27316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:02 volumio sudo[29409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 11:15 May 03 11:16:02 volumio sudo[29409]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:02 volumio sudo[29409]: pam_unix(sudo:session): session closed for user root May 03 11:16:02 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 03 11:16:02 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 03 11:16:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 03 11:16:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 03 11:16:02 volumio systemd[1]: Started dynamicswap service. May 03 11:16:02 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 03 11:16:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 03 11:16:02 volumio systemd[1]: Stopped Volumio Backend Module. May 03 11:16:02 volumio systemd[1]: Started Volumio Backend Module. May 03 11:16:02 volumio systemd[1]: Started dynamicswap service. May 03 11:16:03 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:03 volumio kernel: hwmon hwmon1: Undervoltage detected! May 03 11:16:05 volumio volumio[29420]: info: ------------------------------------------- May 03 11:16:05 volumio volumio[29420]: info: ----- Volumio3 ---- May 03 11:16:05 volumio volumio[29420]: info: ------------------------------------------- May 03 11:16:05 volumio volumio[29420]: info: ----- System startup ---- May 03 11:16:05 volumio volumio[29420]: info: ------------------------------------------- May 03 11:16:06 volumio volumio[29420]: info: MYVOLUMIO Environment detected May 03 11:16:06 volumio volumio[29420]: info: Plugin folders cleanup May 03 11:16:06 volumio volumio[29420]: info: Scanning into folder /volumio/app/plugins/ May 03 11:16:06 volumio volumio[29420]: info: Scanning category audio_interface May 03 11:16:06 volumio volumio[29420]: info: Scanning category miscellanea May 03 11:16:06 volumio volumio[29420]: info: Scanning category music_service May 03 11:16:06 volumio volumio[29420]: info: Scanning category plugins.json May 03 11:16:06 volumio volumio[29420]: info: Scanning category system_controller May 03 11:16:06 volumio volumio[29420]: info: Scanning category user_interface May 03 11:16:06 volumio volumio[29420]: info: Scanning into folder /data/plugins/ May 03 11:16:06 volumio volumio[29420]: info: Scanning category music_service May 03 11:16:06 volumio volumio[29420]: info: Plugin folders cleanup completed May 03 11:16:06 volumio volumio[29420]: info: ------------------------------------------- May 03 11:16:06 volumio volumio[29420]: info: ----- Core plugins startup ---- May 03 11:16:06 volumio volumio[29420]: info: ------------------------------------------- May 03 11:16:06 volumio volumio[29420]: info: Loading plugins from folder /volumio/app/plugins/ May 03 11:16:06 volumio volumio[29420]: info: Adding plugin upnp to MyMusic Plugins May 03 11:16:06 volumio volumio[29420]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 11:16:06 volumio volumio[29420]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 11:16:06 volumio volumio[29420]: info: Loading plugins from folder /data/plugins/ May 03 11:16:06 volumio volumio[29420]: info: Loading plugin "system"... May 03 11:16:06 volumio volumio[29420]: info: Loading plugin "appearance"... May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "network"... May 03 11:16:07 volumio volumio[29420]: info: Refreshing Cached IP Addresses May 03 11:16:07 volumio sudo[29448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 11:16:07 volumio sudo[29448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:07 volumio sudo[29448]: pam_unix(sudo:session): session closed for user root May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "services"... May 03 11:16:07 volumio sudo[29450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 11:16:07 volumio sudo[29450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "alsa_controller"... May 03 11:16:07 volumio sudo[29450]: pam_unix(sudo:session): session closed for user root May 03 11:16:07 volumio sudo[29453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 03 11:16:07 volumio sudo[29453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:07 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "wizard"... May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "networkfs"... May 03 11:16:07 volumio volumio[29420]: info: Starting Udev Watcher for removable devices May 03 11:16:07 volumio volumio[29420]: info: Ignoring mount for partition: boot May 03 11:16:07 volumio volumio[29420]: info: Ignoring mount for partition: volumio May 03 11:16:07 volumio volumio[29420]: info: Ignoring mount for partition: volumio_data May 03 11:16:07 volumio volumio[29420]: info: Mounting Device 94E1-235E May 03 11:16:07 volumio sudo[29462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/94E1-235E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:07 volumio sudo[29462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:07 volumio sudo[29462]: pam_unix(sudo:session): session closed for user root May 03 11:16:07 volumio volumio[29420]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:07 volumio volumio[29420]: error: Failed to mount 94E1-235E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/94E1-235E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:07 volumio volumio[29420]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:07 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "volumio_command_line_client"... May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "upnp"... May 03 11:16:07 volumio volumio[29420]: info: [1714727767677] Starting Upmpd Daemon May 03 11:16:07 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "my_music"... May 03 11:16:07 volumio volumio[29420]: info: Loading plugin "mpd"... May 03 11:16:07 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:07] [connect] Successful connection May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "upnp_browser"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "alarm-clock"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "airplay_emulation"... May 03 11:16:08 volumio volumio[29420]: info: Starting Shairport Sync May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "last_100"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "webradio"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "i2s_dacs"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "volumiodiscovery"... May 03 11:16:08 volumio volumio[29420]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:08 volumio node[29420]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:08 volumio node[29420]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:08 volumio volumio[29420]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:08 volumio volumio[29420]: *** WARNING *** For more information see May 03 11:16:08 volumio volumio[29420]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:08 volumio volumio[29420]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:08 volumio volumio[29420]: *** WARNING *** For more information see May 03 11:16:08 volumio node[29420]: *** WARNING *** For more information see May 03 11:16:08 volumio node[29420]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:08 volumio node[29420]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:08 volumio node[29420]: *** WARNING *** For more information see May 03 11:16:08 volumio volumio[29420]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 11:16:08 volumio volumio[29420]: info: Discovery: Started advertising with name: Volumio May 03 11:16:08 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "outputs"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "albumart"... May 03 11:16:08 volumio volumio[29420]: info: Plugin example_plugin is not enabled May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "inputs"... May 03 11:16:08 volumio volumio[29420]: info: Loading plugin "updater_comm"... May 03 11:16:09 volumio volumio[29420]: info: Plugin mpdemulation is not enabled May 03 11:16:09 volumio volumio[29420]: info: Loading plugin "rest_api"... May 03 11:16:09 volumio volumio[29420]: info: Loading plugin "websocket"... May 03 11:16:09 volumio volumio[29420]: info: Loading plugin "minidlna"... May 03 11:16:09 volumio volumio[29420]: info: Applying required configuration parameters for plugin minidlna May 03 11:16:09 volumio volumio[29420]: Forking 3 albumart workers May 03 11:16:09 volumio volumio[29420]: info: Loading i18n strings for locale en May 03 11:16:09 volumio volumio[29420]: Updating browse sources language May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::initPlayerControls May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:09 volumio volumio[29420]: Express server listening on port 3000 May 03 11:16:09 volumio volumio[29420]: [Metrics] WebUI: 5s 490.59ms May 03 11:16:09 volumio volumio[29420]: info: CoreStateMachine::resetVolumioState May 03 11:16:09 volumio volumio[29420]: info: CoreStateMachine::getcurrentVolume May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::volumioRetrievevolume May 03 11:16:09 volumio volumio[29420]: info: CoreStateMachine::pushState May 03 11:16:09 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:16:09 volumio volumio[29420]: info: CoreCommandRouter::volumioPushState May 03 11:16:10 volumio volumio[29420]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 1 May 03 11:16:10 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714727767 101 May 03 11:16:10 volumio volumio[29420]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 11:16:10 volumio volumio[29420]: info: Reloading queue from file May 03 11:16:10 volumio volumio[29420]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 3 May 03 11:16:10 volumio volumio[29420]: info: CoreStateMachine::setRepeat null single undefined May 03 11:16:10 volumio volumio[29420]: info: CoreStateMachine::pushState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioPushState May 03 11:16:10 volumio volumio[29420]: info: CoreStateMachine::setRandom null May 03 11:16:10 volumio volumio[29420]: info: CoreStateMachine::pushState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioPushState May 03 11:16:10 volumio volumio[29420]: info: Setting Device type: Raspberry PI May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 03 11:16:10 volumio volumio[29420]: info: Discovery: adding b18c929a-a525-4090-b1b7-0843470d8394 May 03 11:16:10 volumio volumio[29420]: info: Discovery: Found device Volumio May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetVisibleSources May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 03 11:16:10 volumio volumio[29420]: info: Received Get System Info May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:16:10 volumio volumio[29420]: info: Discovery: Getting this device information May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetState May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getTrack 0 May 03 11:16:10 volumio volumio[29420]: info: Listing playlists May 03 11:16:10 volumio volumio[29420]: info: CoreCommandRouter::volumioGetQueue May 03 11:16:10 volumio volumio[29420]: info: CoreStateMachine::getQueue May 03 11:16:10 volumio volumio[29420]: info: CorePlayQueue::getQueue May 03 11:16:10 volumio volumio[29420]: Starting albumart workers May 03 11:16:10 volumio volumio[29420]: Starting albumart workers May 03 11:16:10 volumio volumio[29420]: Starting albumart workers May 03 11:16:11 volumio sudo[29453]: pam_unix(sudo:session): session closed for user root May 03 11:16:13 volumio kernel: hwmon hwmon1: Voltage normalised May 03 11:16:16 volumio volumio[29420]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:16:16 volumio volumio[29420]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:16 volumio volumio[29420]: TypeError: Cannot read property 'length' of undefined May 03 11:16:16 volumio volumio[29420]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 03 11:16:16 volumio volumio[29420]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 03 11:16:16 volumio volumio[29420]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 03 11:16:16 volumio volumio[29420]: at Parser.emit (events.js:315:20) May 03 11:16:16 volumio volumio[29420]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 03 11:16:16 volumio volumio[29420]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 03 11:16:16 volumio volumio[29420]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 03 11:16:16 volumio volumio[29420]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 03 11:16:16 volumio volumio[29420]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 03 11:16:16 volumio volumio[29420]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 03 11:16:16 volumio volumio[29420]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 03 11:16:16 volumio volumio[29420]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 03 11:16:16 volumio volumio[29420]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 03 11:16:16 volumio volumio[29420]: at IncomingMessage.emit (events.js:327:22) May 03 11:16:16 volumio volumio[29420]: at endReadableNT (internal/streams/readable.js:1327:12) May 03 11:16:16 volumio volumio[29420]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 03 11:16:16 volumio volumio[29420]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:17 volumio sudo[29538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 11:15 May 03 11:16:17 volumio sudo[29538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:17 volumio sudo[29538]: pam_unix(sudo:session): session closed for user root May 03 11:16:17 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 03 11:16:17 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 03 11:16:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 03 11:16:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 03 11:16:17 volumio systemd[1]: Started dynamicswap service. May 03 11:16:17 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 03 11:16:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. May 03 11:16:17 volumio systemd[1]: Stopped Volumio Backend Module. May 03 11:16:17 volumio systemd[1]: Started Volumio Backend Module. May 03 11:16:17 volumio systemd[1]: Started dynamicswap service. May 03 11:16:17 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:17 volumio kernel: hwmon hwmon1: Undervoltage detected! May 03 11:16:19 volumio volumio[29549]: info: ------------------------------------------- May 03 11:16:19 volumio volumio[29549]: info: ----- Volumio3 ---- May 03 11:16:19 volumio volumio[29549]: info: ------------------------------------------- May 03 11:16:19 volumio volumio[29549]: info: ----- System startup ---- May 03 11:16:19 volumio volumio[29549]: info: ------------------------------------------- May 03 11:16:20 volumio volumio[29549]: info: MYVOLUMIO Environment detected May 03 11:16:20 volumio volumio[29549]: info: Plugin folders cleanup May 03 11:16:20 volumio volumio[29549]: info: Scanning into folder /volumio/app/plugins/ May 03 11:16:20 volumio volumio[29549]: info: Scanning category audio_interface May 03 11:16:20 volumio volumio[29549]: info: Scanning category miscellanea May 03 11:16:20 volumio volumio[29549]: info: Scanning category music_service May 03 11:16:20 volumio volumio[29549]: info: Scanning category plugins.json May 03 11:16:20 volumio volumio[29549]: info: Scanning category system_controller May 03 11:16:20 volumio volumio[29549]: info: Scanning category user_interface May 03 11:16:20 volumio volumio[29549]: info: Scanning into folder /data/plugins/ May 03 11:16:20 volumio volumio[29549]: info: Scanning category music_service May 03 11:16:20 volumio volumio[29549]: info: Plugin folders cleanup completed May 03 11:16:20 volumio volumio[29549]: info: ------------------------------------------- May 03 11:16:20 volumio volumio[29549]: info: ----- Core plugins startup ---- May 03 11:16:20 volumio volumio[29549]: info: ------------------------------------------- May 03 11:16:20 volumio volumio[29549]: info: Loading plugins from folder /volumio/app/plugins/ May 03 11:16:20 volumio volumio[29549]: info: Adding plugin upnp to MyMusic Plugins May 03 11:16:20 volumio volumio[29549]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 11:16:20 volumio volumio[29549]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 11:16:20 volumio volumio[29549]: info: Loading plugins from folder /data/plugins/ May 03 11:16:20 volumio volumio[29549]: info: Loading plugin "system"... May 03 11:16:20 volumio volumio[29549]: info: Loading plugin "appearance"... May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "network"... May 03 11:16:21 volumio volumio[29549]: info: Refreshing Cached IP Addresses May 03 11:16:21 volumio sudo[29578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 11:16:21 volumio sudo[29578]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:21 volumio sudo[29578]: pam_unix(sudo:session): session closed for user root May 03 11:16:21 volumio sudo[29580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 11:16:21 volumio sudo[29580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "services"... May 03 11:16:21 volumio sudo[29580]: pam_unix(sudo:session): session closed for user root May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "alsa_controller"... May 03 11:16:21 volumio sudo[29584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 03 11:16:21 volumio sudo[29584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:21 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "wizard"... May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "networkfs"... May 03 11:16:21 volumio volumio[29549]: info: Starting Udev Watcher for removable devices May 03 11:16:21 volumio volumio[29549]: info: Ignoring mount for partition: boot May 03 11:16:21 volumio volumio[29549]: info: Ignoring mount for partition: volumio May 03 11:16:21 volumio volumio[29549]: info: Ignoring mount for partition: volumio_data May 03 11:16:21 volumio volumio[29549]: info: Mounting Device 94E1-235E May 03 11:16:21 volumio sudo[29592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/94E1-235E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:21 volumio sudo[29592]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:21 volumio sudo[29592]: pam_unix(sudo:session): session closed for user root May 03 11:16:21 volumio volumio[29549]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:21 volumio volumio[29549]: error: Failed to mount 94E1-235E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/94E1-235E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:21 volumio volumio[29549]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:21 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "volumio_command_line_client"... May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "upnp"... May 03 11:16:21 volumio volumio[29549]: info: [1714727781563] Starting Upmpd Daemon May 03 11:16:21 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "my_music"... May 03 11:16:21 volumio volumio[29549]: info: Loading plugin "mpd"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "upnp_browser"... May 03 11:16:22 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:22] [connect] Successful connection May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "alarm-clock"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "airplay_emulation"... May 03 11:16:22 volumio volumio[29549]: info: Starting Shairport Sync May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "last_100"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "webradio"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "i2s_dacs"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "volumiodiscovery"... May 03 11:16:22 volumio volumio[29549]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:22 volumio node[29549]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:22 volumio volumio[29549]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:22 volumio node[29549]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:22 volumio volumio[29549]: *** WARNING *** For more information see May 03 11:16:22 volumio node[29549]: *** WARNING *** For more information see May 03 11:16:22 volumio volumio[29549]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:22 volumio node[29549]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:22 volumio volumio[29549]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:22 volumio node[29549]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:22 volumio volumio[29549]: *** WARNING *** For more information see May 03 11:16:22 volumio node[29549]: *** WARNING *** For more information see May 03 11:16:22 volumio volumio[29549]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 11:16:22 volumio volumio[29549]: info: Discovery: Started advertising with name: Volumio May 03 11:16:22 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "outputs"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "albumart"... May 03 11:16:22 volumio volumio[29549]: info: Plugin example_plugin is not enabled May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "inputs"... May 03 11:16:22 volumio volumio[29549]: info: Loading plugin "updater_comm"... May 03 11:16:23 volumio volumio[29549]: info: Plugin mpdemulation is not enabled May 03 11:16:23 volumio volumio[29549]: info: Loading plugin "rest_api"... May 03 11:16:23 volumio volumio[29549]: info: Loading plugin "websocket"... May 03 11:16:23 volumio volumio[29549]: info: Loading plugin "minidlna"... May 03 11:16:23 volumio volumio[29549]: info: Applying required configuration parameters for plugin minidlna May 03 11:16:23 volumio volumio[29549]: Forking 3 albumart workers May 03 11:16:23 volumio volumio[29549]: info: Loading i18n strings for locale en May 03 11:16:23 volumio volumio[29549]: Updating browse sources language May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::initPlayerControls May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:23 volumio volumio[29549]: Express server listening on port 3000 May 03 11:16:23 volumio volumio[29549]: [Metrics] WebUI: 4s 731.06ms May 03 11:16:23 volumio volumio[29549]: info: CoreStateMachine::resetVolumioState May 03 11:16:23 volumio volumio[29549]: info: CoreStateMachine::getcurrentVolume May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::volumioRetrievevolume May 03 11:16:23 volumio volumio[29549]: info: CoreStateMachine::pushState May 03 11:16:23 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:16:23 volumio volumio[29549]: info: CoreCommandRouter::volumioPushState May 03 11:16:24 volumio volumio[29549]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 1 May 03 11:16:24 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714727782 101 May 03 11:16:24 volumio volumio[29549]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 May 03 11:16:24 volumio volumio[29549]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 3 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 11:16:24 volumio volumio[29549]: info: Reloading queue from file May 03 11:16:24 volumio volumio[29549]: info: CoreStateMachine::setRepeat null single undefined May 03 11:16:24 volumio volumio[29549]: info: CoreStateMachine::pushState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioPushState May 03 11:16:24 volumio volumio[29549]: info: CoreStateMachine::setRandom null May 03 11:16:24 volumio volumio[29549]: info: CoreStateMachine::pushState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioPushState May 03 11:16:24 volumio volumio[29549]: info: Setting Device type: Raspberry PI May 03 11:16:24 volumio volumio[29549]: info: Discovery: adding b18c929a-a525-4090-b1b7-0843470d8394 May 03 11:16:24 volumio volumio[29549]: info: Discovery: Found device Volumio May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: Starting albumart workers May 03 11:16:24 volumio volumio[29549]: Starting albumart workers May 03 11:16:24 volumio volumio[29549]: Starting albumart workers May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetVisibleSources May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 03 11:16:24 volumio volumio[29549]: info: Received Get System Info May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:16:24 volumio volumio[29549]: info: Discovery: Getting this device information May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetState May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getTrack 0 May 03 11:16:24 volumio volumio[29549]: info: Listing playlists May 03 11:16:24 volumio volumio[29549]: info: CoreCommandRouter::volumioGetQueue May 03 11:16:24 volumio volumio[29549]: info: CoreStateMachine::getQueue May 03 11:16:24 volumio volumio[29549]: info: CorePlayQueue::getQueue May 03 11:16:25 volumio sudo[29584]: pam_unix(sudo:session): session closed for user root May 03 11:16:29 volumio volumio[29549]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 03 11:16:29 volumio volumio[29549]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:29 volumio volumio[29549]: TypeError: Cannot read property 'length' of undefined May 03 11:16:29 volumio volumio[29549]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 03 11:16:29 volumio volumio[29549]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 03 11:16:29 volumio volumio[29549]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 03 11:16:29 volumio volumio[29549]: at Parser.emit (events.js:315:20) May 03 11:16:29 volumio volumio[29549]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 03 11:16:29 volumio volumio[29549]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 03 11:16:29 volumio volumio[29549]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 03 11:16:29 volumio volumio[29549]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 03 11:16:29 volumio volumio[29549]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 03 11:16:29 volumio volumio[29549]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 03 11:16:29 volumio volumio[29549]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 03 11:16:29 volumio volumio[29549]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 03 11:16:29 volumio volumio[29549]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 03 11:16:29 volumio volumio[29549]: at IncomingMessage.emit (events.js:327:22) May 03 11:16:29 volumio volumio[29549]: at endReadableNT (internal/streams/readable.js:1327:12) May 03 11:16:29 volumio volumio[29549]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 03 11:16:29 volumio volumio[29549]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:29 volumio sudo[29666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 11:15 May 03 11:16:29 volumio sudo[29666]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:29 volumio sudo[29666]: pam_unix(sudo:session): session closed for user root May 03 11:16:29 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 03 11:16:29 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 03 11:16:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 03 11:16:30 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 03 11:16:30 volumio systemd[1]: Started dynamicswap service. May 03 11:16:30 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:30 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 03 11:16:30 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. May 03 11:16:30 volumio systemd[1]: Started dynamicswap service. May 03 11:16:30 volumio systemd[1]: Stopped Volumio Backend Module. May 03 11:16:30 volumio systemd[1]: Started Volumio Backend Module. May 03 11:16:30 volumio systemd[1]: dynamicswap.service: Succeeded. May 03 11:16:32 volumio volumio[29678]: info: ------------------------------------------- May 03 11:16:32 volumio volumio[29678]: info: ----- Volumio3 ---- May 03 11:16:32 volumio volumio[29678]: info: ------------------------------------------- May 03 11:16:32 volumio volumio[29678]: info: ----- System startup ---- May 03 11:16:32 volumio volumio[29678]: info: ------------------------------------------- May 03 11:16:33 volumio volumio[29678]: info: MYVOLUMIO Environment detected May 03 11:16:33 volumio volumio[29678]: info: Plugin folders cleanup May 03 11:16:33 volumio volumio[29678]: info: Scanning into folder /volumio/app/plugins/ May 03 11:16:33 volumio volumio[29678]: info: Scanning category audio_interface May 03 11:16:33 volumio volumio[29678]: info: Scanning category miscellanea May 03 11:16:33 volumio volumio[29678]: info: Scanning category music_service May 03 11:16:33 volumio volumio[29678]: info: Scanning category plugins.json May 03 11:16:33 volumio volumio[29678]: info: Scanning category system_controller May 03 11:16:33 volumio volumio[29678]: info: Scanning category user_interface May 03 11:16:33 volumio volumio[29678]: info: Scanning into folder /data/plugins/ May 03 11:16:33 volumio volumio[29678]: info: Scanning category music_service May 03 11:16:33 volumio volumio[29678]: info: Plugin folders cleanup completed May 03 11:16:33 volumio volumio[29678]: info: ------------------------------------------- May 03 11:16:33 volumio volumio[29678]: info: ----- Core plugins startup ---- May 03 11:16:33 volumio volumio[29678]: info: ------------------------------------------- May 03 11:16:33 volumio volumio[29678]: info: Loading plugins from folder /volumio/app/plugins/ May 03 11:16:33 volumio volumio[29678]: info: Adding plugin upnp to MyMusic Plugins May 03 11:16:33 volumio volumio[29678]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 11:16:33 volumio volumio[29678]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 11:16:33 volumio volumio[29678]: info: Loading plugins from folder /data/plugins/ May 03 11:16:33 volumio volumio[29678]: info: Loading plugin "system"... May 03 11:16:33 volumio volumio[29678]: info: Loading plugin "appearance"... May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "network"... May 03 11:16:34 volumio volumio[29678]: info: Refreshing Cached IP Addresses May 03 11:16:34 volumio sudo[29704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 11:16:34 volumio sudo[29704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:34 volumio sudo[29704]: pam_unix(sudo:session): session closed for user root May 03 11:16:34 volumio sudo[29706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 11:16:34 volumio sudo[29706]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "services"... May 03 11:16:34 volumio sudo[29706]: pam_unix(sudo:session): session closed for user root May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "alsa_controller"... May 03 11:16:34 volumio sudo[29709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 03 11:16:34 volumio sudo[29709]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:34 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "wizard"... May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "networkfs"... May 03 11:16:34 volumio volumio[29678]: info: Starting Udev Watcher for removable devices May 03 11:16:34 volumio volumio[29678]: info: Ignoring mount for partition: boot May 03 11:16:34 volumio volumio[29678]: info: Ignoring mount for partition: volumio May 03 11:16:34 volumio volumio[29678]: info: Ignoring mount for partition: volumio_data May 03 11:16:34 volumio volumio[29678]: info: Mounting Device 94E1-235E May 03 11:16:34 volumio sudo[29718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/94E1-235E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:34 volumio sudo[29718]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:34 volumio sudo[29718]: pam_unix(sudo:session): session closed for user root May 03 11:16:34 volumio volumio[29678]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:34 volumio volumio[29678]: error: Failed to mount 94E1-235E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/94E1-235E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:16:34 volumio volumio[29678]: mount: /media/94E1-235E: /dev/sda1 already mounted on /media/94E1-235E. May 03 11:16:34 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "volumio_command_line_client"... May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "upnp"... May 03 11:16:34 volumio volumio[29678]: info: [1714727794265] Starting Upmpd Daemon May 03 11:16:34 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "my_music"... May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "mpd"... May 03 11:16:34 volumio volumio[29678]: info: Loading plugin "upnp_browser"... May 03 11:16:34 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:34] [connect] Successful connection May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "alarm-clock"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "airplay_emulation"... May 03 11:16:35 volumio volumio[29678]: info: Starting Shairport Sync May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "last_100"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "webradio"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "i2s_dacs"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "volumiodiscovery"... May 03 11:16:35 volumio volumio[29678]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:35 volumio volumio[29678]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:35 volumio volumio[29678]: *** WARNING *** For more information see May 03 11:16:35 volumio node[29678]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 11:16:35 volumio volumio[29678]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:35 volumio volumio[29678]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:35 volumio volumio[29678]: *** WARNING *** For more information see May 03 11:16:35 volumio node[29678]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:35 volumio node[29678]: *** WARNING *** For more information see May 03 11:16:35 volumio node[29678]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 11:16:35 volumio node[29678]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 11:16:35 volumio node[29678]: *** WARNING *** For more information see May 03 11:16:35 volumio volumio[29678]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 11:16:35 volumio volumio[29678]: info: Discovery: Started advertising with name: Volumio May 03 11:16:35 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "outputs"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "albumart"... May 03 11:16:35 volumio volumio[29678]: info: Plugin example_plugin is not enabled May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "inputs"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "updater_comm"... May 03 11:16:35 volumio volumio[29678]: info: Plugin mpdemulation is not enabled May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "rest_api"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "websocket"... May 03 11:16:35 volumio volumio[29678]: info: Loading plugin "minidlna"... May 03 11:16:36 volumio volumio[29678]: info: Applying required configuration parameters for plugin minidlna May 03 11:16:36 volumio volumio[29678]: Forking 3 albumart workers May 03 11:16:36 volumio volumio[29678]: info: Loading i18n strings for locale en May 03 11:16:36 volumio volumio[29678]: Updating browse sources language May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:36 volumio dnsmasq-dhcp[754]: no address range available for DHCP request via wlan0 May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::initPlayerControls May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:16:36 volumio volumio[29678]: Express server listening on port 3000 May 03 11:16:36 volumio volumio[29678]: [Metrics] WebUI: 4s 818.77ms May 03 11:16:36 volumio volumio[29678]: info: CoreStateMachine::resetVolumioState May 03 11:16:36 volumio volumio[29678]: info: CoreStateMachine::getcurrentVolume May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::volumioRetrievevolume May 03 11:16:36 volumio volumio[29678]: info: CoreStateMachine::pushState May 03 11:16:36 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:16:36 volumio volumio[29678]: info: CoreCommandRouter::volumioPushState May 03 11:16:37 volumio volumio[29678]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 1 May 03 11:16:37 volumio volumio-remote-updater[1388]: [2024-05-03 09:16:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714727794 101 May 03 11:16:37 volumio volumio[29678]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 May 03 11:16:37 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 11:16:37 volumio volumio[29678]: info: Reloading queue from file May 03 11:16:37 volumio volumio[29678]: info: CoreStateMachine::setRepeat null single undefined May 03 11:16:37 volumio volumio[29678]: info: CoreStateMachine::pushState May 03 11:16:37 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:37 volumio volumio[29678]: info: CoreCommandRouter::volumioPushState May 03 11:16:37 volumio volumio[29678]: info: CoreStateMachine::setRandom null May 03 11:16:37 volumio volumio[29678]: info: CoreStateMachine::pushState May 03 11:16:37 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:37 volumio volumio[29678]: info: CoreCommandRouter::volumioPushState May 03 11:16:37 volumio volumio[29678]: info: Setting Device type: Raspberry PI May 03 11:16:37 volumio volumio[29678]: Starting albumart workers May 03 11:16:37 volumio volumio[29678]: info: Discovery: adding b18c929a-a525-4090-b1b7-0843470d8394 May 03 11:16:37 volumio volumio[29678]: info: Discovery: Found device Volumio May 03 11:16:37 volumio volumio[29678]: info: CoreCommandRouter::volumioGetState May 03 11:16:37 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:37 volumio volumio[29678]: Starting albumart workers May 03 11:16:37 volumio volumio[29678]: Starting albumart workers May 03 11:16:37 volumio sudo[29709]: pam_unix(sudo:session): session closed for user root May 03 11:16:38 volumio volumio[29678]: verbose: New Socket.io Connection to 10.5.5.179 from 10.5.5.117 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.6 Safari/605.1.15 Total Clients: 3 May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetVisibleSources May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetState May 03 11:16:38 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetState May 03 11:16:38 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 03 11:16:38 volumio volumio[29678]: info: Received Get System Info May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:16:38 volumio volumio[29678]: info: Discovery: Getting this device information May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetState May 03 11:16:38 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetState May 03 11:16:38 volumio volumio[29678]: info: CorePlayQueue::getTrack 0 May 03 11:16:38 volumio volumio[29678]: info: Listing playlists May 03 11:16:38 volumio volumio[29678]: info: CoreCommandRouter::volumioGetQueue May 03 11:16:38 volumio volumio[29678]: info: CoreStateMachine::getQueue May 03 11:16:38 volumio volumio[29678]: info: CorePlayQueue::getQueue May 03 11:16:44 volumio sudo[29840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 11:16:44 volumio sudo[29840]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:44 volumio sudo[29840]: pam_unix(sudo:session): session closed for user root May 03 11:16:44 volumio sudo[29842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 11:16:44 volumio sudo[29842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:44 volumio sudo[29842]: pam_unix(sudo:session): session closed for user root May 03 11:16:44 volumio sudo[29846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 03 11:16:44 volumio sudo[29846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:16:44 volumio sudo[29846]: pam_unix(sudo:session): session closed for user root May 03 11:16:44 volumio volumio[29678]: info: Upmpdcli Daemon Started May 03 11:16:44 volumio kernel: hwmon hwmon1: Voltage normalised May 03 11:16:48 volumio volumio[29678]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 11:16:48 volumio volumio[29678]: info: CURURI: albums:// May 03 11:16:48 volumio volumio[29678]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:48 volumio volumio[29678]: TypeError: Cannot read property 'sendCommand' of undefined May 03 11:16:48 volumio volumio[29678]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 May 03 11:16:48 volumio volumio[29678]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 03 11:16:48 volumio volumio[29678]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:16:48 volumio sudo[29858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 11:15 May 03 11:16:48 volumio sudo[29858]: 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"