-- Logs begin at Thu 2019-02-14 18:11:59 HKT, end at Sun 2024-10-13 12:45:51 HKT. -- Oct 13 12:44:01 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 12:44:01 volumio volumio[1613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 13 12:44:01 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:01 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:04 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 12:44:04 volumio volumio[1613]: info: CURURI: albums:// Oct 13 12:44:04 volumio volumio[1613]: info: listAlbums - loading Albums from cache Oct 13 12:44:04 volumio volumio[1613]: info: Preload queue cleared Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 12:44:06 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:44:07 volumio volumio[1613]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 13 12:44:07 volumio volumio[1613]: info: Completed starting MyVolumio Plugin Oct 13 12:44:07 volumio volumio[1613]: info: BOOT COMPLETED Oct 13 12:44:07 volumio volumio[1613]: [Metrics] CommandRouter: 34s 794.77ms Oct 13 12:44:07 volumio volumio[1613]: info: CoreCommandRouter::volumiosetStartupVolume Oct 13 12:44:07 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:44:07 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:44:07 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:44:07 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:08 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:08 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 12:44:08 volumio volumio[1613]: info: CURURI: music-library Oct 13 12:44:08 volumio volumio[1613]: error: Failed LSINFO: null Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 12:44:08 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 12:44:08 volumio volumio[1613]: info: Preload queue cleared Oct 13 12:44:12 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 Oct 13 12:44:12 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a119e10 00000000 00000000 0e000000 03028001 Oct 13 12:44:18 volumio volumio-remote-updater[705]: No test mode Oct 13 12:44:18 volumio volumio-remote-updater[705]: No alpha test mode Oct 13 12:44:18 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 13 12:44:22 volumio volumio[1613]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 13 12:44:22 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 13 12:44:26 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 12:44:26 volumio volumio[1613]: info: CURURI: music-library Oct 13 12:44:26 volumio volumio[1613]: error: Failed LSINFO: null Oct 13 12:44:26 volumio volumio[1613]: info: Preload queue cleared Oct 13 12:44:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPlay Oct 13 12:44:31 volumio volumio[1613]: info: CoreStateMachine::play index undefined Oct 13 12:44:31 volumio volumio[1613]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 12:44:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:31 volumio volumio[1613]: info: CoreStateMachine::startPlaybackTimer Oct 13 12:44:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:31 volumio volumio[1613]: verbose: ControllerMpd::clearAddPlayTracks USB/74A1-D1A0/Music/无损音乐/(Everything I Do) I Do It for You - Bryan Adams.flac Oct 13 12:44:31 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 12:44:31 volumio volumio[1613]: info: sendMpdCommand stop took 0 milliseconds Oct 13 12:44:31 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 12:44:31 volumio volumio[1613]: info: Oct 13 12:44:31 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:31 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:31 volumio volumio[1613]: info: sendMpdCommand clear took 1 milliseconds Oct 13 12:44:31 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand add "USB/74A1-D1A0/Music/无损音乐/(Everything I Do) I Do It for You - Bryan Adams.flac" Oct 13 12:44:31 volumio volumio[1613]: info: Oct 13 12:44:31 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:31 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:31 volumio volumio[1613]: info: Oct 13 12:44:31 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:31 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:31 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:31 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:31 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:31 volumio volumio[1613]: info: ------------------------------ 4ms Oct 13 12:44:31 volumio volumio[1613]: info: ------------------------------ 4ms Oct 13 12:44:31 volumio volumio[1613]: info: ------------------------------ 3ms Oct 13 12:44:32 volumio volumio[1613]: info: CoreCommandRouter::volumioPlay Oct 13 12:44:32 volumio volumio[1613]: info: CoreStateMachine::play index undefined Oct 13 12:44:32 volumio volumio[1613]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 12:44:32 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:32 volumio volumio[1613]: info: CoreStateMachine::startPlaybackTimer Oct 13 12:44:32 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:32 volumio volumio[1613]: verbose: ControllerMpd::clearAddPlayTracks USB/74A1-D1A0/Music/无损音乐/(Everything I Do) I Do It for You - Bryan Adams.flac Oct 13 12:44:32 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 12:44:32 volumio volumio[1613]: info: sendMpdCommand stop took 0 milliseconds Oct 13 12:44:32 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 12:44:32 volumio volumio[1613]: info: Oct 13 12:44:32 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:32 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:32 volumio volumio[1613]: info: sendMpdCommand clear took 1 milliseconds Oct 13 12:44:32 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand add "USB/74A1-D1A0/Music/无损音乐/(Everything I Do) I Do It for You - Bryan Adams.flac" Oct 13 12:44:32 volumio volumio[1613]: info: Oct 13 12:44:32 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:32 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:32 volumio volumio[1613]: info: Oct 13 12:44:32 volumio volumio[1613]: ---------------------------- MPD announces system playlist update Oct 13 12:44:32 volumio volumio[1613]: info: Ignoring MPD Status Update Oct 13 12:44:32 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:32 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:32 volumio volumio[1613]: error: updateQueue error: null Oct 13 12:44:32 volumio volumio[1613]: info: ------------------------------ 4ms Oct 13 12:44:32 volumio volumio[1613]: info: ------------------------------ 3ms Oct 13 12:44:32 volumio volumio[1613]: info: ------------------------------ 2ms Oct 13 12:44:35 volumio volumio[1613]: verbose: New Socket.io Connection to 192.168.31.21 from 192.168.31.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 13 12:44:36 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:36 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 12:44:36 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:36 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:36 volumio volumio[1613]: info: Listing playlists Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:44:36 volumio volumio[1613]: info: CoreStateMachine::getQueue Oct 13 12:44:36 volumio volumio[1613]: info: CorePlayQueue::getQueue Oct 13 12:44:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 12:44:37 volumio volumio[1613]: verbose: New Socket.io Connection to 192.168.31.21 from 192.168.31.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 13 12:44:37 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:37 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 12:44:37 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:37 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: Listing playlists Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:44:37 volumio volumio[1613]: info: CoreStateMachine::getQueue Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getQueue Oct 13 12:44:37 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:37 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:37 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:37 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:38 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:38 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:38 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:38 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:38 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:38 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:40 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:47 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:47 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:47 volumio volumio[1613]: verbose: New Socket.io Connection to 192.168.31.21:3000 from 192.168.31.211 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:47 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 12:44:47 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 12:44:47 volumio volumio[1613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bryan%20Adams/Live%20At%20Sydney%20Opera%20House/324daea3-67b2-42cc-bcd7-22ee35ca247b.png' Oct 13 12:44:47 volumio volumio[1613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bryan%20Adams/Live%20At%20Sydney%20Opera%20House/324daea3-67b2-42cc-bcd7-22ee35ca247b.png' Oct 13 12:44:50 volumio sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:44:50 volumio sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:44:50 volumio sudo[1877]: pam_unix(sudo:session): session closed for user root Oct 13 12:44:50 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:44:50 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:44:50 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Oct 13 12:44:50 volumio volumio[1613]: verbose: New Socket.io Connection to 192.168.31.21 from 192.168.31.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 12:44:50 volumio sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:44:50 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:44:50 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Oct 13 12:44:50 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:44:50 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:44:50 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Oct 13 12:44:50 volumio volumio[1613]: verbose: New Socket.io Connection to 192.168.31.21 from 192.168.31.211 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:50 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 12:44:50 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:50 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:50 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:50 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:50 volumio volumio[1613]: info: Listing playlists Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 12:44:50 volumio volumio[1613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bryan%20Adams/Live%20At%20Sydney%20Opera%20House/324daea3-67b2-42cc-bcd7-22ee35ca247b.png' Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 12:44:50 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 12:44:51 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:51 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:51 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:51 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:51 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:51 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 12:44:52 volumio volumio[1613]: info: Received Get System Info Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:44:52 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:44:52 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:44:52 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:44:53 volumio volumio[1613]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:44:53 volumio volumio[1613]: info: CoreStateMachine::getQueue Oct 13 12:44:53 volumio volumio[1613]: info: CorePlayQueue::getQueue Oct 13 12:44:58 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 13 12:44:58 volumio volumio[1613]: info: CURURI: music-library Oct 13 12:44:58 volumio volumio[1613]: error: Failed LSINFO: null Oct 13 12:44:58 volumio volumio[1613]: info: Preload queue cleared Oct 13 12:45:00 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 12:45:05 volumio kernel: usb 2-1: USB disconnect, device number 2 Oct 13 12:45:05 volumio volumio[1613]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:1/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Generic_MassStorageClass_000000001538-0:1-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:1-part1 /dev/disk/by-partuuid/48e4e10f-01 /dev/disk/by-uuid/74A1-D1A0","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:1/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"74A1-D1A0","ID_FS_UUID_ENC":"74A1-D1A0","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:1","ID_MODEL":"MassStorageClass","ID_MODEL_ENC":"MassStorageClass","ID_MODEL_ID":"0749","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32768","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"250314752","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"48e4e10f-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"48e4e10f","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:1","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_1","ID_REVISION":"1538","ID_SERIAL":"Generic_MassStorageClass_000000001538-0:1","ID_SERIAL_SHORT":"000000001538","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Generic","ID_VENDOR_ENC":"Generic\\x20","ID_VENDOR_ID":"05e3","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2363","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"5789607"} Oct 13 12:45:09 volumio kernel: usb 2-1: new SuperSpeed USB device number 3 using xhci_hcd Oct 13 12:45:09 volumio kernel: usb 2-1: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.38 Oct 13 12:45:09 volumio kernel: usb 2-1: New USB device strings: Mfr=3, Product=4, SerialNumber=5 Oct 13 12:45:09 volumio kernel: usb 2-1: Product: USB3.0 Card Reader Oct 13 12:45:09 volumio kernel: usb 2-1: Manufacturer: Generic Oct 13 12:45:09 volumio kernel: usb 2-1: SerialNumber: 000000001538 Oct 13 12:45:09 volumio kernel: usb-storage 2-1:1.0: USB Mass Storage device detected Oct 13 12:45:09 volumio kernel: scsi host1: usb-storage 2-1:1.0 Oct 13 12:45:10 volumio kernel: scsi 1:0:0:0: Direct-Access Generic MassStorageClass 1538 PQ: 0 ANSI: 6 Oct 13 12:45:10 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Oct 13 12:45:10 volumio kernel: sd 1:0:0:0: [sda] Media removed, stopped polling Oct 13 12:45:10 volumio kernel: sd 1:0:0:0: [sda] Attached SCSI removable disk Oct 13 12:45:10 volumio kernel: scsi 1:0:0:1: Direct-Access Generic MassStorageClass 1538 PQ: 0 ANSI: 6 Oct 13 12:45:10 volumio kernel: sd 1:0:0:1: Attached scsi generic sg1 type 0 Oct 13 12:45:10 volumio kernel: sd 1:0:0:1: [sdc] 250347520 512-byte logical blocks: (128 GB/119 GiB) Oct 13 12:45:10 volumio kernel: sd 1:0:0:1: [sdc] Write Protect is off Oct 13 12:45:10 volumio kernel: sd 1:0:0:1: [sdc] Mode Sense: 21 00 00 00 Oct 13 12:45:10 volumio kernel: sd 1:0:0:1: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 13 12:45:11 volumio kernel: sdc: sdc1 Oct 13 12:45:11 volumio kernel: sd 1:0:0:1: [sdc] Attached SCSI removable disk Oct 13 12:45:11 volumio volumio[1613]: info: Mounting Device 74A1-D1A0 Oct 13 12:45:11 volumio volumio[1613]: /bin/mkdir: cannot create directory ‘/mnt/USB/74A1-D1A0’: File exists Oct 13 12:45:11 volumio volumio[1613]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/74A1-D1A0" Oct 13 12:45:11 volumio volumio[1613]: /bin/mkdir: cannot create directory ‘/mnt/USB/74A1-D1A0’: File exists Oct 13 12:45:12 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/74A1-D1A0 -o noatime Oct 13 12:45:12 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 12:45:12 volumio mount.exfat[1909]: volume was not unmounted cleanly Oct 13 12:45:12 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Oct 13 12:45:12 volumio volumio[1613]: WARN: volume was not unmounted cleanly. Oct 13 12:45:12 volumio volumio[1613]: fuse: bad mount point `/mnt/USB/74A1-D1A0': Transport endpoint is not connected Oct 13 12:45:12 volumio volumio[1613]: error: Failed to mount 74A1-D1A0: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdc1" "/mnt/USB/74A1-D1A0" -o noatime Oct 13 12:45:12 volumio volumio[1613]: WARN: volume was not unmounted cleanly. Oct 13 12:45:12 volumio volumio[1613]: fuse: bad mount point `/mnt/USB/74A1-D1A0': Transport endpoint is not connected Oct 13 12:45:16 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:45:16 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:45:16 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:45:16 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:45:16 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:16 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: info: Retrieving Cloud Streaming UI Oct 13 12:45:27 volumio volumio[1613]: info: Getting Tidal Cloud Configuration Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: info: Getting Qobuz Cloud Configuration Oct 13 12:45:27 volumio volumio[1613]: info: Asking plugin for UI Config Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: info: Getting Spotify Cloud Configuration Oct 13 12:45:27 volumio volumio[1613]: info: Asking plugin for UI Config Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: info: Saving Spotify Acccount Oct 13 12:45:27 volumio volumio[1613]: info: Got Tidal Cloud Configuration Oct 13 12:45:27 volumio volumio[1613]: info: Got it Oct 13 12:45:27 volumio volumio[1613]: info: Got it Oct 13 12:45:27 volumio volumio[1613]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 12:45:27 volumio volumio[1613]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 12:45:27 volumio volumio[1613]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 12:45:28 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:28 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand update Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand update took 2 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 3 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 1 milliseconds Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: Command Router : Notfying DB Updatetrue Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:30 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: No code Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: ------------------------------ 104ms Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 109 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 109 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 7 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 6 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 6 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 2 milliseconds Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: Command Router : Notfying DB Updatefalse Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: Command Router : Notfying DB Updatefalse Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: Command Router : Notfying DB Updatefalse Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:30 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: No code Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:30 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: No code Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:30 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: No code Oct 13 12:45:30 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:30 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:30 volumio volumio[1613]: info: ------------------------------ 231ms Oct 13 12:45:30 volumio volumio[1613]: info: ------------------------------ 129ms Oct 13 12:45:30 volumio volumio[1613]: info: ------------------------------ 129ms Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: Oct 13 12:45:30 volumio volumio[1613]: ---------------------------- MPD announces state update: update Oct 13 12:45:30 volumio volumio[1613]: info: ControllerMpd::getState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::sendMpdCommand status Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 13 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 12 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 12 milliseconds Oct 13 12:45:30 volumio volumio[1613]: info: sendMpdCommand status took 11 milliseconds Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: info: Command Router : Notfying DB Updatefalse Oct 13 12:45:30 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:30 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:31 volumio volumio[1613]: info: Command Router : Notfying DB Updatefalse Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::Close All Modals sent Oct 13 12:45:31 volumio volumio[1613]: verbose: ControllerMpd::parseState Oct 13 12:45:31 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:31 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: No code Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: ControllerMpd::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::servicePushState Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 13 12:45:31 volumio volumio[1613]: verbose: CURRENT POSITION 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::syncState stateService stop Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::syncState currentStatus stop Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: No code Oct 13 12:45:31 volumio volumio[1613]: info: CoreStateMachine::pushState Oct 13 12:45:31 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:31 volumio volumio[1613]: info: CoreCommandRouter::volumioPushState Oct 13 12:45:31 volumio volumio[1613]: info: ------------------------------ 100ms Oct 13 12:45:31 volumio volumio[1613]: info: ------------------------------ 99ms Oct 13 12:45:31 volumio volumio[1613]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Bryan%20Adams/Live%20At%20Sydney%20Opera%20House/324daea3-67b2-42cc-bcd7-22ee35ca247b.png' Oct 13 12:45:32 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:36 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:40 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:41 volumio volumio[1613]: info: Enabling MyMusic plugin bluetooth Oct 13 12:45:41 volumio volumio[1613]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Oct 13 12:45:41 volumio volumio[1613]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Oct 13 12:45:41 volumio volumio[1613]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Oct 13 12:45:41 volumio volumio[1613]: error: Could not Enable MyMusic Plugin: Error Oct 13 12:45:44 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:46 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:45:46 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:45:46 volumio volumio[1613]: info: Discovery: Getting this device information Oct 13 12:45:46 volumio volumio[1613]: info: CoreCommandRouter::volumioGetState Oct 13 12:45:46 volumio volumio[1613]: info: CorePlayQueue::getTrack 0 Oct 13 12:45:46 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:45:48 volumio volumio[1613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 12:45:51 volumio volumio[1613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:45:51 volumio volumio[1613]: Error: connect ETIMEDOUT 199.59.148.9:443 Oct 13 12:45:51 volumio volumio[1613]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 13 12:45:51 volumio volumio[1613]: errno: -110, Oct 13 12:45:51 volumio volumio[1613]: code: 'ETIMEDOUT', Oct 13 12:45:51 volumio volumio[1613]: syscall: 'connect', Oct 13 12:45:51 volumio volumio[1613]: address: '199.59.148.9', Oct 13 12:45:51 volumio volumio[1613]: port: 443 Oct 13 12:45:51 volumio volumio[1613]: } Oct 13 12:45:51 volumio volumio[1613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:45:51 volumio sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 12:44 Oct 13 12:45:51 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"