-- Logs begin at Fri 2021-09-03 11:41:24 UTC, end at Sun 2024-08-25 12:16:28 UTC. -- Aug 25 12:15:03 volumio volumio[1814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:07 volumio volumio[1814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:08 volumio volumio[1814]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 12:15:08 volumio volumio[1814]: verbose: ControllerMpd::sendMpdCommand update Aug 25 12:15:08 volumio volumio[1814]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:15:08 volumio volumio[1814]: TypeError: Cannot read property 'then' of undefined Aug 25 12:15:08 volumio volumio[1814]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Aug 25 12:15:08 volumio volumio[1814]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1838:15) Aug 25 12:15:08 volumio volumio[1814]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Aug 25 12:15:08 volumio volumio[1814]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:911:26) Aug 25 12:15:08 volumio volumio[1814]: at emitTwo (events.js:126:13) Aug 25 12:15:08 volumio volumio[1814]: at Socket.emit (events.js:214:7) Aug 25 12:15:08 volumio volumio[1814]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 25 12:15:08 volumio volumio[1814]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 25 12:15:08 volumio volumio[1814]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 25 12:15:08 volumio volumio[1814]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:15:09 volumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 12:14 Aug 25 12:15:09 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:09 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:10 volumio volumio-remote-updater[550]: [2024-08-25 12:15:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 25 12:15:10 volumio volumio-remote-updater[550]: [2024-08-25 12:15:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 25 12:15:10 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Aug 25 12:15:10 volumio systemd[1]: Unit volumio.service entered failed state. Aug 25 12:15:10 volumio systemd[1]: Starting dynamicswap service... Aug 25 12:15:10 volumio systemd[1]: Started dynamicswap service. Aug 25 12:15:10 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Aug 25 12:15:10 volumio systemd[1]: Starting dynamicswap service... Aug 25 12:15:10 volumio systemd[1]: Started dynamicswap service. Aug 25 12:15:10 volumio systemd[1]: Stopping Volumio Backend Module... Aug 25 12:15:10 volumio systemd[1]: Starting Volumio Backend Module... Aug 25 12:15:10 volumio systemd[1]: Started Volumio Backend Module. Aug 25 12:15:12 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:12 volumio volumio[2158]: info: ----- Volumio2 ---- Aug 25 12:15:12 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:12 volumio volumio[2158]: info: ----- System startup ---- Aug 25 12:15:12 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:13 volumio volumio[2158]: info: MYVOLUMIO Environment detected Aug 25 12:15:13 volumio volumio[2158]: info: Plugin folders cleanup Aug 25 12:15:13 volumio volumio[2158]: info: Scanning into folder /volumio/app/plugins/ Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category audio_interface Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category miscellanea Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category music_service Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category plugins.json Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category system_controller Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category user_interface Aug 25 12:15:13 volumio volumio[2158]: info: Scanning into folder /data/plugins/ Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category audio_interface Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category music_service Aug 25 12:15:13 volumio volumio[2158]: info: Scanning category system_controller Aug 25 12:15:13 volumio volumio[2158]: info: Plugin folders cleanup completed Aug 25 12:15:13 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:13 volumio volumio[2158]: info: ----- Core plugins startup ---- Aug 25 12:15:13 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:13 volumio volumio[2158]: info: Loading plugins from folder /volumio/app/plugins/ Aug 25 12:15:13 volumio volumio[2158]: info: Adding plugin upnp to MyMusic Plugins Aug 25 12:15:13 volumio volumio[2158]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 25 12:15:13 volumio volumio[2158]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 25 12:15:13 volumio volumio[2158]: info: Loading plugins from folder /data/plugins/ Aug 25 12:15:13 volumio volumio[2158]: info: Loading plugin "system"... Aug 25 12:15:13 volumio volumio[2158]: info: Plugin gpio_control is not enabled Aug 25 12:15:13 volumio volumio[2158]: info: Loading plugin "appearance"... Aug 25 12:15:15 volumio volumio-remote-updater[550]: [2024-08-25 12:15:15] [connect] Successful connection Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "network"... Aug 25 12:15:15 volumio volumio[2158]: info: Refreshing Cached IP Addresses Aug 25 12:15:15 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "services"... Aug 25 12:15:15 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "alsa_controller"... Aug 25 12:15:15 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:15 volumio sudo[2187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:15:15 volumio sudo[2187]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:15 volumio sudo[2187]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "wizard"... Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "volumio_command_line_client"... Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "upnp"... Aug 25 12:15:15 volumio volumio[2158]: info: [1724588115751] Starting Upmpd Daemon Aug 25 12:15:15 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "my_music"... Aug 25 12:15:15 volumio volumio[2158]: info: Loading plugin "mpd"... Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "upnp_browser"... Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "networkfs"... Aug 25 12:15:16 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "alarm-clock"... Aug 25 12:15:16 volumio sudo[2209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:15:16 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:16 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "airplay_emulation"... Aug 25 12:15:16 volumio volumio[2158]: info: Starting Shairport Sync Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "last_100"... Aug 25 12:15:16 volumio volumio[2158]: info: Loading plugin "webradio"... Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "i2s_dacs"... Aug 25 12:15:17 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "volumiodiscovery"... Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 12:15:17 volumio node[2158]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 12:15:17 volumio node[2158]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:15:17 volumio node[2158]: *** WARNING *** For more information see Aug 25 12:15:17 volumio node[2158]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 12:15:17 volumio node[2158]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:15:17 volumio node[2158]: *** WARNING *** For more information see Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** For more information see Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:15:17 volumio volumio[2158]: *** WARNING *** For more information see Aug 25 12:15:17 volumio volumio[2158]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 25 12:15:17 volumio volumio[2158]: Discovery: StartAdv! undefined Aug 25 12:15:17 volumio volumio[2158]: Discovery: Started advertising... Volumio-2 - undefined Aug 25 12:15:17 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:15:17 volumio volumio[2158]: info: Plugin es9038q2m is not enabled Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "outputs"... Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "albumart"... Aug 25 12:15:17 volumio volumio[2158]: info: Plugin example_plugin is not enabled Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "inputs"... Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "updater_comm"... Aug 25 12:15:17 volumio volumio[2158]: info: Plugin mpdemulation is not enabled Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "rest_api"... Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "websocket"... Aug 25 12:15:17 volumio volumio[2158]: info: Loading plugin "podcast"... Aug 25 12:15:17 volumio volumio[2158]: Forking 3 albumart workers Aug 25 12:15:18 volumio volumio[2158]: Starting albumart workers Aug 25 12:15:18 volumio volumio[2158]: Starting albumart workers Aug 25 12:15:18 volumio volumio[2158]: Starting albumart workers Aug 25 12:15:19 volumio volumio[2158]: info: ControllerPodcast::constructor Aug 25 12:15:20 volumio volumio[2158]: info: Loading plugin "spop"... Aug 25 12:15:20 volumio volumio[2158]: info: Loading plugin "volspotconnect2"... Aug 25 12:15:21 volumio volumio[2158]: info: ___________ START PLUGINS ___________ Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:15:21 volumio volumio[2158]: info: [1724588121285] CoreMusicLibrary::Adding element Media Servers Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:21 volumio volumio[2158]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:15:21 volumio volumio[2158]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:15:21 volumio volumio[2158]: info: [1724588121406] CoreMusicLibrary::Adding element Last_100 Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:15:21 volumio volumio[2158]: info: [1724588121410] CoreMusicLibrary::Adding element Webradio Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:15:21 volumio volumio[2158]: info: [1724588121428] CoreMusicLibrary::Adding element Podcast Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:21 volumio volumio[2158]: [SpotifyConnect] Creating VLS config file Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: [SpotifyConnect] Starting metadata listener Aug 25 12:15:21 volumio sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Aug 25 12:15:21 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:21 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:21 volumio volumio[2158]: info: ----- MyVolumio plugins startup ---- Aug 25 12:15:21 volumio volumio[2158]: info: ------------------------------------------- Aug 25 12:15:21 volumio systemd[1]: Starting Spotify Daemon... Aug 25 12:15:21 volumio volumio[2158]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 25 12:15:21 volumio systemd[1]: Started Spotify Daemon. Aug 25 12:15:21 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:21 volumio sudo[2279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 25 12:15:21 volumio volumio[2158]: info: Loading i18n strings for locale ja Aug 25 12:15:21 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [SPTF] 12:15:21.511 I [offline_authorizer.cpp:297] Unable to login offline: no such user Aug 25 12:15:21 volumio volumio[2158]: Updating browse sources language Aug 25 12:15:21 volumio volumio[2158]: Cannot find translation for sourceMedia Servers Aug 25 12:15:21 volumio volumio[2158]: Cannot find translation for sourcePodcast Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [SPTF] 12:15:21.522 I [ap:1752] Connecting to AP ap.spotify.com:4070 Aug 25 12:15:21 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 25 12:15:21 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::initPlayerControls Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [SPTF] 12:15:21.581 I [ap:1226] Connected to AP: 104.199.240.237:4070 Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:15:21 volumio volumio[2158]: info: BOOT COMPLETED Aug 25 12:15:21 volumio volumio[2158]: [Metrics] CommandRouter: 9s 127.34ms Aug 25 12:15:21 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:21 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [SPTF] 12:15:21.845 E [ap:3953] Connection error: 409 Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [WARN] Login failed: User needs a premium account Aug 25 12:15:21 volumio volumio[2280]: 2024-08-25 12:15:21 [ERR ] Could not get the playlist container. Aug 25 12:15:21 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Aug 25 12:15:21 volumio systemd[1]: Unit spop.service entered failed state. Aug 25 12:15:22 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:22 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:15:22 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:15:25 volumio volumio[1159]: [Vollibrespot] : couldn't parse packet from V4(192.168.11.9:5353): query type 65 is invalid Aug 25 12:15:26 volumio volumio[2158]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 25 12:15:26 volumio volumio[2158]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:15:26 volumio volumio[2158]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:15:26 volumio volumio[2158]: Express server listening on port 3000 Aug 25 12:15:26 volumio volumio[2158]: [Metrics] WebUI: 14s 932.15ms Aug 25 12:15:26 volumio volumio[2158]: Volumio Calling Home Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::resetVolumioState Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::getcurrentVolume Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:15:27 volumio sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:15:27 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:27 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:27 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:15:27 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:27 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:27 volumio volumio[2158]: info: Setting Device type: Raspberry PI Aug 25 12:15:27 volumio volumio[2158]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 25 12:15:27 volumio volumio[2158]: [Metrics] SpotifyConnect: 5s 830.98ms Aug 25 12:15:27 volumio volumio[2158]: info: SpopD Daemon Started Aug 25 12:15:27 volumio volumio[2158]: info: Cannot mount NAS Volumio at system boot, trial number 1 ,retrying in 5 seconds Aug 25 12:15:27 volumio volumio[2158]: error: Cannot initialize MPD Connection: MPD is not running Aug 25 12:15:27 volumio volumio[2158]: info: VolumeController:: Volume=30 Mute =false Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::pushState Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioPushState Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::updateTrackBlock Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrackBlock Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:15:27 volumio volumio[2158]: info: Reloading queue from file Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::setRepeat true single undefined Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::pushState Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioPushState Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::setRandom false Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::pushState Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioPushState Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:15:27 volumio volumio[2158]: info: Volumio called home Aug 25 12:15:27 volumio volumio[2158]: info: Starting Shairport Sync Aug 25 12:15:27 volumio volumio[2158]: info: Starting Shairport Sync Aug 25 12:15:27 volumio sudo[2331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:15:27 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 12:15:27 volumio sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:15:27 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:15:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:15:27 volumio sudo[2331]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:27 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:27 volumio volumio[2158]: info: VolumeController:: Volume=30 Mute =false Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::pushState Aug 25 12:15:27 volumio sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioPushState Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:15:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 12:15:27 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:15:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:15:27 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:27 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:27 volumio volumio-remote-updater[550]: [2024-08-25 12:15:27] [connect] Successful connection Aug 25 12:15:27 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 25 12:15:27 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:27 volumio volumio[2158]: info: Shairport-Sync Started Aug 25 12:15:27 volumio volumio[2158]: Error adding Membership: Error: addMembership EINVAL Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 12:15:27 volumio volumio[2158]: Discovery: adding 1d1da114-df90-4fdd-b3db-7ace96709810 Aug 25 12:15:27 volumio volumio[2158]: info: mDNS: Found device Volumio-2 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioGetState Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioGetState Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:15:27 volumio volumio[2158]: info: Listing playlists Aug 25 12:15:27 volumio volumio[2158]: info: CoreCommandRouter::volumioGetQueue Aug 25 12:15:27 volumio volumio[2158]: info: CoreStateMachine::getQueue Aug 25 12:15:27 volumio volumio[2158]: info: CorePlayQueue::getQueue Aug 25 12:15:28 volumio volumio[2158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 12:15:28 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:28 volumio volumio[2158]: info: Upmpdcli Daemon Started Aug 25 12:15:28 volumio volumio[2158]: info: Shairport-Sync Started Aug 25 12:15:28 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:28 volumio volumio-remote-updater[550]: [2024-08-25 12:15:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724588127 101 Aug 25 12:15:28 volumio volumio[2158]: info: CoreCommandRouter::volumioGetState Aug 25 12:15:28 volumio volumio[2158]: info: CorePlayQueue::getTrack 0 Aug 25 12:15:32 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:32 volumio volumio[2158]: info: Connecting to daemon Aug 25 12:15:32 volumio volumio[2158]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:15:32 volumio volumio[2158]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:15:32 volumio volumio[2158]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 25 12:15:32 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:15:32 volumio sudo[2378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:15:32 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:32 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 12:15:34 volumio volumio[2158]: info: Adding plugin bluetooth to MyMusic Plugins Aug 25 12:15:34 volumio volumio[2158]: info: Adding plugin cd_controller to MyMusic Plugins Aug 25 12:15:34 volumio volumio[2158]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 25 12:15:34 volumio volumio[2158]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 25 12:15:34 volumio volumio[2158]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 12:15:35 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:35 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:35 volumio volumio[2158]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 25 12:15:35 volumio volumio[2158]: info: Streaming services startup Aug 25 12:15:35 volumio volumio[2158]: info: Starting Streaming Daemon Aug 25 12:15:35 volumio sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 12:15:35 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:35 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 25 12:15:35 volumio volumio[2158]: info: MyVolumio not started Aug 25 12:15:35 volumio volumio[2158]: info: Initializing device activation check Aug 25 12:15:35 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 25 12:15:35 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 25 12:15:35 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:35 volumio volumio[2158]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 12:15:35 volumio volumio-streaming-daemon[2396]: ############################ Aug 25 12:15:35 volumio volumio-streaming-daemon[2396]: # Volumio Streaming Daemon # Aug 25 12:15:35 volumio volumio-streaming-daemon[2396]: # Running on port 7777 # Aug 25 12:15:35 volumio volumio-streaming-daemon[2396]: ############################ Aug 25 12:15:35 volumio volumio-streaming-daemon[2396]: Environment: production Aug 25 12:15:35 volumio volumio[2158]: info: New Spotify access token = BQDSRIqteP3aarKrm_JXN9hz29kYGC6mNih7x4C4cgIPtr01fatffEOIUSHM1m7qFE_eQxcsely9VelwFjapBhLvrM2_EXjNe7rTypSJdbqnsSIFW1svxvgTFrKxcQ9a3SfDCxSZHl2imVGge2RMAQp_efal5_3xP3QswmPMQokRqkrnYrn-l4ccidRC4Y9bTbYCWDxBSfkmfbXWyKnj4p0aIRF4CTdii3f_ Aug 25 12:15:35 volumio volumio[2158]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 25 12:15:36 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:38 volumio volumio-streaming-daemon[2396]: Environment: production Aug 25 12:15:38 volumio volumio[2158]: info: Fetching Streaming Services browse cache Aug 25 12:15:38 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:38 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:15:38 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:15:38 volumio volumio[2158]: info: Cannot mount NAS Volumio at system boot, trial number 2 ,retrying in 5 seconds Aug 25 12:15:40 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:43 volumio sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.32/NAS /mnt/NAS/Volumio Aug 25 12:15:43 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:43 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Aug 25 12:15:44 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:44 volumio volumio[2158]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 12:15:48 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:49 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:49 volumio kernel: CIFS VFS: Error connecting to socket. Aborting operation. Aug 25 12:15:49 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -113 Aug 25 12:15:49 volumio volumio[2158]: info: Cannot mount NAS Volumio at system boot, trial number 3 ,retrying in 5 seconds Aug 25 12:15:51 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:15:51 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:15:52 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:15:52 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 25 12:15:53 volumio volumio[2158]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 25 12:15:54 volumio volumio[2158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:15:54 volumio volumio[2158]: TypeError: Cannot read property 'replace' of undefined Aug 25 12:15:54 volumio volumio[2158]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:198:28) Aug 25 12:15:54 volumio volumio[2158]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:251:20) Aug 25 12:15:54 volumio volumio[2158]: at ontimeout (timers.js:482:11) Aug 25 12:15:54 volumio volumio[2158]: at tryOnTimeout (timers.js:317:5) Aug 25 12:15:54 volumio volumio[2158]: at Timer.listOnTimeout (timers.js:277:5) Aug 25 12:15:54 volumio volumio[2158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:15:55 volumio sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 12:14 Aug 25 12:15:55 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:15:55 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root Aug 25 12:15:57 volumio volumio-remote-updater[550]: [2024-08-25 12:15:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 25 12:15:57 volumio volumio-remote-updater[550]: [2024-08-25 12:15:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 25 12:15:57 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Aug 25 12:15:57 volumio systemd[1]: Unit volumio.service entered failed state. Aug 25 12:15:57 volumio systemd[1]: Starting dynamicswap service... Aug 25 12:15:57 volumio systemd[1]: Started dynamicswap service. Aug 25 12:15:57 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Aug 25 12:15:57 volumio systemd[1]: Starting dynamicswap service... Aug 25 12:15:57 volumio systemd[1]: Started dynamicswap service. Aug 25 12:15:57 volumio systemd[1]: Stopping Volumio Backend Module... Aug 25 12:15:57 volumio systemd[1]: Starting Volumio Backend Module... Aug 25 12:15:57 volumio systemd[1]: Started Volumio Backend Module. Aug 25 12:15:59 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:15:59 volumio volumio[2474]: info: ----- Volumio2 ---- Aug 25 12:15:59 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:15:59 volumio volumio[2474]: info: ----- System startup ---- Aug 25 12:15:59 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:16:00 volumio volumio[2474]: info: MYVOLUMIO Environment detected Aug 25 12:16:00 volumio volumio[2474]: info: Plugin folders cleanup Aug 25 12:16:00 volumio volumio[2474]: info: Scanning into folder /volumio/app/plugins/ Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category audio_interface Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category miscellanea Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category music_service Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category plugins.json Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category system_controller Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category user_interface Aug 25 12:16:00 volumio volumio[2474]: info: Scanning into folder /data/plugins/ Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category audio_interface Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category music_service Aug 25 12:16:00 volumio volumio[2474]: info: Scanning category system_controller Aug 25 12:16:00 volumio volumio[2474]: info: Plugin folders cleanup completed Aug 25 12:16:00 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:16:00 volumio volumio[2474]: info: ----- Core plugins startup ---- Aug 25 12:16:00 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:16:00 volumio volumio[2474]: info: Loading plugins from folder /volumio/app/plugins/ Aug 25 12:16:00 volumio volumio[2474]: info: Adding plugin upnp to MyMusic Plugins Aug 25 12:16:00 volumio volumio[2474]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 25 12:16:00 volumio volumio[2474]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 25 12:16:00 volumio volumio[2474]: info: Loading plugins from folder /data/plugins/ Aug 25 12:16:00 volumio volumio[2474]: info: Loading plugin "system"... Aug 25 12:16:00 volumio volumio[2474]: info: Plugin gpio_control is not enabled Aug 25 12:16:00 volumio volumio[2474]: info: Loading plugin "appearance"... Aug 25 12:16:02 volumio volumio-remote-updater[550]: [2024-08-25 12:16:02] [connect] Successful connection Aug 25 12:16:02 volumio volumio[2474]: info: Loading plugin "network"... Aug 25 12:16:02 volumio volumio[2474]: info: Refreshing Cached IP Addresses Aug 25 12:16:02 volumio sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:16:02 volumio volumio[2474]: info: Loading plugin "services"... Aug 25 12:16:02 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:02 volumio volumio[2474]: info: Loading plugin "alsa_controller"... Aug 25 12:16:02 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:02 volumio sudo[2504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:16:02 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:02 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "wizard"... Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "volumio_command_line_client"... Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "upnp"... Aug 25 12:16:03 volumio volumio[2474]: info: [1724588163084] Starting Upmpd Daemon Aug 25 12:16:03 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "my_music"... Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "mpd"... Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "upnp_browser"... Aug 25 12:16:03 volumio volumio[2474]: info: Loading plugin "networkfs"... Aug 25 12:16:04 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "alarm-clock"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "airplay_emulation"... Aug 25 12:16:04 volumio volumio[2474]: info: Starting Shairport Sync Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "last_100"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "webradio"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "i2s_dacs"... Aug 25 12:16:04 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "volumiodiscovery"... Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 12:16:04 volumio node[2474]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 12:16:04 volumio node[2474]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:16:04 volumio node[2474]: *** WARNING *** For more information see Aug 25 12:16:04 volumio node[2474]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 12:16:04 volumio node[2474]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:16:04 volumio node[2474]: *** WARNING *** For more information see Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** For more information see Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 12:16:04 volumio volumio[2474]: *** WARNING *** For more information see Aug 25 12:16:04 volumio volumio[2474]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 25 12:16:04 volumio volumio[2474]: Discovery: StartAdv! undefined Aug 25 12:16:04 volumio volumio[2474]: Discovery: Started advertising... Volumio-2 - undefined Aug 25 12:16:04 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 12:16:04 volumio volumio[2474]: info: Plugin es9038q2m is not enabled Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "outputs"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "albumart"... Aug 25 12:16:04 volumio volumio[2474]: info: Plugin example_plugin is not enabled Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "inputs"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "updater_comm"... Aug 25 12:16:04 volumio volumio[2474]: info: Plugin mpdemulation is not enabled Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "rest_api"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "websocket"... Aug 25 12:16:04 volumio volumio[2474]: info: Loading plugin "podcast"... Aug 25 12:16:04 volumio volumio[2474]: Forking 3 albumart workers Aug 25 12:16:05 volumio volumio[2474]: Starting albumart workers Aug 25 12:16:05 volumio volumio[2474]: Starting albumart workers Aug 25 12:16:05 volumio volumio[2474]: Starting albumart workers Aug 25 12:16:07 volumio volumio[2474]: info: ControllerPodcast::constructor Aug 25 12:16:07 volumio volumio[2474]: info: Loading plugin "spop"... Aug 25 12:16:07 volumio volumio[2474]: info: Loading plugin "volspotconnect2"... Aug 25 12:16:08 volumio volumio[2474]: info: ___________ START PLUGINS ___________ Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:16:08 volumio volumio[2474]: info: [1724588168528] CoreMusicLibrary::Adding element Media Servers Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:08 volumio volumio[2474]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:16:08 volumio volumio[2474]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:16:08 volumio volumio[2474]: info: [1724588168649] CoreMusicLibrary::Adding element Last_100 Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:16:08 volumio volumio[2474]: info: [1724588168653] CoreMusicLibrary::Adding element Webradio Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 12:16:08 volumio volumio[2474]: info: [1724588168671] CoreMusicLibrary::Adding element Podcast Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:08 volumio volumio[2474]: [SpotifyConnect] Creating VLS config file Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: [SpotifyConnect] Starting metadata listener Aug 25 12:16:08 volumio sudo[2582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Aug 25 12:16:08 volumio sudo[2582]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:08 volumio systemd[1]: Starting Spotify Daemon... Aug 25 12:16:08 volumio systemd[1]: Started Spotify Daemon. Aug 25 12:16:08 volumio sudo[2582]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:08 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:16:08 volumio volumio[2474]: info: ----- MyVolumio plugins startup ---- Aug 25 12:16:08 volumio volumio[2474]: info: ------------------------------------------- Aug 25 12:16:08 volumio volumio[2474]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 25 12:16:08 volumio volumio[2588]: 2024-08-25 12:16:08 [SPTF] 12:16:08.752 I [offline_authorizer.cpp:297] Unable to login offline: no such user Aug 25 12:16:08 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 25 12:16:08 volumio volumio[2588]: 2024-08-25 12:16:08 [SPTF] 12:16:08.757 I [ap:1752] Connecting to AP ap.spotify.com:4070 Aug 25 12:16:08 volumio volumio[2474]: info: Loading i18n strings for locale ja Aug 25 12:16:08 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:08 volumio volumio[2474]: Updating browse sources language Aug 25 12:16:08 volumio volumio[2474]: Cannot find translation for sourceMedia Servers Aug 25 12:16:08 volumio volumio[2474]: Cannot find translation for sourcePodcast Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:08 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 25 12:16:08 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::initPlayerControls Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 12:16:08 volumio volumio[2474]: info: BOOT COMPLETED Aug 25 12:16:08 volumio volumio[2474]: [Metrics] CommandRouter: 9s 88.59ms Aug 25 12:16:08 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:16:08 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 25 12:16:09 volumio volumio[2588]: 2024-08-25 12:16:09 [SPTF] 12:16:09.902 I [ap:1226] Connected to AP: 104.199.240.237:4070 Aug 25 12:16:10 volumio volumio[2588]: 2024-08-25 12:16:10 [SPTF] 12:16:10.126 E [ap:3953] Connection error: 409 Aug 25 12:16:10 volumio volumio[2588]: 2024-08-25 12:16:10 [WARN] Login failed: User needs a premium account Aug 25 12:16:10 volumio volumio[2588]: 2024-08-25 12:16:10 [ERR ] Could not get the playlist container. Aug 25 12:16:10 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Aug 25 12:16:10 volumio systemd[1]: Unit spop.service entered failed state. Aug 25 12:16:14 volumio volumio[2474]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::Close All Modals sent Aug 25 12:16:14 volumio volumio[2474]: Express server listening on port 3000 Aug 25 12:16:14 volumio volumio[2474]: [Metrics] WebUI: 14s 903.81ms Aug 25 12:16:14 volumio volumio[2474]: Volumio Calling Home Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::resetVolumioState Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::getcurrentVolume Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 12:16:14 volumio sudo[2615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 12:16:14 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:14 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:14 volumio sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 12:16:14 volumio sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:14 volumio sudo[2618]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:14 volumio volumio[2474]: info: Setting Device type: Raspberry PI Aug 25 12:16:14 volumio volumio[2474]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 25 12:16:14 volumio volumio[2474]: [Metrics] SpotifyConnect: 5s 839.46ms Aug 25 12:16:14 volumio volumio[2474]: info: SpopD Daemon Started Aug 25 12:16:14 volumio volumio[2474]: error: Cannot initialize MPD Connection: MPD is not running Aug 25 12:16:14 volumio volumio[2474]: info: VolumeController:: Volume=30 Mute =false Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::pushState Aug 25 12:16:14 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::volumioPushState Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::updateTrackBlock Aug 25 12:16:14 volumio volumio[2474]: info: CorePlayQueue::getTrackBlock Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 12:16:14 volumio volumio[2474]: info: Reloading queue from file Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::setRepeat true single undefined Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::pushState Aug 25 12:16:14 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::volumioPushState Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::setRandom false Aug 25 12:16:14 volumio volumio[2474]: info: CoreStateMachine::pushState Aug 25 12:16:14 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::volumioPushState Aug 25 12:16:14 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:16:14 volumio volumio[2474]: info: Volumio called home Aug 25 12:16:14 volumio volumio[2474]: info: Starting Shairport Sync Aug 25 12:16:14 volumio volumio[2474]: info: Starting Shairport Sync Aug 25 12:16:14 volumio sudo[2637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:16:14 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 12:16:14 volumio sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 12:16:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:16:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:16:15 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:15 volumio sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:15 volumio sudo[2645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 25 12:16:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 12:16:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 25 12:16:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 12:16:15 volumio sudo[2645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:15 volumio sudo[2640]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:15 volumio volumio[2474]: info: VolumeController:: Volume=30 Mute =false Aug 25 12:16:15 volumio volumio[2474]: info: CoreStateMachine::pushState Aug 25 12:16:15 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:15 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioPushState Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 12:16:15 volumio sudo[2645]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:15 volumio volumio-remote-updater[550]: [2024-08-25 12:16:15] [connect] Successful connection Aug 25 12:16:15 volumio volumio[2474]: info: Shairport-Sync Started Aug 25 12:16:15 volumio volumio[2474]: Error adding Membership: Error: addMembership EINVAL Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 12:16:15 volumio volumio[2474]: Discovery: adding 1d1da114-df90-4fdd-b3db-7ace96709810 Aug 25 12:16:15 volumio volumio[2474]: info: mDNS: Found device Volumio-2 Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioGetState Aug 25 12:16:15 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:15 volumio volumio[2474]: info: Upmpdcli Daemon Started Aug 25 12:16:15 volumio volumio[2474]: info: Shairport-Sync Started Aug 25 12:16:15 volumio volumio-remote-updater[550]: [2024-08-25 12:16:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724588175 101 Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioGetState Aug 25 12:16:15 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioGetState Aug 25 12:16:15 volumio volumio[2474]: info: CorePlayQueue::getTrack 0 Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 12:16:15 volumio volumio[2474]: info: Listing playlists Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioGetQueue Aug 25 12:16:15 volumio volumio[2474]: info: CoreStateMachine::getQueue Aug 25 12:16:15 volumio volumio[2474]: info: CorePlayQueue::getQueue Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 12:16:15 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:16 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 12:16:17 volumio volumio[2474]: info: Adding plugin bluetooth to MyMusic Plugins Aug 25 12:16:17 volumio volumio[2474]: info: Adding plugin cd_controller to MyMusic Plugins Aug 25 12:16:17 volumio volumio[2474]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 25 12:16:17 volumio volumio[2474]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 12:16:17 volumio volumio[2474]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 25 12:16:18 volumio volumio[2474]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 12:16:18 volumio volumio[2474]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 12:16:18 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:16:18 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 12:16:18 volumio volumio[2474]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 12:16:18 volumio volumio[2474]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 25 12:16:18 volumio volumio[2474]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 25 12:16:18 volumio volumio[2474]: info: Streaming services startup Aug 25 12:16:18 volumio volumio[2474]: info: Starting Streaming Daemon Aug 25 12:16:18 volumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 12:16:18 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 12:16:18 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 25 12:16:18 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 25 12:16:18 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 25 12:16:18 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root Aug 25 12:16:18 volumio volumio-streaming-daemon[2694]: ############################ Aug 25 12:16:18 volumio volumio-streaming-daemon[2694]: # Volumio Streaming Daemon # Aug 25 12:16:18 volumio volumio-streaming-daemon[2694]: # Running on port 7777 # Aug 25 12:16:18 volumio volumio-streaming-daemon[2694]: ############################ Aug 25 12:16:18 volumio volumio-streaming-daemon[2694]: Environment: production Aug 25 12:16:18 volumio volumio[2474]: info: MyVolumio not started Aug 25 12:16:18 volumio volumio[2474]: info: Initializing device activation check Aug 25 12:16:18 volumio volumio[2474]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 12:16:19 volumio volumio[2474]: info: Connecting to daemon Aug 25 12:16:19 volumio volumio[2474]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:16:19 volumio volumio[2474]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 25 12:16:19 volumio volumio[2474]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 25 12:16:19 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 12:16:20 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:20 volumio volumio[2474]: info: New Spotify access token = BQA3m30OLvOCYAD_r60iIKdu6QXU5VTdvb5f9y53b9wLVhUkKbEeSEzsFduLAIjwCUjw8vjLBo9ERyym_jsYqqRvDCPqXbc8j3iOJ6dgVGIfPpfG3FWDBDfOf1GyMhD6PNtzDb-n6b2g0JawEh-ykl7IU7TaZBrF_9cF32Txjh8NpjEfwoGaID9xKMuRSSQfvIttFXOwTcYSbBSJzAVAgiQgdqHOgt0qibE- Aug 25 12:16:20 volumio volumio[2474]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 25 12:16:20 volumio volumio-streaming-daemon[2694]: Environment: production Aug 25 12:16:20 volumio volumio[2474]: info: Fetching Streaming Services browse cache Aug 25 12:16:22 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 25 12:16:24 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 12:16:24 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 25 12:16:27 volumio volumio[2474]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 25 12:16:27 volumio volumio[2474]: verbose: ControllerMpd::sendMpdCommand rescan Aug 25 12:16:27 volumio volumio[2474]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:16:27 volumio volumio[2474]: TypeError: Cannot read property 'then' of undefined Aug 25 12:16:27 volumio volumio[2474]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Aug 25 12:16:27 volumio volumio[2474]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1825:15) Aug 25 12:16:27 volumio volumio[2474]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Aug 25 12:16:27 volumio volumio[2474]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:907:26) Aug 25 12:16:27 volumio volumio[2474]: at emitTwo (events.js:126:13) Aug 25 12:16:27 volumio volumio[2474]: at Socket.emit (events.js:214:7) Aug 25 12:16:27 volumio volumio[2474]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 25 12:16:27 volumio volumio[2474]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 25 12:16:27 volumio volumio[2474]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 25 12:16:27 volumio volumio[2474]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 12:16:28 volumio sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 12:15 Aug 25 12:16:28 volumio sudo[2718]: 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"