-- Logs begin at Thu 2024-03-07 16:05:52 UTC, end at Thu 2024-03-07 16:08:18 UTC. -- Mar 07 16:07:14 usbridge kernel: usb 1-1.3: new high-speed USB device number 5 using xhci-hcd Mar 07 16:07:14 usbridge kernel: usb 1-1.3: Device not responding to setup address. Mar 07 16:07:14 usbridge kernel: usb 1-1.3: Device not responding to setup address. Mar 07 16:07:15 usbridge kernel: usb 1-1.3: device not accepting address 5, error -71 Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 16:07:31 usbridge volumio[706]: info: Discovery: Getting this device information Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:07:31 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:07:31 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 07 16:07:31 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 07 16:07:34 usbridge sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:07:34 usbridge sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:07:34 usbridge sudo[1281]: pam_unix(sudo:session): session closed for user root Mar 07 16:07:34 usbridge sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:07:34 usbridge sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:07:34 usbridge sudo[1283]: pam_unix(sudo:session): session closed for user root Mar 07 16:07:34 usbridge sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 16:07:34 usbridge sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:07:34 usbridge sudo[1293]: pam_unix(sudo:session): session closed for user root Mar 07 16:07:34 usbridge sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 16:07:34 usbridge sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 16:07:34 usbridge sudo[1295]: pam_unix(sudo:session): session closed for user root Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:07:35 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 07 16:07:35 usbridge volumio[706]: info: Listing playlists Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 07 16:07:35 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 16:07:36 usbridge volumio[706]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 16:07:36 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 16:07:36 usbridge volumio[706]: info: CoreCommandRouter::volumioGetQueue Mar 07 16:07:36 usbridge volumio[706]: info: CoreStateMachine::getQueue Mar 07 16:07:36 usbridge volumio[706]: info: CorePlayQueue::getQueue Mar 07 16:07:36 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 16:07:37 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 07 16:07:37 usbridge volumio[706]: info: CURURI: music-library Mar 07 16:07:37 usbridge volumio[706]: error: Failed LSINFO: null Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 07 16:07:40 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 07 16:07:44 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 16:07:46 usbridge volumio[706]: info: Discovery: Getting this device information Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:07:46 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:07:46 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:07:49 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 07 16:07:49 usbridge volumio[706]: info: CURURI: music-library Mar 07 16:07:49 usbridge volumio[706]: error: Failed LSINFO: null Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 16:07:51 usbridge volumio[706]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 16:07:52 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:07:52 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand update Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:53 usbridge volumio[706]: info: Mar 07 16:07:53 usbridge volumio[706]: ---------------------------- MPD announces state update: update Mar 07 16:07:53 usbridge volumio[706]: info: sendMpdCommand update took 16 milliseconds Mar 07 16:07:53 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:53 usbridge volumio[706]: info: Mar 07 16:07:53 usbridge volumio[706]: ---------------------------- MPD announces state update: update Mar 07 16:07:53 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:53 usbridge volumio[706]: info: sendMpdCommand status took 18 milliseconds Mar 07 16:07:53 usbridge volumio[706]: info: sendMpdCommand status took 10 milliseconds Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:53 usbridge volumio[706]: info: Command Router : Notfying DB Updatefalse Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:53 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: 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} Mar 07 16:07:53 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::syncState stateService stop Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus stop Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: No code Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: ------------------------------ 111ms Mar 07 16:07:53 usbridge volumio[706]: info: sendMpdCommand status took 103 milliseconds Mar 07 16:07:53 usbridge volumio[706]: info: sendMpdCommand status took 99 milliseconds Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:53 usbridge volumio[706]: info: Command Router : Notfying DB Updatefalse Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:07:53 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:53 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: 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} Mar 07 16:07:53 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::syncState stateService stop Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus stop Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: No code Mar 07 16:07:53 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:53 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:53 usbridge volumio[706]: info: ------------------------------ 174ms Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 16:07:53 usbridge volumio[706]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 07 16:07:53 usbridge volumio[706]: info: Received Get System Version Mar 07 16:07:53 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand rescan Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:56 usbridge volumio[706]: info: Mar 07 16:07:56 usbridge volumio[706]: ---------------------------- MPD announces state update: update Mar 07 16:07:56 usbridge volumio[706]: info: sendMpdCommand rescan took 12 milliseconds Mar 07 16:07:56 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:56 usbridge volumio[706]: info: Mar 07 16:07:56 usbridge volumio[706]: ---------------------------- MPD announces state update: update Mar 07 16:07:56 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:07:56 usbridge volumio[706]: info: sendMpdCommand status took 20 milliseconds Mar 07 16:07:56 usbridge volumio[706]: info: sendMpdCommand status took 14 milliseconds Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:56 usbridge volumio[706]: info: Command Router : Notfying DB Updatefalse Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:56 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: 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} Mar 07 16:07:56 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::syncState stateService stop Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus stop Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: No code Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: ------------------------------ 95ms Mar 07 16:07:56 usbridge volumio[706]: info: sendMpdCommand status took 84 milliseconds Mar 07 16:07:56 usbridge volumio[706]: info: sendMpdCommand status took 81 milliseconds Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:56 usbridge volumio[706]: info: Command Router : Notfying DB Updatefalse Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::Close All Modals sent Mar 07 16:07:56 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:07:56 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: 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} Mar 07 16:07:56 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::syncState stateService stop Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus stop Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: No code Mar 07 16:07:56 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:07:56 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:07:56 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:07:56 usbridge volumio[706]: info: ------------------------------ 165ms Mar 07 16:08:00 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:08:04 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:08:08 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:08:12 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:08:14 usbridge volumio[706]: info: Clearing queue after UPNP request Mar 07 16:08:14 usbridge volumio[706]: info: CoreStateMachine::ClearQueue Mar 07 16:08:14 usbridge volumio[706]: info: CoreStateMachine::stop Mar 07 16:08:14 usbridge volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 07 16:08:14 usbridge volumio[706]: verbose: UNSET VOLATILE Mar 07 16:08:14 usbridge volumio[706]: info: CorePlayQueue::clearPlayQueue Mar 07 16:08:14 usbridge volumio[706]: info: CorePlayQueue::saveQueue Mar 07 16:08:14 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:14 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:08:14 usbridge volumio[706]: info: CoreCommandRouter::volumioPushQueue Mar 07 16:08:14 usbridge volumio[706]: info: Mar 07 16:08:14 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:14 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:14 usbridge volumio[706]: error: updateQueue error: null Mar 07 16:08:14 usbridge volumio[706]: info: ------------------------------ 6ms Mar 07 16:08:14 usbridge volumio[706]: info: Starting UPNP Playback Mar 07 16:08:14 usbridge volumio[706]: info: Preparing playback through UPNP Mar 07 16:08:14 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:14 usbridge volumio[706]: info: CorePlayQueue::getTrack 0 Mar 07 16:08:14 usbridge volumio[706]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 9ms Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 6ms Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 13ms Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 6ms Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 6ms Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces system playlist update Mar 07 16:08:15 usbridge volumio[706]: info: Ignoring MPD Status Update Mar 07 16:08:15 usbridge volumio[706]: info: ------------------------------ 6ms Mar 07 16:08:15 usbridge volumio[706]: info: Mar 07 16:08:15 usbridge volumio[706]: ---------------------------- MPD announces state update: player Mar 07 16:08:15 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:08:15 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:08:15 usbridge kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4 Mar 07 16:08:15 usbridge kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4 Mar 07 16:08:15 usbridge volumio[706]: info: sendMpdCommand status took 28 milliseconds Mar 07 16:08:15 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:08:15 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 16:08:16 usbridge volumio[706]: info: Mar 07 16:08:16 usbridge volumio[706]: ---------------------------- MPD announces state update: player Mar 07 16:08:16 usbridge volumio[706]: info: ControllerMpd::getState Mar 07 16:08:16 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand status Mar 07 16:08:16 usbridge volumio[706]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 07 16:08:16 usbridge volumio[706]: info: sendMpdCommand status took 7 milliseconds Mar 07 16:08:16 usbridge volumio[706]: verbose: ControllerMpd::parseTrackInfo Mar 07 16:08:16 usbridge volumio[706]: verbose: ControllerMpd::parseState Mar 07 16:08:16 usbridge volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 16:08:16 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:08:16 usbridge volumio[706]: verbose: In UPNP mode Mar 07 16:08:16 usbridge volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:11176/qobuz/5381610.flac","trackType":"flac"} Mar 07 16:08:16 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::syncState stateService play Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus stop Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:08:16 usbridge kernel: TX ERROR status: 0x007e0000 Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:08:16 usbridge volumio[706]: info: ------------------------------ 103ms Mar 07 16:08:16 usbridge volumio[706]: info: sendMpdCommand playlistinfo took 45 milliseconds Mar 07 16:08:16 usbridge volumio[706]: verbose: ControllerMpd::parseTrackInfo Mar 07 16:08:16 usbridge volumio[706]: info: ControllerMpd::pushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::servicePushState Mar 07 16:08:16 usbridge volumio[706]: verbose: In UPNP mode Mar 07 16:08:16 usbridge volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":605,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"475 Kbps","isStreaming":false,"title":"Poem of Chinese Drum","artist":"Hok-man Yim","album":"Master of Chinese Percussion","uri":"http://192.168.10.87:11176/qobuz/5381610.flac","trackType":"flac"} Mar 07 16:08:16 usbridge volumio[706]: verbose: CURRENT POSITION 0 Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::syncState stateService play Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::syncState currentStatus play Mar 07 16:08:16 usbridge volumio[706]: info: Received an update from plugin. extracting info from payload Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::pushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::volumioPushState Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 16:08:16 usbridge volumio[706]: info: ------------------------------ 117ms Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::volumioGetQueue Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::getQueue Mar 07 16:08:16 usbridge volumio[706]: info: CorePlayQueue::getQueue Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::volumioGetQueue Mar 07 16:08:16 usbridge volumio[706]: info: CoreStateMachine::getQueue Mar 07 16:08:16 usbridge volumio[706]: info: CorePlayQueue::getQueue Mar 07 16:08:16 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 16:08:16 usbridge kernel: ------------[ cut here ]------------ Mar 07 16:08:16 usbridge kernel: WARNING: at net/sched/sch_generic.c:255 dev_watchdog+0x260/0x26c() Mar 07 16:08:16 usbridge kernel: NETDEV WATCHDOG: eth0 (owl-ethernet): transmit queue 0 timed out Mar 07 16:08:16 usbridge kernel: Modules linked in: bnep snd_usb_audio nfsd snd_hwdep snd_usbmidi_lib auth_rpcgss oid_registry nfs_acl pvrsrvkm drm ethernet spidev owl_camera atc260x_irkeypad ctp_ft5x06 atc260x_cap_gauge gsensor_mir3da gsensor_bma222 gsensor_stk8313 autofs4 nls_cp437 squashfs overlayfs Mar 07 16:08:16 usbridge kernel: CPU: 3 PID: 0 Comm: swapper/3 Tainted: G W 3.10.38 #4 Mar 07 16:08:16 usbridge kernel: [] (unwind_backtrace+0x0/0x134) from [] (show_stack+0x24/0x34) Mar 07 16:08:16 usbridge kernel: [] (show_stack+0x24/0x34) from [] (warn_slowpath_common+0x54/0x6c) Mar 07 16:08:16 usbridge kernel: [] (warn_slowpath_common+0x54/0x6c) from [] (warn_slowpath_fmt+0x30/0x40) Mar 07 16:08:16 usbridge kernel: [] (warn_slowpath_fmt+0x30/0x40) from [] (dev_watchdog+0x260/0x26c) Mar 07 16:08:16 usbridge kernel: [] (dev_watchdog+0x260/0x26c) from [] (call_timer_fn+0x40/0x17c) Mar 07 16:08:16 usbridge kernel: [] (call_timer_fn+0x40/0x17c) from [] (run_timer_softirq+0x1bc/0x2b8) Mar 07 16:08:16 usbridge kernel: [] (run_timer_softirq+0x1bc/0x2b8) from [] (__do_softirq+0x140/0x308) Mar 07 16:08:16 usbridge kernel: [] (__do_softirq+0x140/0x308) from [] (do_softirq+0x50/0x58) Mar 07 16:08:16 usbridge kernel: [] (do_softirq+0x50/0x58) from [] (irq_exit+0x9c/0xd0) Mar 07 16:08:16 usbridge kernel: [] (irq_exit+0x9c/0xd0) from [] (handle_IRQ+0x44/0x90) Mar 07 16:08:16 usbridge kernel: [] (handle_IRQ+0x44/0x90) from [] (gic_handle_irq+0x2c/0x5c) Mar 07 16:08:16 usbridge kernel: [] (gic_handle_irq+0x2c/0x5c) from [] (__irq_svc+0x40/0x70) Mar 07 16:08:16 usbridge kernel: Exception stack(0xe2a15f88 to 0xe2a15fd0) Mar 07 16:08:16 usbridge kernel: 5f80: ffffffed 00a53000 c0ca36e4 00000000 e2a14000 e2a14018 Mar 07 16:08:16 usbridge kernel: 5fa0: c0d429ac c0ca1ed4 c07eb8e0 e2a14000 00000000 e2a14000 00000000 e2a15fd0 Mar 07 16:08:16 usbridge kernel: 5fc0: c0010024 c001001c 600f0013 ffffffff Mar 07 16:08:16 usbridge kernel: [] (__irq_svc+0x40/0x70) from [] (arch_cpu_idle+0x2c/0x38) Mar 07 16:08:16 usbridge kernel: [] (arch_cpu_idle+0x2c/0x38) from [] (cpu_startup_entry+0x138/0x270) Mar 07 16:08:16 usbridge kernel: [] (cpu_startup_entry+0x138/0x270) from [<0000821c>] (0x821c) Mar 07 16:08:16 usbridge kernel: ---[ end trace aa911d22f3aa99be ]--- Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 16:08:17 usbridge volumio[706]: info: Discovery: Getting this device information Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::volumioGetState Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:08:17 usbridge volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConf Mar 07 16:08:17 usbridge volumio[706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 16:08:17 usbridge volumio[706]: { Error: certificate is not yet valid Mar 07 16:08:17 usbridge volumio[706]: at TLSSocket. (_tls_wrap.js:1105:38) Mar 07 16:08:17 usbridge volumio[706]: at emitNone (events.js:106:13) Mar 07 16:08:17 usbridge volumio[706]: at TLSSocket.emit (events.js:208:7) Mar 07 16:08:17 usbridge volumio[706]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Mar 07 16:08:17 usbridge volumio[706]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Mar 07 16:08:17 usbridge volumio[706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 16:08:18 usbridge sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-07 16:07 Mar 07 16:08:18 usbridge sudo[1455]: 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="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 12:12:00 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="sparky" VOLUMIO_HASH="1507c65028120e16c14994c066bc5dd4"