-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Wed 2024-10-30 19:30:21 +04. -- Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:17 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:17 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:17 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:17 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:17 volumio-2 volumio[1058]: verbose: New Socket.io Connection to 192.168.0.3:3000 from 192.168.0.4 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:17 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 30 19:29:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 30 19:29:25 volumio-2 ntpd[1032]: Soliciting pool server 2a00:ab00:203:9::1000:6 Oct 30 19:29:26 volumio-2 sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 19:29:26 volumio-2 sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 19:29:26 volumio-2 sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 19:29:26 volumio-2 sudo[1876]: pam_unix(sudo:session): session closed for user root Oct 30 19:29:26 volumio-2 sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 19:29:26 volumio-2 sudo[1879]: pam_unix(sudo:session): session closed for user root Oct 30 19:29:26 volumio-2 volumio[1058]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.4 UA: Mozilla/5.0 (Linux; Android 14; RMX3630 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Total Clients: 5 Oct 30 19:29:27 volumio-2 sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 30 19:29:27 volumio-2 sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 30 19:29:27 volumio-2 sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 19:29:27 volumio-2 sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 19:29:27 volumio-2 sudo[1896]: pam_unix(sudo:session): session closed for user root Oct 30 19:29:27 volumio-2 sudo[1898]: pam_unix(sudo:session): session closed for user root Oct 30 19:29:27 volumio-2 volumio[1058]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.4 UA: Mozilla/5.0 (Linux; Android 14; RMX3630 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Total Clients: 6 Oct 30 19:29:27 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:28 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 19:29:28 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:28 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:28 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:28 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:28 volumio-2 volumio[1058]: info: Listing playlists Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 19:29:28 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 19:29:29 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:29 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:29 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:29 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 30 19:29:31 volumio-2 ntpd[1032]: Soliciting pool server 178.185.245.245 Oct 30 19:29:31 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 19:29:31 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:31 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:31 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:31 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:31 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:31 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:31 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:32 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 19:29:32 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:32 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:32 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:32 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:32 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:32 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:32 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:33 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 30 19:29:33 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:33 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:33 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:33 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:33 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:33 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:33 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:34 volumio-2 ntpd[1032]: Soliciting pool server 162.159.200.123 Oct 30 19:29:37 volumio-2 ntpd[1032]: Soliciting pool server 188.187.62.16 Oct 30 19:29:37 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetQueue Oct 30 19:29:37 volumio-2 volumio[1058]: info: CoreStateMachine::getQueue Oct 30 19:29:37 volumio-2 volumio[1058]: info: CorePlayQueue::getQueue Oct 30 19:29:39 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 19:29:39 volumio-2 volumio[1058]: info: CURURI: music-library/USB/1C12-2733 Oct 30 19:29:39 volumio-2 volumio[1058]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 30 19:29:39 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 30 19:29:46 volumio-2 volumio[1058]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 30 19:29:46 volumio-2 volumio[1058]: info: Received Get System Version Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 19:29:46 volumio-2 volumio[1058]: info: Received Get System Info Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:46 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:46 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:46 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:47 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:29:47 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:29:47 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:29:47 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:29:47 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:47 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand update Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: info: Oct 30 19:29:56 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand update took 5 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: info: Oct 30 19:29:56 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: info: Oct 30 19:29:56 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 8 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 5 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 5 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 4 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 3 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: info: sendMpdCommand status took 2 milliseconds Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatetrue Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: No code Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: No code Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 30 19:29:56 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: No code Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:56 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:56 volumio-2 volumio[1058]: info: ------------------------------ 149ms Oct 30 19:29:56 volumio-2 volumio[1058]: info: ------------------------------ 146ms Oct 30 19:29:56 volumio-2 volumio[1058]: info: ------------------------------ 145ms Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: info: Oct 30 19:29:57 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: info: Oct 30 19:29:57 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 3 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 2 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 2 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 1 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: 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 30 19:29:57 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: No code Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: 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 30 19:29:57 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: No code Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: ------------------------------ 79ms Oct 30 19:29:57 volumio-2 volumio[1058]: info: ------------------------------ 78ms Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: info: Oct 30 19:29:57 volumio-2 volumio[1058]: ---------------------------- MPD announces state update: update Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::getState Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::sendMpdCommand status Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 1 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: info: sendMpdCommand status took 2 milliseconds Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: info: Command Router : Notfying DB Updatefalse Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::Close All Modals sent Oct 30 19:29:57 volumio-2 volumio[1058]: verbose: ControllerMpd::parseState Oct 30 19:29:57 volumio-2 volumio[1058]: info: ControllerMpd::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::servicePushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: 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 30 19:29:57 volumio-2 volumio[1058]: verbose: CURRENT POSITION 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState stateService stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: No code Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreStateMachine::pushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:29:57 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushState Oct 30 19:29:57 volumio-2 volumio[1058]: info: ------------------------------ 40ms Oct 30 19:30:03 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 19:30:03 volumio-2 volumio[1058]: info: CURURI: music-library/NAS Oct 30 19:30:04 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:06 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 19:30:06 volumio-2 volumio[1058]: info: CURURI: music-library/NAS/New_Music Oct 30 19:30:06 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:09 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 19:30:09 volumio-2 volumio[1058]: info: CURURI: music-library/NAS/New_Music/Sari Schorr Oct 30 19:30:09 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:15 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 30 19:30:15 volumio-2 volumio[1058]: info: CURURI: music-library/NAS/New_Music/Sari Schorr/Sari Schorr - A Force Of Nature (2016) Oct 30 19:30:15 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 19:30:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 19:30:17 volumio-2 volumio[1058]: info: Discovery: Getting this device information Oct 30 19:30:17 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioGetState Oct 30 19:30:17 volumio-2 volumio[1058]: info: CorePlayQueue::getTrack 0 Oct 30 19:30:17 volumio-2 volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 19:30:20 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreStateMachine::ClearQueue Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreStateMachine::stop Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 19:30:20 volumio-2 volumio[1058]: info: CorePlayQueue::clearPlayQueue Oct 30 19:30:20 volumio-2 volumio[1058]: info: CorePlayQueue::saveQueue Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreCommandRouter::volumioPushQueue Oct 30 19:30:20 volumio-2 volumio[1058]: info: CoreStateMachine::addQueueItems Oct 30 19:30:20 volumio-2 volumio[1058]: info: CorePlayQueue::addQueueItems Oct 30 19:30:20 volumio-2 volumio[1058]: info: Preload queue cleared Oct 30 19:30:20 volumio-2 volumio[1058]: info: Adding Item to queue: music-library/NAS/New_Music/Sari Schorr/Sari Schorr - A Force Of Nature (2016) Oct 30 19:30:20 volumio-2 volumio[1058]: info: Exploding uri music-library/NAS/New_Music/Sari Schorr/Sari Schorr - A Force Of Nature (2016) in service mpd Oct 30 19:30:20 volumio-2 volumio[1058]: info: ALBUMART /albumart?cacheid=755&web=Sari%20Schorr/A%20Force%20Of%20Nature/extralarge&path=%2Fmnt%2FNAS%2FNew_Music%2FSari%20Schorr%2FSari%20Schorr%20-%20A%20Force%20Of%20Nature%20(2016)%2FSari%20Schorr%20-%20A%20Force%20Of%20Nature.cue&metadata=false Oct 30 19:30:20 volumio-2 volumio[1058]: info: URI /mnt/NAS/New_Music/Sari Schorr/Sari Schorr - A Force Of Nature (2016)/Sari Schorr - A Force Of Nature.cue Oct 30 19:30:20 volumio-2 volumio[1058]: info: ALBUMART /albumart?cacheid=755&web=Sari%20Schorr/A%20Force%20Of%20Nature/extralarge&path=%2Fmnt%2FNAS%2FNew_Music%2FSari%20Schorr%2FSari%20Schorr%20-%20A%20Force%20Of%20Nature%20(2016)%2FSari%20Schorr%20-%20A%20Force%20Of%20Nature.cue&metadata=false Oct 30 19:30:20 volumio-2 volumio[1058]: info: URI /mnt/NAS/New_Music/Sari Schorr/Sari Schorr - A Force Of Nature (2016)/Sari Schorr - A Force Of Nature.cue Oct 30 19:30:20 volumio-2 volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 19:30:20 volumio-2 volumio[1058]: Error: Unable to resolve or reject the same promise twice Oct 30 19:30:20 volumio-2 volumio[1058]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 30 19:30:20 volumio-2 volumio[1058]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 30 19:30:20 volumio-2 volumio[1058]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 30 19:30:20 volumio-2 volumio[1058]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 30 19:30:20 volumio-2 volumio[1058]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 30 19:30:20 volumio-2 volumio[1058]: at Socket.emit (events.js:400:28) Oct 30 19:30:20 volumio-2 volumio[1058]: at addChunk (internal/streams/readable.js:293:12) Oct 30 19:30:20 volumio-2 volumio[1058]: at readableAddChunk (internal/streams/readable.js:263:11) Oct 30 19:30:20 volumio-2 volumio[1058]: at Socket.Readable.push (internal/streams/readable.js:206:10) Oct 30 19:30:20 volumio-2 volumio[1058]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 30 19:30:20 volumio-2 volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 19:30:21 volumio-2 sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 19:29 Oct 30 19:30:21 volumio-2 sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"