-- Logs begin at Sat 2024-11-02 07:32:12 UTC, end at Sat 2024-11-02 07:34:15 UTC. -- Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "network"... Nov 02 07:33:01 volumio2 volumio[924]: info: Refreshing Cached IP Addresses Nov 02 07:33:01 volumio2 sudo[967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "services"... Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "alsa_controller"... Nov 02 07:33:01 volumio2 sudo[967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:01 volumio2 sudo[967]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:01 volumio2 sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:01 volumio2 sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:01 volumio2 sudo[970]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "wizard"... Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "volumio_command_line_client"... Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "upnp"... Nov 02 07:33:01 volumio2 volumio[924]: info: [1730532781272] Starting Upmpd Daemon Nov 02 07:33:01 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "my_music"... Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "mpd"... Nov 02 07:33:01 volumio2 volumio[924]: info: Loading plugin "upnp_browser"... Nov 02 07:33:02 volumio2 volumio[924]: info: Loading plugin "networkfs"... Nov 02 07:33:02 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:02 volumio2 volumio[924]: info: Loading plugin "alarm-clock"... Nov 02 07:33:02 volumio2 volumio[924]: info: Loading plugin "airplay_emulation"... Nov 02 07:33:02 volumio2 volumio[924]: info: Starting Shairport Sync Nov 02 07:33:02 volumio2 volumio[924]: info: Loading plugin "last_100"... Nov 02 07:33:02 volumio2 volumio[924]: info: Loading plugin "webradio"... Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "i2s_dacs"... Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "volumiodiscovery"... Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** For more information see Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:03 volumio2 node[924]: *** WARNING *** For more information see Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** For more information see Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:03 volumio2 volumio[924]: *** WARNING *** For more information see Nov 02 07:33:03 volumio2 volumio[924]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 07:33:03 volumio2 volumio[924]: Discovery: StartAdv! undefined Nov 02 07:33:03 volumio2 volumio[924]: Discovery: Started advertising... Volumio2 - undefined Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "outputs"... Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "albumart"... Nov 02 07:33:03 volumio2 volumio[924]: info: Plugin example_plugin is not enabled Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "inputs"... Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "updater_comm"... Nov 02 07:33:03 volumio2 volumio[924]: info: Plugin mpdemulation is not enabled Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "rest_api"... Nov 02 07:33:03 volumio2 volumio[924]: info: Loading plugin "websocket"... Nov 02 07:33:03 volumio2 volumio[924]: info: ___________ START PLUGINS ___________ Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:03 volumio2 volumio[924]: info: [1730532783454] CoreMusicLibrary::Adding element Media Servers Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:03 volumio2 volumio[924]: Forking 3 albumart workers Nov 02 07:33:03 volumio2 volumio[924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:03 volumio2 volumio[924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:03 volumio2 volumio[924]: info: [1730532783602] CoreMusicLibrary::Adding element Last_100 Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:03 volumio2 volumio[924]: info: [1730532783606] CoreMusicLibrary::Adding element Webradio Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:33:03 volumio2 volumio[924]: info: ------------------------------------------- Nov 02 07:33:03 volumio2 volumio[924]: info: ----- MyVolumio plugins startup ---- Nov 02 07:33:03 volumio2 volumio[924]: info: ------------------------------------------- Nov 02 07:33:03 volumio2 volumio[924]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 07:33:03 volumio2 volumio[924]: info: Loading i18n strings for locale ja Nov 02 07:33:03 volumio2 volumio[924]: Updating browse sources language Nov 02 07:33:03 volumio2 volumio[924]: Cannot find translation for sourceMedia Servers Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::initPlayerControls Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:03 volumio2 volumio[924]: info: BOOT COMPLETED Nov 02 07:33:03 volumio2 volumio[924]: [Metrics] CommandRouter: 6s 774.89ms Nov 02 07:33:03 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:03 volumio2 kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Nov 02 07:33:04 volumio2 volumio[924]: Starting albumart workers Nov 02 07:33:04 volumio2 volumio[924]: Starting albumart workers Nov 02 07:33:04 volumio2 volumio[924]: Starting albumart workers Nov 02 07:33:09 volumio2 volumio[924]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:33:09 volumio2 volumio[924]: Express server listening on port 3000 Nov 02 07:33:09 volumio2 volumio[924]: [Metrics] WebUI: 12s 822.05ms Nov 02 07:33:09 volumio2 volumio[924]: Volumio Calling Home Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::resetVolumioState Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::getcurrentVolume Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::pushState Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:09 volumio2 volumio[924]: info: Setting Device type: Raspberry PI Nov 02 07:33:09 volumio2 volumio[924]: error: Cannot initialize MPD Connection: MPD is not running Nov 02 07:33:09 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:09] [connect] Successful connection Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 07:33:09 volumio2 volumio[924]: info: Discovery: Getting this device information Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:09 volumio2 volumio[924]: info: Cannot read play queue from file Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:09 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730532789 101 Nov 02 07:33:09 volumio2 volumio[924]: info: Volumio called home Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::setRepeat null single undefined Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::pushState Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::setRandom null Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::pushState Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:09 volumio2 volumio[924]: info: CoreStateMachine::getQueue Nov 02 07:33:09 volumio2 volumio[924]: info: CorePlayQueue::getQueue Nov 02 07:33:09 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 02 07:33:10 volumio2 volumio[924]: info: Starting Shairport Sync Nov 02 07:33:10 volumio2 volumio[924]: info: Starting Shairport Sync Nov 02 07:33:10 volumio2 sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:33:10 volumio2 sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:10 volumio2 sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:33:10 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:10 volumio2 sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:10 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:33:10 volumio2 sudo[1058]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:10 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:10 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:10 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:33:10 volumio2 sudo[1060]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 07:33:10 volumio2 volumio[924]: Discovery: adding 1be6ef9c-369c-41f2-a1d1-f7f1a7a8ae41 Nov 02 07:33:10 volumio2 volumio[924]: info: mDNS: Found device Volumio2 Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:10 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:10 volumio2 volumio[924]: info: Shairport-Sync Started Nov 02 07:33:10 volumio2 volumio[924]: Error adding Membership: Error: addMembership EINVAL Nov 02 07:33:10 volumio2 volumio[924]: info: Shairport-Sync Started Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:10 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:10 volumio2 volumio[924]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:10 volumio2 volumio[924]: info: CoreStateMachine::getQueue Nov 02 07:33:10 volumio2 volumio[924]: info: CorePlayQueue::getQueue Nov 02 07:33:10 volumio2 volumio[924]: info: Listing playlists Nov 02 07:33:10 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:33:11 volumio2 sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:11 volumio2 sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:11 volumio2 sudo[1088]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:11 volumio2 sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:11 volumio2 sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:11 volumio2 sudo[1091]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:11 volumio2 sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 07:33:11 volumio2 sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:11 volumio2 sudo[1099]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:11 volumio2 volumio[924]: error: Cannot start Upmpdcli: Error: Command failed: /usr/bin/sudo /bin/systemctl start upmpdcli.service Nov 02 07:33:11 volumio2 volumio[924]: Failed to start upmpdcli.service: Unit upmpdcli.service failed to load: No such file or directory. Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 07:33:12 volumio2 volumio[924]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 07:33:12 volumio2 volumio[924]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 07:33:12 volumio2 volumio[924]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 07:33:12 volumio2 volumio[924]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 07:33:12 volumio2 volumio[924]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 07:33:13 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:13 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:13 volumio2 volumio[924]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 07:33:13 volumio2 volumio[924]: info: Streaming services startup Nov 02 07:33:13 volumio2 volumio[924]: info: Starting Streaming Daemon Nov 02 07:33:13 volumio2 sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 07:33:13 volumio2 sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:13 volumio2 volumio[924]: info: MyVolumio login type: Token Nov 02 07:33:13 volumio2 volumio[924]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 07:33:13 volumio2 systemd[1]: Stopping Volumio Streaming Daemon... Nov 02 07:33:13 volumio2 systemd[1]: Starting Volumio Streaming Daemon... Nov 02 07:33:13 volumio2 systemd[1]: Started Volumio Streaming Daemon. Nov 02 07:33:13 volumio2 sudo[1115]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:13 volumio2 volumio-streaming-daemon[1125]: ############################ Nov 02 07:33:13 volumio2 volumio-streaming-daemon[1125]: # Volumio Streaming Daemon # Nov 02 07:33:13 volumio2 volumio-streaming-daemon[1125]: # Running on port 7777 # Nov 02 07:33:13 volumio2 volumio-streaming-daemon[1125]: ############################ Nov 02 07:33:13 volumio2 volumio-streaming-daemon[1125]: Environment: production Nov 02 07:33:14 volumio2 volumio[924]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 07:33:14 volumio2 volumio[924]: info: MYVOLUMIO: Adding device Nov 02 07:33:14 volumio2 volumio[924]: info: MYVOLUMIO: Evaluating Server Nov 02 07:33:15 volumio2 volumio[924]: info: MyVolumio status changed Nov 02 07:33:15 volumio2 volumio[924]: info: Streaming services startup Nov 02 07:33:15 volumio2 volumio[924]: info: Re-Starting Streaming Daemon Nov 02 07:33:15 volumio2 volumio[924]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 07:33:15 volumio2 volumio[924]: info: Removing audio output: Nov 02 07:33:15 volumio2 volumio[924]: info: Stoppping Tunnel 1 Nov 02 07:33:15 volumio2 sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 07:33:15 volumio2 volumio-streaming-daemon[1125]: Restarting daemon Nov 02 07:33:15 volumio2 volumio-streaming-daemon[1125]: Environment: production Nov 02 07:33:15 volumio2 sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:15 volumio2 systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 02 07:33:15 volumio2 sudo[1152]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:15 volumio2 volumio[924]: info: Remote SSH Stopped Nov 02 07:33:15 volumio2 volumio[924]: info: Setting Geolocation for MyVolumio to as1 Nov 02 07:33:15 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:15 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:15 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:16 volumio2 volumio-streaming-daemon[1125]: Environment: production Nov 02 07:33:16 volumio2 volumio[924]: info: Fetching Streaming Services browse cache Nov 02 07:33:16 volumio2 volumio-streaming-daemon[1125]: Environment: production Nov 02 07:33:16 volumio2 volumio[924]: info: Fetching Streaming Services browse cache Nov 02 07:33:16 volumio2 volumio[924]: info: Updating MyVolumio device info Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 07:33:16 volumio2 volumio[924]: info: CoreStateMachine::ClearQueue Nov 02 07:33:16 volumio2 volumio[924]: info: CoreStateMachine::stop Nov 02 07:33:16 volumio2 volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 07:33:16 volumio2 volumio[924]: verbose: UNSET VOLATILE Nov 02 07:33:16 volumio2 volumio[924]: info: CorePlayQueue::clearPlayQueue Nov 02 07:33:16 volumio2 volumio[924]: info: CorePlayQueue::saveQueue Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:16 volumio2 volumio[924]: info: CoreCommandRouter::volumioPushQueue Nov 02 07:33:16 volumio2 volumio[924]: info: CoreStateMachine::addQueueItems Nov 02 07:33:16 volumio2 volumio[924]: info: CorePlayQueue::addQueueItems Nov 02 07:33:16 volumio2 volumio[924]: info: Adding Item to queue: mnt/USB/A54F-F297/闡提宗祀 ～ Offering to The Sukhavati/08 亡き王女の為のセプテット ～ Ascending Into Nau.aif Nov 02 07:33:16 volumio2 volumio[924]: info: Exploding uri mnt/USB/A54F-F297/闡提宗祀 ～ Offering to The Sukhavati/08 亡き王女の為のセプテット ～ Ascending Into Nau.aif in service mpd Nov 02 07:33:16 volumio2 volumio[924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:33:16 volumio2 volumio[924]: TypeError: Cannot read property 'then' of undefined Nov 02 07:33:16 volumio2 volumio[924]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2479:19) Nov 02 07:33:16 volumio2 volumio[924]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2277:21) Nov 02 07:33:16 volumio2 volumio[924]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1283:16) Nov 02 07:33:16 volumio2 volumio[924]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Nov 02 07:33:16 volumio2 volumio[924]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:238:25) Nov 02 07:33:16 volumio2 volumio[924]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:476:23) Nov 02 07:33:16 volumio2 volumio[924]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:452:17) Nov 02 07:33:16 volumio2 volumio[924]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:106:35) Nov 02 07:33:16 volumio2 volumio[924]: at emitTwo (events.js:126:13) Nov 02 07:33:16 volumio2 volumio[924]: at Socket.emit (events.js:214:7) Nov 02 07:33:16 volumio2 volumio[924]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 02 07:33:16 volumio2 volumio[924]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 02 07:33:16 volumio2 volumio[924]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 02 07:33:16 volumio2 volumio[924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:33:17 volumio2 sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 07:32 Nov 02 07:33:17 volumio2 sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:17 volumio2 sudo[1170]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:19 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:19] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 07:33:19 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:19] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 07:33:19 volumio2 systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 02 07:33:19 volumio2 systemd[1]: Unit volumio.service entered failed state. Nov 02 07:33:19 volumio2 systemd[1]: Starting dynamicswap service... Nov 02 07:33:19 volumio2 systemd[1]: Started dynamicswap service. Nov 02 07:33:19 volumio2 systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 02 07:33:19 volumio2 systemd[1]: Starting dynamicswap service... Nov 02 07:33:19 volumio2 systemd[1]: Started dynamicswap service. Nov 02 07:33:19 volumio2 systemd[1]: Stopping Volumio Backend Module... Nov 02 07:33:19 volumio2 systemd[1]: Starting Volumio Backend Module... Nov 02 07:33:19 volumio2 systemd[1]: Started Volumio Backend Module. Nov 02 07:33:21 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:21 volumio2 volumio[1194]: info: ----- Volumio2 ---- Nov 02 07:33:21 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:21 volumio2 volumio[1194]: info: ----- System startup ---- Nov 02 07:33:21 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:22 volumio2 volumio[1194]: info: MYVOLUMIO Environment detected Nov 02 07:33:22 volumio2 volumio[1194]: info: Plugin folders cleanup Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning into folder /volumio/app/plugins/ Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category audio_interface Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category miscellanea Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category music_service Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category plugins.json Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category system_controller Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning category user_interface Nov 02 07:33:22 volumio2 volumio[1194]: info: Scanning into folder /data/plugins/ Nov 02 07:33:22 volumio2 volumio[1194]: info: Plugin folders cleanup completed Nov 02 07:33:22 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:22 volumio2 volumio[1194]: info: ----- Core plugins startup ---- Nov 02 07:33:22 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:22 volumio2 volumio[1194]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 07:33:22 volumio2 volumio[1194]: info: Adding plugin upnp to MyMusic Plugins Nov 02 07:33:22 volumio2 volumio[1194]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 07:33:22 volumio2 volumio[1194]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 07:33:22 volumio2 volumio[1194]: info: Loading plugins from folder /data/plugins/ Nov 02 07:33:22 volumio2 volumio[1194]: info: Loading plugin "system"... Nov 02 07:33:22 volumio2 volumio[1194]: info: Loading plugin "appearance"... Nov 02 07:33:24 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:24] [connect] Successful connection Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "network"... Nov 02 07:33:24 volumio2 volumio[1194]: info: Refreshing Cached IP Addresses Nov 02 07:33:24 volumio2 sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "services"... Nov 02 07:33:24 volumio2 sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "alsa_controller"... Nov 02 07:33:24 volumio2 sudo[1219]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:24 volumio2 sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:24 volumio2 sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:24 volumio2 sudo[1222]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "wizard"... Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "volumio_command_line_client"... Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "upnp"... Nov 02 07:33:24 volumio2 volumio[1194]: info: [1730532804589] Starting Upmpd Daemon Nov 02 07:33:24 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "my_music"... Nov 02 07:33:24 volumio2 volumio[1194]: info: Loading plugin "mpd"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "upnp_browser"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "networkfs"... Nov 02 07:33:25 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "alarm-clock"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "airplay_emulation"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Starting Shairport Sync Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "last_100"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "webradio"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "i2s_dacs"... Nov 02 07:33:25 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "volumiodiscovery"... Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** For more information see Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:25 volumio2 node[1194]: *** WARNING *** For more information see Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** For more information see Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:25 volumio2 volumio[1194]: *** WARNING *** For more information see Nov 02 07:33:25 volumio2 volumio[1194]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 07:33:25 volumio2 volumio[1194]: Discovery: StartAdv! undefined Nov 02 07:33:25 volumio2 volumio[1194]: Discovery: Started advertising... Volumio2 - undefined Nov 02 07:33:25 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "outputs"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "albumart"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Plugin example_plugin is not enabled Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "inputs"... Nov 02 07:33:25 volumio2 volumio[1194]: info: Loading plugin "updater_comm"... Nov 02 07:33:26 volumio2 volumio[1194]: info: Plugin mpdemulation is not enabled Nov 02 07:33:26 volumio2 volumio[1194]: info: Loading plugin "rest_api"... Nov 02 07:33:26 volumio2 volumio[1194]: info: Loading plugin "websocket"... Nov 02 07:33:26 volumio2 volumio[1194]: info: ___________ START PLUGINS ___________ Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:26 volumio2 volumio[1194]: info: [1730532806216] CoreMusicLibrary::Adding element Media Servers Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:26 volumio2 volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:26 volumio2 volumio[1194]: Forking 3 albumart workers Nov 02 07:33:26 volumio2 volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:26 volumio2 volumio[1194]: info: [1730532806341] CoreMusicLibrary::Adding element Last_100 Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:26 volumio2 volumio[1194]: info: [1730532806347] CoreMusicLibrary::Adding element Webradio Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:33:26 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:26 volumio2 volumio[1194]: info: ----- MyVolumio plugins startup ---- Nov 02 07:33:26 volumio2 volumio[1194]: info: ------------------------------------------- Nov 02 07:33:26 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 07:33:26 volumio2 volumio[1194]: info: Loading i18n strings for locale ja Nov 02 07:33:26 volumio2 volumio[1194]: Updating browse sources language Nov 02 07:33:26 volumio2 volumio[1194]: Cannot find translation for sourceMedia Servers Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::initPlayerControls Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:26 volumio2 volumio[1194]: info: BOOT COMPLETED Nov 02 07:33:26 volumio2 volumio[1194]: [Metrics] CommandRouter: 5s 187.10ms Nov 02 07:33:26 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:26 volumio2 kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Nov 02 07:33:27 volumio2 volumio[1194]: Starting albumart workers Nov 02 07:33:27 volumio2 volumio[1194]: Starting albumart workers Nov 02 07:33:27 volumio2 volumio[1194]: Starting albumart workers Nov 02 07:33:31 volumio2 volumio[1194]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:33:31 volumio2 volumio[1194]: Express server listening on port 3000 Nov 02 07:33:31 volumio2 volumio[1194]: [Metrics] WebUI: 10s 996.21ms Nov 02 07:33:31 volumio2 volumio[1194]: Volumio Calling Home Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreStateMachine::resetVolumioState Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreStateMachine::getcurrentVolume Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreStateMachine::pushState Nov 02 07:33:31 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:31 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:32 volumio2 volumio[1194]: info: Setting Device type: Raspberry PI Nov 02 07:33:32 volumio2 volumio[1194]: error: Cannot initialize MPD Connection: MPD is not running Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 07:33:32 volumio2 volumio[1194]: info: Discovery: Getting this device information Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:32 volumio2 volumio[1194]: info: Cannot read play queue from file Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::setRepeat null single undefined Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::pushState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::setRandom null Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::pushState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::getQueue Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getQueue Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 02 07:33:32 volumio2 volumio[1194]: info: Volumio called home Nov 02 07:33:32 volumio2 volumio[1194]: info: Starting Shairport Sync Nov 02 07:33:32 volumio2 volumio[1194]: info: Starting Shairport Sync Nov 02 07:33:32 volumio2 sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:33:32 volumio2 sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:32 volumio2 sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:33:32 volumio2 sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:32 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreStateMachine::getQueue Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getQueue Nov 02 07:33:32 volumio2 sudo[1313]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:32 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:33:32 volumio2 volumio[1194]: info: Listing playlists Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:33:32 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:32 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:33:32 volumio2 sudo[1315]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:32 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:33:32 volumio2 volumio[1194]: Discovery: adding 1be6ef9c-369c-41f2-a1d1-f7f1a7a8ae41 Nov 02 07:33:32 volumio2 volumio[1194]: info: mDNS: Found device Volumio2 Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:32 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:32 volumio2 volumio[1194]: info: Shairport-Sync Started Nov 02 07:33:32 volumio2 volumio[1194]: Error adding Membership: Error: addMembership EINVAL Nov 02 07:33:32 volumio2 volumio[1194]: info: Shairport-Sync Started Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 07:33:32 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:33 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:33 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:33:33 volumio2 volumio[1194]: info: Listing playlists Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreStateMachine::getQueue Nov 02 07:33:33 volumio2 volumio[1194]: info: CorePlayQueue::getQueue Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:33:33 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:34 volumio2 sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:34 volumio2 sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:34 volumio2 sudo[1344]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:34 volumio2 sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:34 volumio2 sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:34 volumio2 sudo[1347]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:34 volumio2 sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 07:33:34 volumio2 sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:34 volumio2 sudo[1359]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:34 volumio2 volumio[1194]: error: Cannot start Upmpdcli: Error: Command failed: /usr/bin/sudo /bin/systemctl start upmpdcli.service Nov 02 07:33:34 volumio2 volumio[1194]: Failed to start upmpdcli.service: Unit upmpdcli.service failed to load: No such file or directory. Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 07:33:35 volumio2 volumio[1194]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 07:33:35 volumio2 volumio[1194]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 07:33:35 volumio2 volumio[1194]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 07:33:35 volumio2 volumio[1194]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 07:33:35 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:35 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:35 volumio2 volumio[1194]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 07:33:35 volumio2 volumio[1194]: info: Streaming services startup Nov 02 07:33:35 volumio2 volumio[1194]: info: Starting Streaming Daemon Nov 02 07:33:35 volumio2 sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 07:33:35 volumio2 sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:35 volumio2 systemd[1]: Stopping Volumio Streaming Daemon... Nov 02 07:33:35 volumio2 volumio[1194]: info: MyVolumio login type: Token Nov 02 07:33:35 volumio2 volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 07:33:35 volumio2 systemd[1]: Starting Volumio Streaming Daemon... Nov 02 07:33:35 volumio2 systemd[1]: Started Volumio Streaming Daemon. Nov 02 07:33:35 volumio2 sudo[1371]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:35 volumio2 volumio-streaming-daemon[1381]: ############################ Nov 02 07:33:35 volumio2 volumio-streaming-daemon[1381]: # Volumio Streaming Daemon # Nov 02 07:33:35 volumio2 volumio-streaming-daemon[1381]: # Running on port 7777 # Nov 02 07:33:35 volumio2 volumio-streaming-daemon[1381]: ############################ Nov 02 07:33:35 volumio2 volumio-streaming-daemon[1381]: Environment: production Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 07:33:36 volumio2 volumio[1194]: info: Discovery: Getting this device information Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:36 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:36 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 07:33:36 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 07:33:36 volumio2 volumio[1194]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 07:33:36 volumio2 volumio[1194]: info: MYVOLUMIO: Adding device Nov 02 07:33:36 volumio2 volumio[1194]: info: MYVOLUMIO: Evaluating Server Nov 02 07:33:36 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:36] [connect] Successful connection Nov 02 07:33:36 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730532816 101 Nov 02 07:33:37 volumio2 volumio[1194]: info: MyVolumio status changed Nov 02 07:33:37 volumio2 volumio[1194]: info: Streaming services startup Nov 02 07:33:37 volumio2 volumio[1194]: info: Re-Starting Streaming Daemon Nov 02 07:33:37 volumio2 volumio[1194]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 07:33:37 volumio2 volumio[1194]: info: Removing audio output: Nov 02 07:33:37 volumio2 volumio[1194]: info: Stoppping Tunnel 1 Nov 02 07:33:37 volumio2 sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 07:33:37 volumio2 sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:38 volumio2 systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 07:33:38 volumio2 volumio[1194]: info: Discovery: Getting this device information Nov 02 07:33:38 volumio2 sudo[1407]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:38 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:33:38 volumio2 volumio[1194]: info: Setting Geolocation for MyVolumio to as1 Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:38 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:38 volumio2 volumio-streaming-daemon[1381]: Restarting daemon Nov 02 07:33:38 volumio2 volumio-streaming-daemon[1381]: Environment: production Nov 02 07:33:38 volumio2 volumio[1194]: info: Remote SSH Stopped Nov 02 07:33:38 volumio2 volumio-streaming-daemon[1381]: Environment: production Nov 02 07:33:38 volumio2 volumio[1194]: info: Fetching Streaming Services browse cache Nov 02 07:33:38 volumio2 volumio-streaming-daemon[1381]: Environment: production Nov 02 07:33:38 volumio2 volumio[1194]: info: Fetching Streaming Services browse cache Nov 02 07:33:39 volumio2 volumio[1194]: info: Updating MyVolumio device info Nov 02 07:33:39 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:39 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:39 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:39 volumio2 sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:39 volumio2 sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:40 volumio2 sudo[1419]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:40 volumio2 sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:40 volumio2 sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:40 volumio2 sudo[1422]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:40 volumio2 sudo[1437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:40 volumio2 sudo[1437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:40 volumio2 sudo[1437]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:40 volumio2 sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:40 volumio2 sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:40 volumio2 sudo[1440]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 02 07:33:41 volumio2 volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:33:41 volumio2 volumio[1194]: info: Listing playlists Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 02 07:33:41 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 07:33:43 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:33:43 volumio2 volumio[1194]: info: CoreStateMachine::getQueue Nov 02 07:33:43 volumio2 volumio[1194]: info: CorePlayQueue::getQueue Nov 02 07:33:46 volumio2 volumio[1194]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:33:46 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:48 volumio2 volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 07:33:48 volumio2 volumio[1194]: info: CURURI: albums:// Nov 02 07:33:48 volumio2 volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:33:48 volumio2 volumio[1194]: TypeError: Cannot read property 'sendCommand' of undefined Nov 02 07:33:48 volumio2 volumio[1194]: at /volumio/app/plugins/music_service/mpd/index.js:2938:22 Nov 02 07:33:48 volumio2 volumio[1194]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 02 07:33:48 volumio2 volumio[1194]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 02 07:33:48 volumio2 volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:33:49 volumio2 sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 07:32 Nov 02 07:33:49 volumio2 sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:49 volumio2 sudo[1466]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:50 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:50] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 07:33:50 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:50] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 07:33:50 volumio2 systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 02 07:33:50 volumio2 systemd[1]: Unit volumio.service entered failed state. Nov 02 07:33:51 volumio2 systemd[1]: Starting dynamicswap service... Nov 02 07:33:51 volumio2 systemd[1]: Started dynamicswap service. Nov 02 07:33:51 volumio2 systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 02 07:33:51 volumio2 systemd[1]: Starting dynamicswap service... Nov 02 07:33:51 volumio2 systemd[1]: Started dynamicswap service. Nov 02 07:33:51 volumio2 systemd[1]: Stopping Volumio Backend Module... Nov 02 07:33:51 volumio2 systemd[1]: Starting Volumio Backend Module... Nov 02 07:33:51 volumio2 systemd[1]: Started Volumio Backend Module. Nov 02 07:33:53 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:53 volumio2 volumio[1490]: info: ----- Volumio2 ---- Nov 02 07:33:53 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:53 volumio2 volumio[1490]: info: ----- System startup ---- Nov 02 07:33:53 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:53 volumio2 volumio[1490]: info: MYVOLUMIO Environment detected Nov 02 07:33:53 volumio2 volumio[1490]: info: Plugin folders cleanup Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning into folder /volumio/app/plugins/ Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category audio_interface Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category miscellanea Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category music_service Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category plugins.json Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category system_controller Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning category user_interface Nov 02 07:33:53 volumio2 volumio[1490]: info: Scanning into folder /data/plugins/ Nov 02 07:33:53 volumio2 volumio[1490]: info: Plugin folders cleanup completed Nov 02 07:33:53 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:53 volumio2 volumio[1490]: info: ----- Core plugins startup ---- Nov 02 07:33:53 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:53 volumio2 volumio[1490]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 07:33:53 volumio2 volumio[1490]: info: Adding plugin upnp to MyMusic Plugins Nov 02 07:33:53 volumio2 volumio[1490]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 07:33:53 volumio2 volumio[1490]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 07:33:53 volumio2 volumio[1490]: info: Loading plugins from folder /data/plugins/ Nov 02 07:33:53 volumio2 volumio[1490]: info: Loading plugin "system"... Nov 02 07:33:53 volumio2 volumio[1490]: info: Loading plugin "appearance"... Nov 02 07:33:55 volumio2 volumio-remote-updater[712]: [2024-11-02 07:33:55] [connect] Successful connection Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "network"... Nov 02 07:33:56 volumio2 volumio[1490]: info: Refreshing Cached IP Addresses Nov 02 07:33:56 volumio2 sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "services"... Nov 02 07:33:56 volumio2 sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "alsa_controller"... Nov 02 07:33:56 volumio2 sudo[1529]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:56 volumio2 sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:33:56 volumio2 sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:33:56 volumio2 sudo[1533]: pam_unix(sudo:session): session closed for user root Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "wizard"... Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "volumio_command_line_client"... Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "upnp"... Nov 02 07:33:56 volumio2 volumio[1490]: info: [1730532836274] Starting Upmpd Daemon Nov 02 07:33:56 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "my_music"... Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "mpd"... Nov 02 07:33:56 volumio2 volumio[1490]: info: Loading plugin "upnp_browser"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "networkfs"... Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "alarm-clock"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "airplay_emulation"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Starting Shairport Sync Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "last_100"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "webradio"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "i2s_dacs"... Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "volumiodiscovery"... Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** For more information see Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:57 volumio2 node[1490]: *** WARNING *** For more information see Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** For more information see Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 07:33:57 volumio2 volumio[1490]: *** WARNING *** For more information see Nov 02 07:33:57 volumio2 volumio[1490]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 07:33:57 volumio2 volumio[1490]: Discovery: StartAdv! undefined Nov 02 07:33:57 volumio2 volumio[1490]: Discovery: Started advertising... Volumio2 - undefined Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "outputs"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "albumart"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Plugin example_plugin is not enabled Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "inputs"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "updater_comm"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Plugin mpdemulation is not enabled Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "rest_api"... Nov 02 07:33:57 volumio2 volumio[1490]: info: Loading plugin "websocket"... Nov 02 07:33:57 volumio2 volumio[1490]: info: ___________ START PLUGINS ___________ Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:57 volumio2 volumio[1490]: info: [1730532837886] CoreMusicLibrary::Adding element Media Servers Nov 02 07:33:57 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:57 volumio2 volumio[1490]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:58 volumio2 volumio[1490]: Forking 3 albumart workers Nov 02 07:33:58 volumio2 volumio[1490]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:58 volumio2 volumio[1490]: info: [1730532838009] CoreMusicLibrary::Adding element Last_100 Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 07:33:58 volumio2 volumio[1490]: info: [1730532838014] CoreMusicLibrary::Adding element Webradio Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:33:58 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:58 volumio2 volumio[1490]: info: ----- MyVolumio plugins startup ---- Nov 02 07:33:58 volumio2 volumio[1490]: info: ------------------------------------------- Nov 02 07:33:58 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 07:33:58 volumio2 volumio[1490]: info: Loading i18n strings for locale ja Nov 02 07:33:58 volumio2 volumio[1490]: Updating browse sources language Nov 02 07:33:58 volumio2 volumio[1490]: Cannot find translation for sourceMedia Servers Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::initPlayerControls Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 07:33:58 volumio2 volumio[1490]: info: BOOT COMPLETED Nov 02 07:33:58 volumio2 volumio[1490]: [Metrics] CommandRouter: 5s 92.07ms Nov 02 07:33:58 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:33:58 volumio2 kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Nov 02 07:33:58 volumio2 volumio[1490]: Starting albumart workers Nov 02 07:33:58 volumio2 volumio[1490]: Starting albumart workers Nov 02 07:33:58 volumio2 volumio[1490]: Starting albumart workers Nov 02 07:34:03 volumio2 volumio[1490]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::Close All Modals sent Nov 02 07:34:03 volumio2 volumio[1490]: Express server listening on port 3000 Nov 02 07:34:03 volumio2 volumio[1490]: [Metrics] WebUI: 10s 887.44ms Nov 02 07:34:03 volumio2 volumio[1490]: Volumio Calling Home Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreStateMachine::resetVolumioState Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreStateMachine::getcurrentVolume Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreStateMachine::pushState Nov 02 07:34:03 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPushState Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 07:34:03 volumio2 volumio[1490]: info: Setting Device type: Raspberry PI Nov 02 07:34:03 volumio2 volumio[1490]: error: Cannot initialize MPD Connection: MPD is not running Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 07:34:03 volumio2 volumio[1490]: info: Discovery: Getting this device information Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:03 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:34:03 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConf Nov 02 07:34:04 volumio2 volumio[1490]: info: Cannot read play queue from file Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:04 volumio2 volumio[1490]: info: Volumio called home Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreStateMachine::setRepeat null single undefined Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreStateMachine::pushState Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPushState Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreStateMachine::setRandom null Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreStateMachine::pushState Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPushState Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:34:04 volumio2 volumio[1490]: info: Starting Shairport Sync Nov 02 07:34:04 volumio2 volumio[1490]: info: Starting Shairport Sync Nov 02 07:34:04 volumio2 sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:34:04 volumio2 sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:04 volumio2 sudo[1623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 07:34:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:34:04 volumio2 sudo[1623]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:04 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:34:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:34:04 volumio2 sudo[1620]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 07:34:04 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreStateMachine::getQueue Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getQueue Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 02 07:34:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 07:34:04 volumio2 sudo[1623]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 07:34:04 volumio2 volumio[1490]: Discovery: adding 1be6ef9c-369c-41f2-a1d1-f7f1a7a8ae41 Nov 02 07:34:04 volumio2 volumio[1490]: info: mDNS: Found device Volumio2 Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:04 volumio2 volumio[1490]: info: Shairport-Sync Started Nov 02 07:34:04 volumio2 volumio[1490]: Error adding Membership: Error: addMembership EINVAL Nov 02 07:34:04 volumio2 volumio[1490]: info: Shairport-Sync Started Nov 02 07:34:04 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:04 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:05 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreStateMachine::getQueue Nov 02 07:34:05 volumio2 volumio[1490]: info: CorePlayQueue::getQueue Nov 02 07:34:05 volumio2 volumio[1490]: info: Listing playlists Nov 02 07:34:05 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:34:06 volumio2 sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 07:34:06 volumio2 sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:06 volumio2 sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 07:34:06 volumio2 sudo[1656]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:06 volumio2 sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:06 volumio2 sudo[1659]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:06 volumio2 sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 07:34:06 volumio2 sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:06 volumio2 sudo[1673]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:06 volumio2 volumio[1490]: error: Cannot start Upmpdcli: Error: Command failed: /usr/bin/sudo /bin/systemctl start upmpdcli.service Nov 02 07:34:06 volumio2 volumio[1490]: Failed to start upmpdcli.service: Unit upmpdcli.service failed to load: No such file or directory. Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 07:34:07 volumio2 volumio[1490]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 07:34:07 volumio2 volumio[1490]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 07:34:07 volumio2 volumio[1490]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 07:34:07 volumio2 volumio[1490]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 07:34:07 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:07 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:07 volumio2 volumio[1490]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 07:34:07 volumio2 volumio[1490]: info: Streaming services startup Nov 02 07:34:07 volumio2 volumio[1490]: info: Starting Streaming Daemon Nov 02 07:34:07 volumio2 sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 07:34:07 volumio2 sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:07 volumio2 systemd[1]: Stopping Volumio Streaming Daemon... Nov 02 07:34:07 volumio2 systemd[1]: Starting Volumio Streaming Daemon... Nov 02 07:34:07 volumio2 systemd[1]: Started Volumio Streaming Daemon. Nov 02 07:34:07 volumio2 sudo[1683]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:07 volumio2 volumio[1490]: info: MyVolumio login type: Token Nov 02 07:34:07 volumio2 volumio[1490]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 07:34:07 volumio2 volumio-streaming-daemon[1693]: ############################ Nov 02 07:34:07 volumio2 volumio-streaming-daemon[1693]: # Volumio Streaming Daemon # Nov 02 07:34:07 volumio2 volumio-streaming-daemon[1693]: # Running on port 7777 # Nov 02 07:34:07 volumio2 volumio-streaming-daemon[1693]: ############################ Nov 02 07:34:07 volumio2 volumio-streaming-daemon[1693]: Environment: production Nov 02 07:34:08 volumio2 volumio-remote-updater[712]: [2024-11-02 07:34:08] [connect] Successful connection Nov 02 07:34:08 volumio2 volumio-remote-updater[712]: [2024-11-02 07:34:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730532848 101 Nov 02 07:34:08 volumio2 volumio[1490]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 07:34:08 volumio2 volumio[1490]: info: MYVOLUMIO: Adding device Nov 02 07:34:08 volumio2 volumio[1490]: info: MYVOLUMIO: Evaluating Server Nov 02 07:34:08 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 07:34:09 volumio2 volumio[1490]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 02 07:34:09 volumio2 volumio[1490]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Nov 02 07:34:09 volumio2 volumio[1490]: info: Received Get System Version Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetState Nov 02 07:34:09 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 07:34:09 volumio2 volumio[1490]: info: Listing playlists Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetQueue Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreStateMachine::getQueue Nov 02 07:34:09 volumio2 volumio[1490]: info: CorePlayQueue::getQueue Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 07:34:09 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 07:34:09 volumio2 volumio[1490]: info: MyVolumio status changed Nov 02 07:34:09 volumio2 volumio[1490]: info: Streaming services startup Nov 02 07:34:09 volumio2 volumio[1490]: info: Re-Starting Streaming Daemon Nov 02 07:34:09 volumio2 volumio[1490]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 07:34:09 volumio2 volumio[1490]: info: Removing audio output: Nov 02 07:34:09 volumio2 volumio[1490]: info: Stoppping Tunnel 1 Nov 02 07:34:09 volumio2 volumio-streaming-daemon[1693]: Restarting daemon Nov 02 07:34:09 volumio2 sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 07:34:09 volumio2 volumio-streaming-daemon[1693]: Environment: production Nov 02 07:34:09 volumio2 sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 07:34:09 volumio2 systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 02 07:34:09 volumio2 sudo[1724]: pam_unix(sudo:session): session closed for user root Nov 02 07:34:09 volumio2 volumio[1490]: info: Remote SSH Stopped Nov 02 07:34:10 volumio2 volumio[1490]: info: Setting Geolocation for MyVolumio to as1 Nov 02 07:34:10 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:10 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:10 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:10 volumio2 volumio-streaming-daemon[1693]: Environment: production Nov 02 07:34:10 volumio2 volumio[1490]: info: Fetching Streaming Services browse cache Nov 02 07:34:10 volumio2 volumio-streaming-daemon[1693]: Environment: production Nov 02 07:34:10 volumio2 volumio[1490]: info: Fetching Streaming Services browse cache Nov 02 07:34:11 volumio2 volumio[1490]: info: Updating MyVolumio device info Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPlay Nov 02 07:34:11 volumio2 volumio[1490]: verbose: UNSET VOLATILE Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreStateMachine::play index undefined Nov 02 07:34:11 volumio2 volumio[1490]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 07:34:11 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 0 Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreCommandRouter::volumioNext Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreStateMachine::next Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreStateMachine::stop Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 07:34:13 volumio2 volumio[1490]: verbose: UNSET VOLATILE Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreStateMachine::play index undefined Nov 02 07:34:13 volumio2 volumio[1490]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 07:34:13 volumio2 volumio[1490]: info: CorePlayQueue::getTrack 1 Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreStateMachine::ClearQueue Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreStateMachine::stop Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 07:34:14 volumio2 volumio[1490]: verbose: UNSET VOLATILE Nov 02 07:34:14 volumio2 volumio[1490]: info: CorePlayQueue::clearPlayQueue Nov 02 07:34:14 volumio2 volumio[1490]: info: CorePlayQueue::saveQueue Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPushState Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreCommandRouter::volumioPushQueue Nov 02 07:34:14 volumio2 volumio[1490]: info: CoreStateMachine::addQueueItems Nov 02 07:34:14 volumio2 volumio[1490]: info: CorePlayQueue::addQueueItems Nov 02 07:34:14 volumio2 volumio[1490]: info: Adding Item to queue: mnt/USB/A54F-F297/闡提宗祀 ～ Offering to The Sukhavati/08 亡き王女の為のセプテット ～ Ascending Into Nau.aif Nov 02 07:34:14 volumio2 volumio[1490]: info: Exploding uri mnt/USB/A54F-F297/闡提宗祀 ～ Offering to The Sukhavati/08 亡き王女の為のセプテット ～ Ascending Into Nau.aif in service mpd Nov 02 07:34:14 volumio2 volumio[1490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:34:14 volumio2 volumio[1490]: TypeError: Cannot read property 'then' of undefined Nov 02 07:34:14 volumio2 volumio[1490]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2479:19) Nov 02 07:34:14 volumio2 volumio[1490]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2277:21) Nov 02 07:34:14 volumio2 volumio[1490]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1283:16) Nov 02 07:34:14 volumio2 volumio[1490]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:144:48) Nov 02 07:34:14 volumio2 volumio[1490]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:238:25) Nov 02 07:34:14 volumio2 volumio[1490]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:476:23) Nov 02 07:34:14 volumio2 volumio[1490]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:452:17) Nov 02 07:34:14 volumio2 volumio[1490]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:106:35) Nov 02 07:34:14 volumio2 volumio[1490]: at emitTwo (events.js:126:13) Nov 02 07:34:14 volumio2 volumio[1490]: at Socket.emit (events.js:214:7) Nov 02 07:34:14 volumio2 volumio[1490]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 02 07:34:14 volumio2 volumio[1490]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Nov 02 07:34:14 volumio2 volumio[1490]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 02 07:34:14 volumio2 volumio[1490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 07:34:15 volumio2 sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 07:33 Nov 02 07:34:15 volumio2 sudo[1741]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"