-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2024-10-18 19:53:22 CEST. -- Oct 18 19:52:00 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:01 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:01 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:05 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:05 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:07 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:09 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:09 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:13 margules-magenta kernel: EXT4-fs (mmcblk0p3): error count since last fsck: 428 Oct 18 19:52:13 margules-magenta kernel: EXT4-fs (mmcblk0p3): initial error at time 1711365088: ext4_lookup:1855: inode 20020218 Oct 18 19:52:13 margules-magenta kernel: EXT4-fs (mmcblk0p3): last error at time 1729273836: ext4_lookup:1855: inode 20020227 Oct 18 19:52:13 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:13 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:15 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:17 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:17 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:21 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:21 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:22 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:25 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:25 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:29 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:29 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:30 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:33 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:33 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:37 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:37 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:37 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:41 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:41 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:44 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:45 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:45 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:49 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:49 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:52 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:52:53 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 33 Oct 18 19:52:53 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 34 Oct 18 19:52:53 margules-magenta volumio[2001]: info: Prefetching next song Oct 18 19:52:53 margules-magenta volumio[2001]: info: [1729273973099] ControllerQobuz::prefetch Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/277462889" Oct 18 19:52:53 margules-magenta volumio[2001]: STREAMING PROXY: Handling url /?data=qobuz://song/277462889 Oct 18 19:52:53 margules-magenta volumio[2001]: info: Executing endpoint getStreamUrlqobuz Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:53 margules-magenta volumio[2001]: info: getStreamUrl took 601 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=1516519&eid=277462889&fmt=27&profile=raw&app_id=539451548&cid=2256463&etsp=1729277573&hmac=cteWqtXDDW2v4bjolIWTOGsCFo8 Oct 18 19:52:53 margules-magenta volumio[2001]: STREAMING PROXY: Response: 200, length: 222569875 Oct 18 19:52:53 margules-magenta mpd[2169]: exception: No such playlist Oct 18 19:52:53 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/277462889" Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces system playlist update Oct 18 19:52:53 margules-magenta volumio[2001]: info: Ignoring MPD Status Update Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/277462889" took 2 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 18 19:52:53 margules-magenta volumio[2001]: STREAMING PROXY: Client dropped request, destroying Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces system playlist update Oct 18 19:52:53 margules-magenta volumio[2001]: info: Ignoring MPD Status Update Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces system playlist update Oct 18 19:52:53 margules-magenta volumio[2001]: info: Ignoring MPD Status Update Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces state update: options Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 6ms Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand consume 1 took 4 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::getState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 4ms Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 3ms Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces state update: options Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::getState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:52:53 margules-magenta volumio[2001]: info: Oct 18 19:52:53 margules-magenta volumio[2001]: ---------------------------- MPD announces state update: options Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::getState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand status Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand status took 3 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand status took 2 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand status took 2 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseState Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseTrackInfo Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseTrackInfo Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: ControllerMpd::parseTrackInfo Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::servicePushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 33 Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":117,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"277462890","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/277462890","trackType":"qobuz"} Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: CURRENT POSITION 33 Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState stateService pause Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::volumioPushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::servicePushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 33 Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":117,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"277462890","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/277462890","trackType":"qobuz"} Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: CURRENT POSITION 33 Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState stateService pause Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::volumioPushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output Oct 18 19:52:53 margules-magenta volumio[2001]: info: ControllerMpd::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::servicePushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 33 Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":117,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"277462890","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/277462890","trackType":"qobuz"} Oct 18 19:52:53 margules-magenta volumio[2001]: verbose: CURRENT POSITION 33 Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState stateService pause Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::syncState currentStatus stop Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreStateMachine::pushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: CoreCommandRouter::volumioPushState Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:52:53 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 37ms Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 36ms Oct 18 19:52:53 margules-magenta volumio[2001]: info: ------------------------------ 35ms Oct 18 19:52:57 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:52:57 margules-magenta mpd[2169]: exception: No database Oct 18 19:52:57 margules-magenta volumio[2001]: info: CoreStateMachine::startPlaybackTimer Oct 18 19:52:57 margules-magenta volumio[2001]: info: CorePlayQueue::getTrack 34 Oct 18 19:52:58 margules-magenta volumio[2001]: info: CoreStateMachine::pushState Oct 18 19:52:58 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 18 19:52:58 margules-magenta volumio[2001]: info: CoreCommandRouter::volumioPushState Oct 18 19:52:58 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output update for this device Oct 18 19:52:58 margules-magenta volumio[2001]: info: MRS: Pushing multiroomSync output Oct 18 19:52:59 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:53:01 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:01 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:05 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:05 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:07 margules-magenta wpa_supplicant[762]: wlan0: Failed to initiate sched scan Oct 18 19:53:09 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:09 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:13 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:13 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:14 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="DECO50-4" Oct 18 19:53:14 margules-magenta wpa_supplicant[762]: wlan0: Trying to associate with SSID 'DECO50-4' Oct 18 19:53:17 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:17 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:20 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=1c:61:b4:79:34:83 status_code=16 Oct 18 19:53:20 margules-magenta wpa_supplicant[762]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="DECO50-4" auth_failures=6 duration=143 reason=CONN_FAILED Oct 18 19:53:21 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 18 19:53:21 margules-magenta mpd[2169]: exception: No database Oct 18 19:53:22 margules-magenta volumio[2001]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Oct 18 19:53:22 margules-magenta sudo[2506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/NAS_CASA Oct 18 19:53:22 margules-magenta sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 19:53:22 margules-magenta sudo[2506]: pam_unix(sudo:session): session closed for user root Oct 18 19:53:22 margules-magenta volumio[2001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:53:22 margules-magenta volumio[2001]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/NAS_CASA' Oct 18 19:53:22 margules-magenta volumio[2001]: at Object.rmdirSync (fs.js:890:10) Oct 18 19:53:22 margules-magenta volumio[2001]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Oct 18 19:53:22 margules-magenta volumio[2001]: at ChildProcess.exithandler (child_process.js:299:7) Oct 18 19:53:22 margules-magenta volumio[2001]: at ChildProcess.emit (events.js:315:20) Oct 18 19:53:22 margules-magenta volumio[2001]: at maybeClose (internal/child_process.js:1048:16) Oct 18 19:53:22 margules-magenta volumio[2001]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Oct 18 19:53:22 margules-magenta volumio[2001]: errno: -16, Oct 18 19:53:22 margules-magenta volumio[2001]: syscall: 'rmdir', Oct 18 19:53:22 margules-magenta volumio[2001]: code: 'EBUSY', Oct 18 19:53:22 margules-magenta volumio[2001]: path: '/mnt/NAS/NAS_CASA' Oct 18 19:53:22 margules-magenta volumio[2001]: } Oct 18 19:53:22 margules-magenta volumio[2001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 18 19:53:22 margules-magenta sudo[2518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-18 19:52 Oct 18 19:53:22 margules-magenta sudo[2518]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="margules" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 25 Mar 2024 12:05:08 PM CET" VOLUMIO_VERSION="3.609" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Margules Magenta" VOLUMIO_VENDOR="Margules" VOLUMIO_MODEL="Margules Magenta" VOLUMIO_HASH="1fe05d36c107355d439846423b3dc483"