-- Logs begin at Mon 2024-04-22 13:08:07 UTC, end at Fri 2024-04-26 15:59:24 UTC. -- Apr 26 15:58:00 volumio1 sudo[1056]: pam_unix(sudo:session): session closed for user root Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 15:58:00 volumio1 volumio[914]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 15:58:00 volumio1 volumio[914]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 15:58:00 volumio1 volumio[914]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 15:58:00 volumio1 volumio[914]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 15:58:12 volumio1 volumio-remote-updater[503]: [2024-04-26 15:58:12] [connect] Successful connection Apr 26 15:58:13 volumio1 volumio[914]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.523362, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.603570, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.616359, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.617672, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.730019, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:16 volumio1 smbd[1066]: [2024/04/26 15:58:16.772693, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:16 volumio1 smbd[1066]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:17 volumio1 smbd[1067]: [2024/04/26 15:58:17.843274, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:17 volumio1 smbd[1067]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:17 volumio1 smbd[1067]: [2024/04/26 15:58:17.852638, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:17 volumio1 smbd[1067]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:18 volumio1 smbd[1067]: [2024/04/26 15:58:18.027557, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:18 volumio1 smbd[1067]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:18 volumio1 smbd[1067]: [2024/04/26 15:58:18.057057, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:18 volumio1 smbd[1067]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:18 volumio1 smbd[1067]: [2024/04/26 15:58:18.079207, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:18 volumio1 smbd[1067]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:19 volumio1 smbd[1068]: [2024/04/26 15:58:19.146374, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:19 volumio1 smbd[1068]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:19 volumio1 smbd[1068]: [2024/04/26 15:58:19.162579, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:19 volumio1 smbd[1068]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:19 volumio1 smbd[1068]: [2024/04/26 15:58:19.299080, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 26 15:58:19 volumio1 smbd[1068]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 26 15:58:23 volumio1 volumio[914]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 15:58:23 volumio1 volumio[914]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 15:58:23 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 15:58:23 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 15:58:23 volumio1 volumio[914]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 15:58:23 volumio1 volumio[914]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 15:58:23 volumio1 volumio[914]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 15:58:23 volumio1 volumio[914]: info: Streaming services startup Apr 26 15:58:23 volumio1 volumio[914]: info: Starting Streaming Daemon Apr 26 15:58:23 volumio1 volumio[914]: info: MyVolumio not started Apr 26 15:58:23 volumio1 volumio[914]: info: Initializing device activation check Apr 26 15:58:23 volumio1 sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 15:58:23 volumio1 sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:58:24 volumio1 systemd[1]: Stopping Volumio Streaming Daemon... Apr 26 15:58:24 volumio1 volumio[914]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 15:58:24 volumio1 systemd[1]: Starting Volumio Streaming Daemon... Apr 26 15:58:24 volumio1 systemd[1]: Started Volumio Streaming Daemon. Apr 26 15:58:24 volumio1 sudo[1070]: pam_unix(sudo:session): session closed for user root Apr 26 15:58:24 volumio1 volumio-streaming-daemon[1076]: ############################ Apr 26 15:58:24 volumio1 volumio-streaming-daemon[1076]: # Volumio Streaming Daemon # Apr 26 15:58:24 volumio1 volumio-streaming-daemon[1076]: # Running on port 7777 # Apr 26 15:58:24 volumio1 volumio-streaming-daemon[1076]: ############################ Apr 26 15:58:24 volumio1 volumio[914]: info: Shairport-Sync Started Apr 26 15:58:24 volumio1 volumio[914]: Error adding Membership: Error: addMembership EINVAL Apr 26 15:58:24 volumio1 volumio[914]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=5e5cecd0379c44b5b42f9783849c5f11" http://updates.volumio.org:7070/downloader-v1/track-device Apr 26 15:58:24 volumio1 volumio[914]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 26 15:58:24 volumio1 volumio[914]: Dload Upload Total Spent Left Speed Apr 26 15:58:24 volumio1 volumio[914]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 26 15:58:24 volumio1 volumio[914]: retrying in 5 seconds, trial 0 Apr 26 15:58:24 volumio1 volumio[914]: Volumio Calling Home Apr 26 15:58:25 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 15:58:25 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 15:58:25 volumio1 volumio[914]: Discovery: adding 6379520e-048b-4162-8297-d80391a171a3 Apr 26 15:58:25 volumio1 volumio[914]: info: mDNS: Found device Volumio2 Apr 26 15:58:25 volumio1 sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 15:58:26 volumio1 sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:58:26 volumio1 sudo[1089]: pam_unix(sudo:session): session closed for user root Apr 26 15:58:26 volumio1 volumio[914]: info: Upmpdcli Daemon Started Apr 26 15:58:26 volumio1 volumio[914]: info: Shairport-Sync Started Apr 26 15:58:27 volumio1 volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 26 15:58:27 volumio1 volumio[914]: info: CoreCommandRouter::volumioGetState Apr 26 15:58:27 volumio1 volumio[914]: info: CoreStateMachine::getState Apr 26 15:58:27 volumio1 volumio[914]: info: CorePlayQueue::getTrack 0 Apr 26 15:58:27 volumio1 volumio-remote-updater[503]: [2024-04-26 15:58:27] [connect] Successful connection Apr 26 15:58:27 volumio1 sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 15:58:28 volumio1 sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:58:28 volumio1 sudo[1098]: pam_unix(sudo:session): session closed for user root Apr 26 15:58:28 volumio1 volumio-remote-updater[503]: [2024-04-26 15:58:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1714147107 101 Apr 26 15:58:30 volumio1 volumio[914]: info: Fetching Streaming Services browse cache Apr 26 15:58:31 volumio1 volumio[914]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Apr 26 15:58:31 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 15:58:31 volumio1 volumio[914]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Apr 26 15:58:31 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 15:58:32 volumio1 volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 26 15:58:36 volumio1 volumio[914]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 15:58:36 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 15:58:36 volumio1 volumio[914]: info: CoreCommandRouter::volumioGetState Apr 26 15:58:36 volumio1 volumio[914]: info: CoreStateMachine::getState Apr 26 15:58:36 volumio1 volumio[914]: info: CorePlayQueue::getTrack 0 Apr 26 15:58:36 volumio1 volumio[914]: info: CoreCommandRouter::volumioGetQueue Apr 26 15:58:36 volumio1 volumio[914]: info: CoreStateMachine::getQueue Apr 26 15:58:36 volumio1 volumio[914]: info: CorePlayQueue::getQueue Apr 26 15:58:36 volumio1 volumio[914]: info: Listing playlists Apr 26 15:58:36 volumio1 sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 15:58:36 volumio1 sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:58:36 volumio1 sudo[1109]: pam_unix(sudo:session): session closed for user root Apr 26 15:58:37 volumio1 volumio[914]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=5e5cecd0379c44b5b42f9783849c5f11" http://updates.volumio.org:7070/downloader-v1/track-device Apr 26 15:58:37 volumio1 volumio[914]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 26 15:58:37 volumio1 volumio[914]: Dload Upload Total Spent Left Speed Apr 26 15:58:37 volumio1 volumio[914]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 26 15:58:37 volumio1 volumio[914]: retrying in 5 seconds, trial 1 Apr 26 15:58:37 volumio1 volumio[914]: Volumio Calling Home Apr 26 15:58:47 volumio1 volumio[914]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.703&uuid=5e5cecd0379c44b5b42f9783849c5f11" http://updates.volumio.org:7070/downloader-v1/track-device Apr 26 15:58:47 volumio1 volumio[914]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 26 15:58:47 volumio1 volumio[914]: Dload Upload Total Spent Left Speed Apr 26 15:58:47 volumio1 volumio[914]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 26 15:58:47 volumio1 volumio[914]: retrying in 5 seconds, trial 2 Apr 26 15:58:47 volumio1 volumio[914]: Volumio Calling Home Apr 26 15:59:05 volumio1 volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 15:59:16 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 15:59:17 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 26 15:59:18 volumio1 volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 26 15:59:18 volumio1 volumio[914]: info: ControllerMpd::sendMpdCommand rescan Apr 26 15:59:18 volumio1 volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 15:59:18 volumio1 volumio[914]: TypeError: Cannot read property 'then' of undefined Apr 26 15:59:18 volumio1 volumio[914]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:347:10) Apr 26 15:59:18 volumio1 volumio[914]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1907:17) Apr 26 15:59:18 volumio1 volumio[914]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1078:29) Apr 26 15:59:18 volumio1 volumio[914]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:986:24) Apr 26 15:59:18 volumio1 volumio[914]: at emitTwo (events.js:126:13) Apr 26 15:59:18 volumio1 volumio[914]: at Socket.emit (events.js:214:7) Apr 26 15:59:18 volumio1 volumio[914]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 26 15:59:18 volumio1 volumio[914]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 26 15:59:18 volumio1 volumio[914]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 26 15:59:18 volumio1 volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 15:59:24 volumio1 sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 15:58 Apr 26 15:59:24 volumio1 sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4"