-- Logs begin at Thu 2019-02-14 13:12:00 MSK, end at Tue 2024-07-23 20:48:24 MSK. -- Jul 23 20:47:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 23 20:47:00 volumio volumio[1072]: info: Received Get System Info Jul 23 20:47:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 20:47:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 20:47:00 volumio volumio[1072]: info: Discovery: Getting this device information Jul 23 20:47:00 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 23 20:47:00 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:00 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 20:47:07 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 23 20:47:07 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 23 20:47:08 volumio volumio[1256]: ..........................................................................................................................................................................................................................................++++ Jul 23 20:47:08 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: info: Retrieving Cloud Streaming UI Jul 23 20:47:11 volumio volumio[1072]: info: Getting Tidal Cloud Configuration Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: info: Getting Qobuz Cloud Configuration Jul 23 20:47:11 volumio volumio[1072]: info: Asking plugin for UI Config Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: info: Getting Spotify Cloud Configuration Jul 23 20:47:11 volumio volumio[1072]: info: Asking plugin for UI Config Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: info: Saving Spotify Acccount Jul 23 20:47:11 volumio volumio[1072]: info: Got Tidal Cloud Configuration Jul 23 20:47:11 volumio volumio[1072]: info: Got it Jul 23 20:47:11 volumio volumio[1072]: info: Got it Jul 23 20:47:11 volumio volumio[1072]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 20:47:11 volumio volumio[1072]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:11 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 23 20:47:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 23 20:47:15 volumio volumio[1072]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 23 20:47:16 volumio volumio[1072]: info: Received Get System Version Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 23 20:47:16 volumio volumio[1072]: info: Received Get System Info Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 23 20:47:16 volumio volumio[1072]: info: Discovery: Getting this device information Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 23 20:47:16 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:16 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 23 20:47:19 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:19 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 23 20:47:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand rescan Jul 23 20:47:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:19 volumio volumio[1072]: info: Jul 23 20:47:19 volumio volumio[1072]: ---------------------------- MPD announces state update: update Jul 23 20:47:19 volumio volumio[1072]: info: sendMpdCommand rescan took 26 milliseconds Jul 23 20:47:19 volumio volumio[1072]: info: ControllerMpd::getState Jul 23 20:47:19 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:19 volumio volumio[1072]: info: sendMpdCommand status took 18 milliseconds Jul 23 20:47:19 volumio volumio[1072]: info: sendMpdCommand status took 6 milliseconds Jul 23 20:47:19 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreStateMachine::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jul 23 20:47:20 volumio volumio[1072]: info: ------------------------------ 306ms Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: info: Jul 23 20:47:20 volumio volumio[1072]: ---------------------------- MPD announces state update: update Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::getState Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: info: Jul 23 20:47:20 volumio volumio[1072]: ---------------------------- MPD announces state update: update Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::getState Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: info: Jul 23 20:47:20 volumio volumio[1072]: ---------------------------- MPD announces state update: update Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::getState Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 38 milliseconds Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 34 milliseconds Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 31 milliseconds Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 26 milliseconds Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 24 milliseconds Jul 23 20:47:20 volumio volumio[1072]: info: sendMpdCommand status took 20 milliseconds Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 23 20:47:20 volumio volumio[1072]: verbose: ControllerMpd::parseState Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreStateMachine::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreStateMachine::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jul 23 20:47:20 volumio volumio[1072]: info: ControllerMpd::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::servicePushState Jul 23 20:47:20 volumio volumio[1072]: info: CoreStateMachine::pushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 23 20:47:20 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:47:20 volumio volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jul 23 20:47:20 volumio volumio[1072]: info: ------------------------------ 372ms Jul 23 20:47:20 volumio volumio[1072]: info: ------------------------------ 364ms Jul 23 20:47:20 volumio volumio[1072]: info: ------------------------------ 367ms Jul 23 20:47:23 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:24 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 23 20:47:24 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand update Jul 23 20:47:24 volumio volumio[1072]: info: sendMpdCommand update took 8 milliseconds Jul 23 20:47:24 volumio volumio[1072]: error: error Jul 23 20:47:25 volumio volumio[1256]: ..........................................................++++ Jul 23 20:47:25 volumio volumio[1256]: e is 65537 (0x010001) Jul 23 20:47:25 volumio volumio[1256]: writing RSA key Jul 23 20:47:27 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:28 volumio volumio[1072]: error: error Jul 23 20:47:31 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 23 20:47:31 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 23 20:47:31 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:35 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:39 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:43 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:47 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:47 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 23 20:47:47 volumio volumio[1072]: info: Adding a new share Jul 23 20:47:47 volumio volumio[1072]: info: Share alias is blank Jul 23 20:47:51 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:54 volumio volumio[1072]: info: Error browsing device DiskStation for shares : Error: Command failed: echo volumio | smbclient -L DiskStation.local. Jul 23 20:47:54 volumio volumio[1072]: Unable to initialize messaging context Jul 23 20:47:55 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:47:59 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 23 20:47:59 volumio volumio[1072]: verbose: ControllerMpd::sendMpdCommand update Jul 23 20:47:59 volumio volumio[1072]: info: sendMpdCommand update took 10 milliseconds Jul 23 20:47:59 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:48:03 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 20:48:06 volumio volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 23 20:48:06 volumio volumio[1072]: info: CorePlayQueue::getTrack 0 Jul 23 20:48:08 volumio volumio[1072]: info: CoreCommandRouter::volumioGetQueue Jul 23 20:48:08 volumio volumio[1072]: info: CoreStateMachine::getQueue Jul 23 20:48:08 volumio volumio[1072]: info: CorePlayQueue::getQueue Jul 23 20:48:13 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 23 20:48:13 volumio volumio[1072]: info: Preload queue cleared Jul 23 20:48:15 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 23 20:48:17 volumio volumio[1072]: info: Preload queue cleared Jul 23 20:48:18 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 23 20:48:19 volumio volumio[1072]: error: error Jul 23 20:48:19 volumio volumio[1072]: info: Preload queue cleared Jul 23 20:48:21 volumio volumio[1072]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 23 20:48:22 volumio volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 20:48:22 volumio volumio[1072]: TypeError: Cannot read property 'length' of undefined Jul 23 20:48:22 volumio volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 23 20:48:22 volumio volumio[1072]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 23 20:48:22 volumio volumio[1072]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 23 20:48:22 volumio volumio[1072]: at Parser.emit (events.js:315:20) Jul 23 20:48:22 volumio volumio[1072]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 23 20:48:22 volumio volumio[1072]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 23 20:48:22 volumio volumio[1072]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 23 20:48:22 volumio volumio[1072]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 23 20:48:22 volumio volumio[1072]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 23 20:48:22 volumio volumio[1072]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 23 20:48:22 volumio volumio[1072]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 23 20:48:22 volumio volumio[1072]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 23 20:48:22 volumio volumio[1072]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 23 20:48:22 volumio volumio[1072]: at IncomingMessage.emit (events.js:327:22) Jul 23 20:48:22 volumio volumio[1072]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 23 20:48:22 volumio volumio[1072]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 23 20:48:22 volumio volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 20:48:24 volumio sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 20:47 Jul 23 20:48:24 volumio sudo[2149]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"