-- Logs begin at Sun 2024-08-18 11:33:02 CEST, end at Sun 2024-08-18 11:35:21 CEST. -- Aug 18 11:34:00 musique-dd volumio[979]: ..........................................++++ Aug 18 11:34:00 musique-dd volumio[979]: e is 65537 (0x010001) Aug 18 11:34:00 musique-dd volumio[979]: writing RSA key Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 18 11:34:02 musique-dd volumio[656]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 18 11:34:04 musique-dd volumio[656]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 18 11:34:04 musique-dd volumio[656]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 18 11:34:04 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:04 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:04 musique-dd volumio[656]: info: Starting MyVolumio Remote Streaming Endpoints Aug 18 11:34:04 musique-dd volumio[656]: info: MyVolumio login type: Token Aug 18 11:34:04 musique-dd volumio[656]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 18 11:34:04 musique-dd volumio[656]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 18 11:34:05 musique-dd volumiologrotate[439]: ls: cannot access '/var/log/samba/log.wb-MUSIQUE': No such file or directory Aug 18 11:34:05 musique-dd volumiologrotate[439]: ls: cannot access 'DD': No such file or directory Aug 18 11:34:05 musique-dd volumio[656]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 18 11:34:05 musique-dd volumio[656]: info: MyVolumio token set successfully Aug 18 11:34:05 musique-dd volumio[656]: info: MYVOLUMIO: Adding device Aug 18 11:34:05 musique-dd volumio[656]: info: MYVOLUMIO: Evaluating Server Aug 18 11:34:06 musique-dd volumio[656]: info: MyVolumio status changed Aug 18 11:34:06 musique-dd volumio[656]: info: Streaming services startup Aug 18 11:34:06 musique-dd volumio[656]: info: Starting Streaming Daemon Aug 18 11:34:06 musique-dd volumio[656]: info: Removing browser output: myVolumio user plan is not superstar Aug 18 11:34:06 musique-dd volumio[656]: info: Removing audio output: Aug 18 11:34:06 musique-dd volumio[656]: info: Stoppping Tunnel 1 Aug 18 11:34:06 musique-dd sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 18 11:34:06 musique-dd sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:34:06 musique-dd sudo[1187]: pam_unix(sudo:session): session closed for user root Aug 18 11:34:06 musique-dd sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 18 11:34:06 musique-dd sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 11:34:06 musique-dd sudo[1190]: pam_unix(sudo:session): session closed for user root Aug 18 11:34:06 musique-dd volumio[656]: error: Cannot start Volumio Streaming Daemon Aug 18 11:34:06 musique-dd volumio[656]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 18 11:34:06 musique-dd volumio[656]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 18 11:34:06 musique-dd volumio[656]: info: Remote SSH Stopped Aug 18 11:34:06 musique-dd volumio[656]: info: Setting Geolocation for MyVolumio to eu6 Aug 18 11:34:06 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:06 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:06 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:07 musique-dd volumio[656]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:34:07 musique-dd volumio[656]: info: Updating MyVolumio device info Aug 18 11:34:07 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:07 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:07 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:08 musique-dd volumio[656]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:34:11 musique-dd volumio[656]: info: MYVOLUMIO: Adding device Aug 18 11:34:11 musique-dd volumio[656]: info: MYVOLUMIO: Evaluating Server Aug 18 11:34:12 musique-dd volumio[656]: info: Setting Geolocation for MyVolumio to eu6 Aug 18 11:34:12 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:12 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:12 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:12 musique-dd volumio[656]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 18 11:34:13 musique-dd volumio[656]: info: Updating MyVolumio device info Aug 18 11:34:13 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:13 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:13 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:13 musique-dd volumio[656]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 18 11:34:14 musique-dd volumio[656]: error: MyVolumio Plugin failed to start in a timely fashion Aug 18 11:34:14 musique-dd volumio[656]: info: BOOT COMPLETED Aug 18 11:34:14 musique-dd volumio[656]: [Metrics] CommandRouter: 59s 466.37ms Aug 18 11:34:14 musique-dd volumio[656]: info: CoreCommandRouter::volumiosetStartupVolume Aug 18 11:34:14 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:34:14 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:14 musique-dd volumio[656]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:34:14 musique-dd volumio[656]: info: CoreCommandRouter::Close All Modals sent Aug 18 11:34:15 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 18 11:34:15 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 11:34:15 musique-dd volumio[656]: xcb_connection_has_error() returned true Aug 18 11:34:15 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 18 11:34:17 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:34:17 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 11:34:17 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 11:34:17 musique-dd volumio-remote-updater[436]: No test mode Aug 18 11:34:17 musique-dd volumio-remote-updater[436]: No alpha test mode Aug 18 11:34:17 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 18 11:34:17 musique-dd volumio[656]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 18 11:34:17 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 18 11:34:17 musique-dd systemd[1]: systemd-hostnamed.service: Succeeded. Aug 18 11:34:19 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 11:34:19 musique-dd volumio[656]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Aug 18 11:34:19 musique-dd volumio[656]: info: CoreCommandRouter::volumioGetState Aug 18 11:34:19 musique-dd volumio[656]: info: CorePlayQueue::getTrack 0 Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::volumioGetBrowseSources Aug 18 11:34:24 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 11:35:05 musique-dd volumiologrotate[439]: ls: cannot access '/var/log/samba/log.wb-MUSIQUE': No such file or directory Aug 18 11:35:05 musique-dd volumiologrotate[439]: ls: cannot access 'DD': No such file or directory Aug 18 11:35:08 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:35:08 musique-dd volumio[656]: info: Preload queue cleared Aug 18 11:35:10 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:35:10 musique-dd volumio[656]: info: Preload queue cleared Aug 18 11:35:13 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:35:13 musique-dd volumio[656]: info: Preload queue cleared Aug 18 11:35:20 musique-dd volumio[656]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 18 11:35:20 musique-dd volumio[656]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:35:20 musique-dd volumio[656]: TypeError: Cannot read property 'length' of undefined Aug 18 11:35:20 musique-dd volumio[656]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 18 11:35:20 musique-dd volumio[656]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 18 11:35:20 musique-dd volumio[656]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 18 11:35:20 musique-dd volumio[656]: at Parser.emit (events.js:400:28) Aug 18 11:35:20 musique-dd volumio[656]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 18 11:35:20 musique-dd volumio[656]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 18 11:35:20 musique-dd volumio[656]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 18 11:35:20 musique-dd volumio[656]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 18 11:35:20 musique-dd volumio[656]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 18 11:35:20 musique-dd volumio[656]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 18 11:35:20 musique-dd volumio[656]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 18 11:35:20 musique-dd volumio[656]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 18 11:35:20 musique-dd volumio[656]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 18 11:35:20 musique-dd volumio[656]: at IncomingMessage.emit (events.js:412:35) Aug 18 11:35:20 musique-dd volumio[656]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 18 11:35:20 musique-dd volumio[656]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 18 11:35:20 musique-dd volumio[656]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 11:35:21 musique-dd sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 11:34 Aug 18 11:35:21 musique-dd sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"