-- Logs begin at Mon 2025-03-03 04:23:52 UTC, end at Mon 2025-03-03 04:26:37 UTC. -- Mar 03 04:25:06 volumio systemd[1]: Time has been changed Mar 03 04:25:07 volumio volumio[987]: info: MYVOLUMIO Environment detected Mar 03 04:25:07 volumio volumio[987]: info: Plugin folders cleanup Mar 03 04:25:07 volumio volumio[987]: info: Scanning into folder /volumio/app/plugins/ Mar 03 04:25:07 volumio volumio[987]: info: Scanning category audio_interface Mar 03 04:25:07 volumio volumio[987]: info: Scanning category miscellanea Mar 03 04:25:07 volumio volumio[987]: info: Scanning category music_service Mar 03 04:25:07 volumio volumio[987]: info: Scanning category plugins.json Mar 03 04:25:07 volumio volumio[987]: info: Scanning category system_controller Mar 03 04:25:07 volumio volumio[987]: info: Scanning category user_interface Mar 03 04:25:07 volumio volumio[987]: info: Scanning into folder /data/plugins/ Mar 03 04:25:07 volumio volumio[987]: info: Plugin folders cleanup completed Mar 03 04:25:07 volumio volumio[987]: info: ------------------------------------------- Mar 03 04:25:07 volumio volumio[987]: info: ----- Core plugins startup ---- Mar 03 04:25:07 volumio volumio[987]: info: ------------------------------------------- Mar 03 04:25:07 volumio volumio[987]: info: Loading plugins from folder /volumio/app/plugins/ Mar 03 04:25:07 volumio volumio[987]: info: Adding plugin upnp to MyMusic Plugins Mar 03 04:25:07 volumio volumio[987]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 03 04:25:07 volumio volumio[987]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 03 04:25:07 volumio volumio[987]: info: Loading plugins from folder /data/plugins/ Mar 03 04:25:07 volumio volumio[987]: info: Loading plugin "system"... Mar 03 04:25:07 volumio volumio[987]: info: Loading plugin "appearance"... Mar 03 04:25:09 volumio volumio[987]: info: Loading plugin "network"... Mar 03 04:25:09 volumio volumio[987]: info: Refreshing Cached IP Addresses Mar 03 04:25:09 volumio volumio[987]: info: Loading plugin "services"... Mar 03 04:25:09 volumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 04:25:09 volumio volumio[987]: info: Loading plugin "alsa_controller"... Mar 03 04:25:09 volumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:10 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 04:25:10 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:10 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:10 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "wizard"... Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "volumio_command_line_client"... Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "upnp"... Mar 03 04:25:10 volumio volumio[987]: info: [1740975910127] Starting Upmpd Daemon Mar 03 04:25:10 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "my_music"... Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "mpd"... Mar 03 04:25:10 volumio volumio[987]: info: Loading plugin "upnp_browser"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "networkfs"... Mar 03 04:25:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "alarm-clock"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "airplay_emulation"... Mar 03 04:25:11 volumio volumio[987]: info: Starting Shairport Sync Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "last_100"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "webradio"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "i2s_dacs"... Mar 03 04:25:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "volumiodiscovery"... Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 04:25:11 volumio node[987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 04:25:11 volumio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 04:25:11 volumio node[987]: *** WARNING *** For more information see Mar 03 04:25:11 volumio node[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 04:25:11 volumio node[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 04:25:11 volumio node[987]: *** WARNING *** For more information see Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** For more information see Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 04:25:11 volumio volumio[987]: *** WARNING *** For more information see Mar 03 04:25:11 volumio volumio[987]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 03 04:25:11 volumio volumio[987]: Discovery: StartAdv! undefined Mar 03 04:25:11 volumio volumio[987]: Discovery: Started advertising... Volumio - undefined Mar 03 04:25:11 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "outputs"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "albumart"... Mar 03 04:25:11 volumio volumio[987]: info: Plugin example_plugin is not enabled Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "inputs"... Mar 03 04:25:11 volumio volumio[987]: info: Loading plugin "updater_comm"... Mar 03 04:25:12 volumio volumio[987]: info: Plugin mpdemulation is not enabled Mar 03 04:25:12 volumio volumio[987]: info: Loading plugin "rest_api"... Mar 03 04:25:12 volumio volumio[987]: info: Loading plugin "websocket"... Mar 03 04:25:12 volumio volumio[987]: info: ___________ START PLUGINS ___________ Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 04:25:12 volumio volumio[987]: info: [1740975912116] CoreMusicLibrary::Adding element Media Servers Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 04:25:12 volumio volumio[987]: Forking 3 albumart workers Mar 03 04:25:12 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 04:25:12 volumio volumio[987]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 04:25:12 volumio volumio[987]: info: [1740975912258] CoreMusicLibrary::Adding element Last_100 Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 04:25:12 volumio volumio[987]: info: [1740975912263] CoreMusicLibrary::Adding element Webradio Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 04:25:12 volumio volumio[987]: info: ------------------------------------------- Mar 03 04:25:12 volumio volumio[987]: info: ----- MyVolumio plugins startup ---- Mar 03 04:25:12 volumio volumio[987]: info: ------------------------------------------- Mar 03 04:25:12 volumio volumio[987]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 03 04:25:12 volumio volumio[987]: info: Loading i18n strings for locale zh Mar 03 04:25:12 volumio volumio[987]: Updating browse sources language Mar 03 04:25:12 volumio volumio[987]: Cannot find translation for sourceMedia Servers Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::initPlayerControls Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 04:25:12 volumio volumio[987]: info: BOOT COMPLETED Mar 03 04:25:12 volumio volumio[987]: [Metrics] CommandRouter: 6s 138.95ms Mar 03 04:25:12 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:12 volumio volumio-remote-updater[692]: [2025-03-03 04:25:12] [connect] Successful connection Mar 03 04:25:13 volumio volumio[987]: Starting albumart workers Mar 03 04:25:13 volumio volumio[987]: Starting albumart workers Mar 03 04:25:13 volumio volumio[987]: Starting albumart workers Mar 03 04:25:17 volumio volumio[987]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 03 04:25:17 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Mar 03 04:25:17 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Mar 03 04:25:17 volumio volumio[987]: Express server listening on port 3000 Mar 03 04:25:17 volumio volumio[987]: [Metrics] WebUI: 12s 158.21ms Mar 03 04:25:17 volumio volumio[987]: Volumio Calling Home Mar 03 04:25:17 volumio volumio[987]: info: CoreStateMachine::resetVolumioState Mar 03 04:25:17 volumio volumio[987]: info: CoreStateMachine::getcurrentVolume Mar 03 04:25:17 volumio volumio[987]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 04:25:17 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:25:17 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:17 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:25:17 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:25:18 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:18 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 04:25:18 volumio volumio[987]: info: Setting Device type: Raspberry PI Mar 03 04:25:18 volumio volumio[987]: info: MPD running with PID765 Mar 03 04:25:18 volumio volumio[987]: ,establishing connection Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 03 04:25:19 volumio volumio[987]: info: Reloading queue from file Mar 03 04:25:19 volumio volumio[987]: info: CoreStateMachine::setRepeat null single undefined Mar 03 04:25:19 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:25:19 volumio volumio[987]: info: CoreStateMachine::setRandom null Mar 03 04:25:19 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:25:19 volumio volumio[987]: info: Starting Shairport Sync Mar 03 04:25:19 volumio volumio[987]: info: Starting Shairport Sync Mar 03 04:25:19 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 04:25:19 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:19 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 04:25:19 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:19 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Mar 03 04:25:19 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 04:25:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 04:25:19 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Mar 03 04:25:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 04:25:19 volumio volumio[987]: Discovery: adding 29da5697-79e9-44eb-aa8b-e31e8faf2f6d Mar 03 04:25:19 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:19 volumio volumio[987]: info: mDNS: Found device Volumio Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:19 volumio volumio[987]: info: Shairport-Sync Started Mar 03 04:25:19 volumio volumio[987]: Error adding Membership: Error: addMembership EINVAL Mar 03 04:25:19 volumio volumio[987]: info: Shairport-Sync Started Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Mar 03 04:25:19 volumio volumio[987]: info: CoreStateMachine::getQueue Mar 03 04:25:19 volumio volumio[987]: info: CorePlayQueue::getQueue Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 04:25:19 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 04:25:20 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 04:25:20 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:20 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 04:25:20 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:20 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:20 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:20 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 03 04:25:20 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:20 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Mar 03 04:25:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 03 04:25:20 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:20 volumio volumio[987]: info: Upmpdcli Daemon Started Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 03 04:25:21 volumio volumio[987]: info: Adding plugin bluetooth to MyMusic Plugins Mar 03 04:25:21 volumio volumio[987]: info: Adding plugin cd_controller to MyMusic Plugins Mar 03 04:25:21 volumio volumio[987]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 03 04:25:21 volumio volumio[987]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 03 04:25:21 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 03 04:25:22 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:22 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:22 volumio volumio[987]: info: Starting MyVolumio Remote Streaming Endpoints Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 03 04:25:22 volumio volumio[987]: info: Streaming services startup Mar 03 04:25:22 volumio volumio[987]: info: Starting Streaming Daemon Mar 03 04:25:22 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 04:25:22 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 04:25:22 volumio volumio[987]: info: MyVolumio not started Mar 03 04:25:22 volumio volumio[987]: info: Initializing device activation check Mar 03 04:25:22 volumio systemd[1]: Stopping Volumio Streaming Daemon... Mar 03 04:25:22 volumio systemd[1]: Starting Volumio Streaming Daemon... Mar 03 04:25:22 volumio systemd[1]: Started Volumio Streaming Daemon. Mar 03 04:25:22 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 03 04:25:22 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Mar 03 04:25:22 volumio volumio-streaming-daemon[1195]: ############################ Mar 03 04:25:22 volumio volumio-streaming-daemon[1195]: # Volumio Streaming Daemon # Mar 03 04:25:22 volumio volumio-streaming-daemon[1195]: # Running on port 7777 # Mar 03 04:25:22 volumio volumio-streaming-daemon[1195]: ############################ Mar 03 04:25:22 volumio volumio-streaming-daemon[1195]: Environment: production Mar 03 04:25:23 volumio volumio[987]: info: Volumio called home Mar 03 04:25:25 volumio volumio-streaming-daemon[1195]: Environment: production Mar 03 04:25:25 volumio volumio[987]: info: Fetching Streaming Services browse cache Mar 03 04:25:27 volumio volumio[987]: Discovery: this is already registered, 29da5697-79e9-44eb-aa8b-e31e8faf2f6d Mar 03 04:25:27 volumio volumio[987]: info: mDNS: Found device Volumio Mar 03 04:25:27 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Mar 03 04:25:27 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Mar 03 04:25:27 volumio volumio-remote-updater[692]: [2025-03-03 04:25:27] [connect] Successful connection Mar 03 04:25:27 volumio volumio-remote-updater[692]: [2025-03-03 04:25:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1740975927 101 Mar 03 04:25:30 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 03 04:25:30 volumio volumio[987]: info: CURURI: music-library Mar 03 04:25:30 volumio volumio[987]: info: Listing playlists Mar 03 04:25:31 volumio udisks-glue[689]: error: Timeout Mar 03 04:25:39 volumio systemd[1]: Started Music Player Daemon. Mar 03 04:25:39 volumio systemd[1]: Starting Multi-User System. Mar 03 04:25:39 volumio systemd[1]: Reached target Multi-User System. Mar 03 04:25:39 volumio systemd[1]: Starting Graphical Interface. Mar 03 04:25:39 volumio systemd[1]: Reached target Graphical Interface. Mar 03 04:25:39 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 03 04:25:39 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 03 04:25:39 volumio systemd[1]: Startup finished in 10.427s (kernel) + 48.089s (userspace) = 58.516s. Mar 03 04:25:39 volumio volumio[987]: error: updateQueue error: null Mar 03 04:25:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 04:25:42 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 04:25:43 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 03 04:25:43 volumio volumio[987]: info: CURURI: music-library/USB Mar 03 04:25:45 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 03 04:25:45 volumio volumio[987]: info: CURURI: music-library/USB/音乐歌曲盘1T_ Mar 03 04:25:51 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 03 04:25:51 volumio volumio[987]: info: CURURI: music-library/USB/音乐歌曲盘1T_/24bit歌曲 Mar 03 04:25:59 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 03 04:25:59 volumio volumio[987]: info: CURURI: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲 Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::ClearQueue Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::stop Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 04:26:02 volumio volumio[987]: UNSET VOLATILE Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::clearPlayQueue Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::saveQueue Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::addQueueItems Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::addQueueItems Mar 03 04:26:02 volumio volumio[987]: info: Adding Item to queue: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/李荣浩 - 等着等着就老了.flac Mar 03 04:26:02 volumio volumio[987]: info: Exploding uri music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/李荣浩 - 等着等着就老了.flac in service mpd Mar 03 04:26:02 volumio volumio[987]: info: Adding Item to queue: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/刘德华 - 原谅我 (国语).flac Mar 03 04:26:02 volumio volumio[987]: info: Exploding uri music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/刘德华 - 原谅我 (国语).flac in service mpd Mar 03 04:26:02 volumio volumio[987]: info: Adding Item to queue: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac Mar 03 04:26:02 volumio volumio[987]: info: Exploding uri music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac in service mpd Mar 03 04:26:02 volumio volumio[987]: info: Adding Item to queue: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/那英&王菲 - 岁月.flac Mar 03 04:26:02 volumio volumio[987]: info: Exploding uri music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/那英&王菲 - 岁月.flac in service mpd Mar 03 04:26:02 volumio volumio[987]: info: Adding Item to queue: music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/王菲 - 我和我的祖国.flac Mar 03 04:26:02 volumio volumio[987]: info: Exploding uri music-library/USB/音乐歌曲盘1T_/24bit歌曲/单曲/王菲 - 我和我的祖国.flac in service mpd Mar 03 04:26:02 volumio volumio[987]: info: ALBUMART /albumart?cacheid=241&web=//extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E6%AD%8C%E6%9B%B2%E7%9B%981T_%2F24bit%E6%AD%8C%E6%9B%B2%2F%E5%8D%95%E6%9B%B2%2F%E6%9D%8E%E8%8D%A3%E6%B5%A9%20-%20%E7%AD%89%E7%9D%80%E7%AD%89%E7%9D%80%E5%B0%B1%E8%80%81%E4%BA%86.flac&metadata=false Mar 03 04:26:02 volumio volumio[987]: info: URI /mnt/USB/音乐歌曲盘1T_/24bit歌曲/单曲/李荣浩 - 等着等着就老了.flac Mar 03 04:26:02 volumio volumio[987]: info: ALBUMART /albumart?cacheid=241&web=%E5%88%98%E5%BE%B7%E5%8D%8E/%E5%8E%9F%E8%B0%85%E6%88%91/extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E6%AD%8C%E6%9B%B2%E7%9B%981T_%2F24bit%E6%AD%8C%E6%9B%B2%2F%E5%8D%95%E6%9B%B2%2F%E5%88%98%E5%BE%B7%E5%8D%8E%20-%20%E5%8E%9F%E8%B0%85%E6%88%91%20(%E5%9B%BD%E8%AF%AD).flac&metadata=false Mar 03 04:26:02 volumio volumio[987]: info: URI /mnt/USB/音乐歌曲盘1T_/24bit歌曲/单曲/刘德华 - 原谅我 (国语).flac Mar 03 04:26:02 volumio volumio[987]: info: ALBUMART /albumart?cacheid=241&web=A-Lin/%E5%A4%A9%E8%8B%A5%E6%9C%89%E6%83%85/extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E6%AD%8C%E6%9B%B2%E7%9B%981T_%2F24bit%E6%AD%8C%E6%9B%B2%2F%E5%8D%95%E6%9B%B2%2FA-Lin%20-%20%E5%A4%A9%E8%8B%A5%E6%9C%89%E6%83%85.flac&metadata=false Mar 03 04:26:02 volumio volumio[987]: info: URI /mnt/USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac Mar 03 04:26:02 volumio volumio[987]: info: ALBUMART /albumart?cacheid=241&web=%E7%8E%8B%E8%8F%B2/%E5%B2%81%E6%9C%88/extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E6%AD%8C%E6%9B%B2%E7%9B%981T_%2F24bit%E6%AD%8C%E6%9B%B2%2F%E5%8D%95%E6%9B%B2%2F%E9%82%A3%E8%8B%B1%26%E7%8E%8B%E8%8F%B2%20-%20%E5%B2%81%E6%9C%88.flac&metadata=false Mar 03 04:26:02 volumio volumio[987]: info: URI /mnt/USB/音乐歌曲盘1T_/24bit歌曲/单曲/那英&王菲 - 岁月.flac Mar 03 04:26:02 volumio volumio[987]: info: ALBUMART /albumart?cacheid=241&web=%E7%8E%8B%E8%8F%B2/%E6%88%91%E5%92%8C%E6%88%91%E7%9A%84%E7%A5%96%E5%9B%BD/extralarge&path=%2Fmnt%2FUSB%2F%E9%9F%B3%E4%B9%90%E6%AD%8C%E6%9B%B2%E7%9B%981T_%2F24bit%E6%AD%8C%E6%9B%B2%2F%E5%8D%95%E6%9B%B2%2F%E7%8E%8B%E8%8F%B2%20-%20%E6%88%91%E5%92%8C%E6%88%91%E7%9A%84%E7%A5%96%E5%9B%BD.flac&metadata=false Mar 03 04:26:02 volumio volumio[987]: info: URI /mnt/USB/音乐歌曲盘1T_/24bit歌曲/单曲/王菲 - 我和我的祖国.flac Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::saveQueue Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::updateTrackBlock Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrackBlock Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPlay Mar 03 04:26:02 volumio volumio[987]: UNSET VOLATILE Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::play index 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::stop Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 04:26:02 volumio volumio[987]: UNSET VOLATILE Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::play index undefined Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::startPlaybackTimer Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand stop Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand stop took 7 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand clear Mar 03 04:26:02 volumio volumio[987]: info: Mar 03 04:26:02 volumio volumio[987]: ---------------------------- MPD announces system playlist update Mar 03 04:26:02 volumio volumio[987]: info: Ignoring MPD Status Update Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand clear took 7 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac" Mar 03 04:26:02 volumio volumio[987]: error: updateQueue error: null Mar 03 04:26:02 volumio volumio[987]: info: Mar 03 04:26:02 volumio volumio[987]: ---------------------------- MPD announces system playlist update Mar 03 04:26:02 volumio volumio[987]: info: Ignoring MPD Status Update Mar 03 04:26:02 volumio volumio[987]: info: ------------------------------ 7ms Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand add "USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac" took 5 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand play Mar 03 04:26:02 volumio volumio[987]: info: ------------------------------ 10ms Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand play took 7 milliseconds Mar 03 04:26:02 volumio volumio[987]: info: Mar 03 04:26:02 volumio volumio[987]: ---------------------------- MPD announces state update: player Mar 03 04:26:02 volumio volumio[987]: info: ControllerMpd::getState Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Mar 03 04:26:02 volumio volumio[987]: info: Mar 03 04:26:02 volumio volumio[987]: ---------------------------- MPD announces state update: player Mar 03 04:26:02 volumio volumio[987]: info: ControllerMpd::getState Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand status took 4 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::parseState Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand status took 8 milliseconds Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::parseState Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Mar 03 04:26:02 volumio volumio[987]: info: ControllerMpd::pushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1136 Kbps","isStreaming":false,"title":"天若有情","artist":"A-Lin","album":"天若有情","uri":"USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac","trackType":"flac"} Mar 03 04:26:02 volumio volumio[987]: verbose: CURRENT POSITION 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:26:02 volumio volumio[987]: info: ------------------------------ 38ms Mar 03 04:26:02 volumio volumio[987]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 03 04:26:02 volumio volumio[987]: verbose: ControllerMpd::parseTrackInfo Mar 03 04:26:02 volumio volumio[987]: info: ControllerMpd::pushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::servicePushState Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":257,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1137 Kbps","isStreaming":false,"title":"天若有情","artist":"A-Lin","album":"天若有情","uri":"USB/音乐歌曲盘1T_/24bit歌曲/单曲/A-Lin - 天若有情.flac","trackType":"flac"} Mar 03 04:26:02 volumio volumio[987]: verbose: CURRENT POSITION 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::syncState stateService play Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::syncState currentStatus play Mar 03 04:26:02 volumio volumio[987]: info: Received an update from plugin. extracting info from payload Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:26:02 volumio volumio[987]: info: CoreStateMachine::pushState Mar 03 04:26:02 volumio volumio[987]: info: CorePlayQueue::getTrack 2 Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::volumioPushState Mar 03 04:26:02 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 04:26:02 volumio volumio[987]: info: ------------------------------ 60ms Mar 03 04:26:35 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 04:26:35 volumio volumio[987]: { Error: connect ETIMEDOUT 199.16.158.12:443 Mar 03 04:26:35 volumio volumio[987]: at Object._errnoException (util.js:1022:11) Mar 03 04:26:35 volumio volumio[987]: at _exceptionWithHostPort (util.js:1044:20) Mar 03 04:26:35 volumio volumio[987]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Mar 03 04:26:35 volumio volumio[987]: code: 'ETIMEDOUT', Mar 03 04:26:35 volumio volumio[987]: errno: 'ETIMEDOUT', Mar 03 04:26:35 volumio volumio[987]: syscall: 'connect', Mar 03 04:26:35 volumio volumio[987]: address: '199.16.158.12', Mar 03 04:26:35 volumio volumio[987]: port: 443 } Mar 03 04:26:35 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 04:26:37 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 04:25 Mar 03 04:26:37 volumio sudo[1350]: 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="14acf028cf46278bd3fd3dd02523424a9bc825bf" VOLUMIO_FE_VERSION="74f64fbba9dc8053dc27ed7125863d6ad6533965" VOLUMIO_BE_VERSION="0a6008ded215bf284d5d4cd9c4df3333cb320899" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 24 17:39:58 CEST 2021" VOLUMIO_VERSION="2.882" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1a98a01eef5aa5927a5874c2d3c1b0dc"