-- Logs begin at Wed 2022-07-13 14:55:24 UTC, end at Fri 2026-04-03 07:50:01 UTC. -- Apr 03 07:49:00 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:00 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:00 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:00 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:00 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:00 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:00 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:00 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"1","powerShutdown":"0"} Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , enablePCInput Apr 03 07:49:00 musical-fidelity volumio[19698]: info: Unsetting Volatile before setting PC Input Apr 03 07:49:00 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:00 musical-fidelity volumio[19698]: info: Not executing Bluetooth detach command Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:00 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::stPlaybackTimer Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::updateTrackBlock Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrackBlock Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::serviceStop Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::serviceStop Apr 03 07:49:00 musical-fidelity volumio[19698]: info: ControllerMpd::stop Apr 03 07:49:00 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:00 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 25 milliseconds Apr 03 07:49:00 musical-fidelity kernel: usb 1-1.4: USB disconnect, device number 13 Apr 03 07:49:00 musical-fidelity kernel: usb 1-1.4.2: USB disconnect, device number 14 Apr 03 07:49:00 musical-fidelity volumio[19698]: info: Notifying PC INPUT Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:00 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivepc_input Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:01 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:01 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:01 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:01 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:01 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:01 musical-fidelity udisks-glue[716]: Device file /dev/sda1 unmounted from /media/ECC22863C22833EC Apr 03 07:49:01 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:01 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:01 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:01 musical-fidelity udisks-glue[716]: Device file /dev/sda1 removed Apr 03 07:49:01 musical-fidelity udisks-glue[716]: Device file /dev/sda removed Apr 03 07:49:01 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:01 musical-fidelity udisks-glue[716]: Updating DB (#28) ... Apr 03 07:49:01 musical-fidelity udisks-glue[716]: volume: n/a repeat: off random: off single: off consume: off Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:01 musical-fidelity udisks-glue[716]: Updating DB (#28) ... Apr 03 07:49:01 musical-fidelity udisks-glue[716]: volume: n/a repeat: off random: off single: off consume: off Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 4 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity kernel: buffer_io_error: 8 callbacks suppressed Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767913, async page read Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767914, async page read Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767914, async page read Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767914, async page read Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767914, async page read Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767913, async page read Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x1: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x2: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x2: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x2: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x2: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read vcn 0x1: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Failed to read index block: Input/output error Apr 03 07:49:01 musical-fidelity kernel: Buffer I/O error on dev sda1, logical block 767912, async page read Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatetrue Apr 03 07:49:01 musical-fidelity volumio[19698]: info: MPD Database updated - AlbumList cache refreshed Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: database Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 71 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:01 musical-fidelity ntfs-3g[18943]: Unmounting /dev/sda1 () Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: 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} Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus play Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::play index undefined Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::startPlaybackTimer Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::clearAddPlayTracks USB/ECC22863C22833EC/王挑选音乐/人声/02 Track - No16.wav Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 112ms Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 58 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 55 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 55 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 21 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatefalse Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::Close All Modals sent Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: 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} Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: No code Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: 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} Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: No code Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 158ms Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 160ms Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces system playlist update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Ignoring MPD Status Update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 152 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand status took 149 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand clear took 107 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatefalse Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::Close All Modals sent Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand add "USB/ECC22863C22833EC/王挑选音乐/人声/02 Track - No16.wav" Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: 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} Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus play Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::play index undefined Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::startPlaybackTimer Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::clearAddPlayTracks USB/ECC22863C22833EC/王挑选音乐/人声/02 Track - No16.wav Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 227ms Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:01 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:01 musical-fidelity volumio[19698]: error: updateQueue error: null Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 118ms Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 43 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Apr 03 07:49:01 musical-fidelity volumio[19698]: ---------------------------- MPD announces system playlist update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: Ignoring MPD Status Update Apr 03 07:49:01 musical-fidelity volumio[19698]: info: sendMpdCommand clear took 3 milliseconds Apr 03 07:49:01 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand add "USB/ECC22863C22833EC/王挑选音乐/人声/02 Track - No16.wav" Apr 03 07:49:01 musical-fidelity volumio[19698]: error: updateQueue error: null Apr 03 07:49:01 musical-fidelity volumio[19698]: info: ------------------------------ 4ms Apr 03 07:49:02 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:02 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:02 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:02 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:02 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:02 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:02 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:02 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:03 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:03 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:03 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:03 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:03 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:03 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:03 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:03 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:04 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:04 musical-fidelity volumio[19698]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:04 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:04 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:04 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:04 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:04 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:04 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:04 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:04 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:04 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:04 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:04 musical-fidelity kernel: usb 1-1.4: new high-speed USB device number 15 using dwc_otg Apr 03 07:49:04 musical-fidelity kernel: usb 1-1.4: New USB device found, idVendor=0424, idProduct=2412 Apr 03 07:49:04 musical-fidelity kernel: usb 1-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Apr 03 07:49:04 musical-fidelity kernel: hub 1-1.4:1.0: USB hub found Apr 03 07:49:04 musical-fidelity kernel: hub 1-1.4:1.0: 2 ports detected Apr 03 07:49:04 musical-fidelity kernel: usb 1-1.4.2: new high-speed USB device number 16 using dwc_otg Apr 03 07:49:05 musical-fidelity kernel: usb 1-1.4.2: New USB device found, idVendor=0781, idProduct=55a3 Apr 03 07:49:05 musical-fidelity kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 03 07:49:05 musical-fidelity kernel: usb 1-1.4.2: Product: SanDisk 3.2Gen1 Apr 03 07:49:05 musical-fidelity kernel: usb 1-1.4.2: Manufacturer: USB Apr 03 07:49:05 musical-fidelity kernel: usb 1-1.4.2: SerialNumber: 0101a3c3154436de49fc9f36b35cd08f7d07c7adbcc55694094a998d8aa13340efb00000000000000000000054594f6000031a00a355810771a89983 Apr 03 07:49:05 musical-fidelity kernel: usb-storage 1-1.4.2:1.0: USB Mass Storage device detected Apr 03 07:49:05 musical-fidelity kernel: scsi host0: usb-storage 1-1.4.2:1.0 Apr 03 07:49:05 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:05 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:05 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:05 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:05 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:05 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:05 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:05 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:06 musical-fidelity kernel: scsi 0:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: [sda] 489160704 512-byte logical blocks: (250 GB/233 GiB) Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 03 07:49:06 musical-fidelity kernel: sda: sda1 Apr 03 07:49:06 musical-fidelity kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Device file /dev/sda inserted Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Device file /dev/sda1 inserted Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Trying to automount /dev/sda1... Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Apr 03 07:49:06 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Apr 03 07:49:06 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 7 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 5 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatefalse Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::Close All Modals sent Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: 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} Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: No code Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ------------------------------ 95ms Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 112 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 109 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatefalse Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::Close All Modals sent Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: 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} Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: No code Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ------------------------------ 206ms Apr 03 07:49:06 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:06 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:06 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:06 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:06 musical-fidelity ntfs-3g[23585]: Version 2014.2.15AR.2 integrated FUSE 28 Apr 03 07:49:06 musical-fidelity ntfs-3g[23585]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Apr 03 07:49:06 musical-fidelity ntfs-3g[23585]: Cmdline options: rw,nodev,nosuid,noatime,uid=1000,gid=1000,dmask=0077,fmask=0177,dmask=0000,fmask=0000,uhelper=udisks Apr 03 07:49:06 musical-fidelity ntfs-3g[23585]: Mount options: rw,nodev,nosuid,uhelper=udisks,allow_other,nonempty,noatime,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 03 07:49:06 musical-fidelity ntfs-3g[23585]: Global ownership and permissions enforced, configuration type 7 Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Successfully automounted /dev/sda1 at /media/ECC22863C22833EC Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Device file /dev/sda1 mounted at /media/ECC22863C22833EC Apr 03 07:49:06 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:06 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:06 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity udisks-glue[716]: Updating DB (#31) ... Apr 03 07:49:06 musical-fidelity udisks-glue[716]: volume: n/a repeat: off random: off single: off consume: off Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Apr 03 07:49:06 musical-fidelity volumio[19698]: ---------------------------- MPD announces state update: update Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::getState Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand status Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 6 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: Command Router : Notfying DB Updatetrue Apr 03 07:49:06 musical-fidelity volumio[19698]: info: sendMpdCommand status took 32 milliseconds Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: ControllerMpd::parseState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ControllerMpd::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: 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} Apr 03 07:49:06 musical-fidelity volumio[19698]: verbose: CURRENT POSITION 0 Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState stateService stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::syncState currentStatus stop Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: No code Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:06 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:06 musical-fidelity volumio[19698]: info: ------------------------------ 144ms Apr 03 07:49:07 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:07 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:07 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:07 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:07 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:07 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:07 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:07 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:08 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:08 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:08 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:08 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:08 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:08 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:08 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:08 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:09 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:09 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:09 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:09 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:09 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:09 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:09 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:09 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:11 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:11 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:11 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:11 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:11 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:11 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:11 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:11 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 03 07:49:11 musical-fidelity volumio[19698]: info: Discovery: Getting this device information Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioGetState Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 03 07:49:11 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: system , getConf Apr 03 07:49:12 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:12 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:12 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:12 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:12 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:12 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:12 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:12 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:13 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:13 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:13 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:13 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:13 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:13 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:13 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:13 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:14 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:14 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:14 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:14 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:14 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:14 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:14 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:14 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:15 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 07:49:15 musical-fidelity volumio[19698]: info: CURURI: music-library Apr 03 07:49:15 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:15 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:15 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:15 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:15 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:15 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:15 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:15 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:16 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:16 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:16 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:16 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:16 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:16 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:16 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:16 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:16 musical-fidelity dhcpd[2527]: DHCPDISCOVER from 5c:f3:70:c6:37:33 (musical-fidelity) via wlan0 Apr 03 07:49:17 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:17 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:17 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:17 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:17 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:17 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:17 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:17 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:17 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 07:49:17 musical-fidelity volumio[19698]: info: CURURI: playlists Apr 03 07:49:17 musical-fidelity volumio[19698]: info: Listing playlists Apr 03 07:49:17 musical-fidelity dhcpd[2527]: DHCPOFFER on 192.168.211.3 to 5c:f3:70:c6:37:33 (musical-fidelity) via wlan0 Apr 03 07:49:18 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:18 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:18 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:18 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:18 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:18 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:18 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:18 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:19 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:19 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:19 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:19 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:19 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:19 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:19 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:19 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:20 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:20 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:20 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:20 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:20 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:20 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:20 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:20 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:21 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:21 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:21 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:21 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:21 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:21 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:21 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:21 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:22 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:23 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:23 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:23 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:23 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:23 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:23 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:23 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:24 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:24 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:24 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:24 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:24 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:24 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:24 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:24 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:24 musical-fidelity sudo[23819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 07:49:24 musical-fidelity sudo[23821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 07:49:24 musical-fidelity sudo[23819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:24 musical-fidelity sudo[23821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:24 musical-fidelity sudo[23821]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:24 musical-fidelity sudo[23819]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:25 musical-fidelity sudo[23837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 07:49:25 musical-fidelity sudo[23837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:25 musical-fidelity sudo[23837]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:25 musical-fidelity sudo[23840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 07:49:25 musical-fidelity sudo[23840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:25 musical-fidelity sudo[23840]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:25 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:25 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:25 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:25 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:25 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:25 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:25 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:25 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioGetVisibleSources Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioGetState Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioGetQueue Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreStateMachine::getQueue Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CorePlayQueue::getQueue Apr 03 07:49:25 musical-fidelity volumio[19698]: info: Listing playlists Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 03 07:49:25 musical-fidelity volumio[19698]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 03 07:49:25 musical-fidelity volumio[19698]: error: Could not retrieve plugin audio_interface outputs Apr 03 07:49:25 musical-fidelity volumio[19698]: error: WARNING: No Audio Output plugin found Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 03 07:49:25 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 03 07:49:26 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:26 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:26 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:26 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:26 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:26 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:26 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:26 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:27 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:27 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:27 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:27 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:27 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:27 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:27 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:27 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:28 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:28 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:28 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:28 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:28 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:28 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:28 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:28 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:29 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:29 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:29 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:29 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:29 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:29 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:29 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:29 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:30 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:49:30 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:30 musical-fidelity sudo[23922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:49:30 musical-fidelity sudo[23922]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:30 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:30 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:30 musical-fidelity sudo[23922]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Shairport-Sync Started Apr 03 07:49:30 musical-fidelity volumio[19698]: Error adding Membership: Error: addMembership EINVAL Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:30 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:30 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:30 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:30 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:30 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:30 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:30 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopShairportSync Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:30 musical-fidelity sudo[23953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:30 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:30 musical-fidelity sudo[23953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:30 musical-fidelity systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:30 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:30 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:30 musical-fidelity sudo[23953]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:30 musical-fidelity volumio[19698]: info: Shairport-Sync Stopped Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:31 musical-fidelity volumio[19698]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:31 musical-fidelity volumio[19698]: STOPPING SHAIRPORT WITH UNSET VOLATILE Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:31 musical-fidelity sudo[23966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 03 07:49:31 musical-fidelity sudo[23966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:31 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:31 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:31 musical-fidelity volumio[19698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:49:31 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:31 musical-fidelity sudo[23966]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:31 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:31 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:31 musical-fidelity volumio[19698]: info: Shairport-Sync Stopped Apr 03 07:49:31 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:31 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:31 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:31 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:31 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:31 musical-fidelity sudo[23980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:49:31 musical-fidelity sudo[23980]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:31 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:31 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:31 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:31 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:31 musical-fidelity sudo[23980]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:31 musical-fidelity volumio[19698]: info: Shairport-Sync Started Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:31 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:32 musical-fidelity volumio[19698]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received airplay_emulation Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:32 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:32 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:32 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:32 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:32 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:32 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:32 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:32 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:32 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:32 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:33 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:33 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:33 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:33 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:33 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:33 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:34 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:34 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:35 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:35 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:35 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:35 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:35 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:35 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:35 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:35 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:36 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:36 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:36 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:36 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:36 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:36 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:36 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:36 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:37 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:37 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:37 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:37 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:37 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:37 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:37 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:37 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:38 musical-fidelity volumio[19698]: STOPPING SHAIRPORT WITH UNSET VOLATILE Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::stPlaybackTimer Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::updateTrackBlock Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrackBlock Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::serviceStop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::serviceStop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: ControllerMpd::stop Apr 03 07:49:38 musical-fidelity sudo[24073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 03 07:49:38 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:38 musical-fidelity sudo[24073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:38 musical-fidelity systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:38 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:38 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:49:38 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:38 musical-fidelity sudo[24073]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Updating push state because input changed Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:38 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:38 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 121 milliseconds Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Shairport-Sync Stopped Apr 03 07:49:38 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:38 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:38 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:38 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:38 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:38 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:38 musical-fidelity sudo[24093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:49:38 musical-fidelity sudo[24093]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:38 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:38 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:38 musical-fidelity sudo[24093]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Shairport-Sync Started Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:38 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:38 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:39 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:39 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:39 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:39 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:39 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:39 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:39 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:39 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:39 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:39 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:39 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:39 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:40 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:40 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:40 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:40 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:40 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:40 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:40 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:40 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:40 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:40 musical-fidelity volumio[19698]: STOPPING SHAIRPORT WITH UNSET VOLATILE Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:40 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::stPlaybackTimer Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::updateTrackBlock Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrackBlock Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::serviceStop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::serviceStop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: ControllerMpd::stop Apr 03 07:49:40 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:40 musical-fidelity sudo[24138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 03 07:49:40 musical-fidelity sudo[24138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:40 musical-fidelity systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:40 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:40 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:40 musical-fidelity volumio[19698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:49:40 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:40 musical-fidelity sudo[24138]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:40 musical-fidelity volumio[19698]: info: Updating push state because input changed Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:40 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:40 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:40 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 104 milliseconds Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:40 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:40 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:40 musical-fidelity volumio[19698]: info: Shairport-Sync Stopped Apr 03 07:49:41 musical-fidelity sudo[24154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:49:41 musical-fidelity sudo[24154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:41 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:41 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:41 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:41 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:41 musical-fidelity sudo[24154]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:41 musical-fidelity volumio[19698]: info: Shairport-Sync Started Apr 03 07:49:41 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:41 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:41 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:41 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:41 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:41 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:41 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:41 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:41 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:41 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:41 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:41 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:41 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:42 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:42 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:42 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:42 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:42 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:42 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:42 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:42 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:43 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:43 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:43 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:43 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:43 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:43 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:44 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:44 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , getAirplayStatus Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSyncOnDemand Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:44 musical-fidelity volumio[19698]: STOPPING SHAIRPORT WITH UNSET VOLATILE Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::stPlaybackTimer Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::updateTrackBlock Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrackBlock Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::serviceStop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CorePlayQueue::getTrack 0 Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::serviceStop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: ControllerMpd::stop Apr 03 07:49:44 musical-fidelity sudo[24209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Apr 03 07:49:44 musical-fidelity sudo[24209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:44 musical-fidelity volumio[19698]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 07:49:44 musical-fidelity systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:44 musical-fidelity systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:44 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:44 musical-fidelity volumio[19698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:49:44 musical-fidelity volumio[19698]: aplay: device_list:268: no soundcards found... Apr 03 07:49:44 musical-fidelity volumio[19698]: info: Updating push state because input changed Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActivempd Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:44 musical-fidelity sudo[24209]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:44 musical-fidelity volumio[19698]: info: sendMpdCommand stop took 96 milliseconds Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioStop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::stop Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: verbose: UNSET VOLATILE Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: info: Shairport-Sync Stopped Apr 03 07:49:44 musical-fidelity sudo[24222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:49:44 musical-fidelity sudo[24222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:49:44 musical-fidelity systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:44 musical-fidelity systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing BT Meta: TypeError: msg.body[0].indexOf is not a function Apr 03 07:49:44 musical-fidelity sudo[24222]: pam_unix(sudo:session): session closed for user root Apr 03 07:49:44 musical-fidelity volumio[19698]: info: Shairport-Sync Started Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: Error in parsing play status: TypeError: Cannot read property '0' of undefined Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumiosetSourceActiveairplay_emulation Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:49:44 musical-fidelity volumio[19698]: info: Received Push USB{"activeRoute":"0","powerShutdown":"0"} Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , setPCInputReturn Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , registerLastUsbStatus Apr 03 07:49:44 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: inputs , disablePCInput Apr 03 07:49:45 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:45 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:45 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:45 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:45 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:45 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:45 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:45 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:45 musical-fidelity volumio[19698]: info: CoreCommandRouter::servicePushState Apr 03 07:49:45 musical-fidelity volumio[19698]: info: CoreStateMachine::pushState Apr 03 07:49:45 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioPushState Apr 03 07:49:45 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:49:46 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:46 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:46 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:46 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:46 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:46 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:46 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:46 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:47 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:47 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:47 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:47 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:47 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:47 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:47 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:47 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:48 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:48 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:48 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:48 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:48 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:48 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:48 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:48 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:48 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 07:49:49 musical-fidelity volumio[19698]: info: CURURI: music-library Apr 03 07:49:49 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:49 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:49 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:49 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:49 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:49 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:49 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:49 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:50 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:50 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:50 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:50 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:50 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:50 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:50 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:50 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:51 musical-fidelity volumio[19698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 07:49:51 musical-fidelity volumio[19698]: info: CURURI: music-library/USB Apr 03 07:49:51 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:51 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:51 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:51 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:51 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:51 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:51 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:51 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:52 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:52 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:52 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:52 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:52 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:52 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:52 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:52 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:53 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:53 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:53 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:53 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:53 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:53 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:53 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:53 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:54 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:54 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:54 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:54 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:54 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:54 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:54 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:54 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:55 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:55 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:55 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:55 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:55 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:55 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:56 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:56 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:57 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:49:57 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:49:57 musical-fidelity volumio[19698]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:nodev,0 Apr 03 07:49:57 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:57 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:57 musical-fidelity volumio[19698]: Failure: Module initialization failed Apr 03 07:49:57 musical-fidelity volumio[19698]: No protocol specified Apr 03 07:49:57 musical-fidelity volumio[19698]: xcb_connection_has_error() returned true Apr 03 07:49:57 musical-fidelity volumio[19698]: info: CoreCommandRouter::volumioAddQueueItems Apr 03 07:49:57 musical-fidelity volumio[19698]: info: CoreStateMachine::addQueueItems Apr 03 07:49:57 musical-fidelity volumio[19698]: info: CorePlayQueue::addQueueItems Apr 03 07:49:57 musical-fidelity volumio[19698]: info: Adding Item to queue: music-library/USB/ECC22863C22833EC Apr 03 07:49:57 musical-fidelity volumio[19698]: info: Exploding uri music-library/USB/ECC22863C22833EC in service mpd Apr 03 07:49:59 musical-fidelity volumio[19698]: info: Enabling Bluetooth Audio Output Apr 03 07:50:00 musical-fidelity pulseaudio[20047]: Failed to load module "module-alsa-sink" (argument: "device=plughw:nodev,0"): initialization failed. Apr 03 07:50:00 musical-fidelity volumio[19698]: info: ALBUMART /albumart?cacheid=285&web=//extralarge&path=%2Fmnt%2FUSB%2FECC22863C22833EC%2F%E3%80%90%E5%AE%98%E6%96%B9%E6%B5%8B%E8%AF%95%E7%89%87%E3%80%91%2FAuro3D%2F2012%202L%20Trondheim%20Solistene%EF%BC%9ASOUVENIR%EF%BC%88Auro3D%209.1%EF%BC%9B36G%EF%BC%89%2FBDMV%2FSTREAM%2F00000.m2ts&metadata=false Apr 03 07:50:00 musical-fidelity volumio[19698]: info: URI /mnt/USB/ECC22863C22833EC/【官方测试片】/Auro3D/2012 2L Trondheim Solistene：SOUVENIR（Auro3D 9.1；36G）/BDMV/STREAM/00000.m2ts Apr 03 07:50:00 musical-fidelity volumio[19698]: info: ALBUMART /albumart?cacheid=285&web=//extralarge&path=%2Fmnt%2FUSB%2FECC22863C22833EC%2F%E3%80%90%E5%AE%98%E6%96%B9%E6%B5%8B%E8%AF%95%E7%89%87%E3%80%91%2FAuro3D%2F2012%202L%20Trondheim%20Solistene%EF%BC%9ASOUVENIR%EF%BC%88Auro3D%209.1%EF%BC%9B36G%EF%BC%89%2FBDMV%2FSTREAM%2F00001.m2ts&metadata=false Apr 03 07:50:00 musical-fidelity volumio[19698]: info: URI /mnt/USB/ECC22863C22833EC/【官方测试片】/Auro3D/2012 2L Trondheim Solistene：SOUVENIR（Auro3D 9.1；36G）/BDMV/STREAM/00001.m2ts Apr 03 07:50:00 musical-fidelity volumio[19698]: info: ALBUMART /albumart?cacheid=285&web=TrondheimSolistene/SOUVENIR%20part%20II/extralarge&path=%2Fmnt%2FUSB%2FECC22863C22833EC%2F%E3%80%90%E5%AE%98%E6%96%B9%E6%B5%8B%E8%AF%95%E7%89%87%E3%80%91%2FAuro3D%2F2012%202L%20Trondheim%20Solistene%EF%BC%9ASOUVENIR%EF%BC%88Auro3D%209.1%EF%BC%9B36G%EF%BC%89%2FROM_DATA%2Fdownloads%2Fflac_192%2Falbum_partII_flac_192.zip&metadata=false Apr 03 07:50:00 musical-fidelity volumio[19698]: info: URI /mnt/USB/ECC22863C22833EC/【官方测试片】/Auro3D/2012 2L Trondheim Solistene：SOUVENIR（Auro3D 9.1；36G）/ROM_DATA/downloads/flac_192/album_partII_flac_192.zip Apr 03 07:50:00 musical-fidelity volumio[19698]: info: ALBUMART /albumart?cacheid=285&web=TrondheimSolistene/SOUVENIR%20part%20II/extralarge&path=%2Fmnt%2FUSB%2FECC22863C22833EC%2F%E3%80%90%E5%AE%98%E6%96%B9%E6%B5%8B%E8%AF%95%E7%89%87%E3%80%91%2FAuro3D%2F2012%202L%20Trondheim%20Solistene%EF%BC%9ASOUVENIR%EF%BC%88Auro3D%209.1%EF%BC%9B36G%EF%BC%89%2FROM_DATA%2Fdownloads%2Fflac_192%2Falbum_partII_flac_192.zip&metadata=false Apr 03 07:50:00 musical-fidelity volumio[19698]: info: URI /mnt/USB/ECC22863C22833EC/【官方测试片】/Auro3D/2012 2L Trondheim Solistene：SOUVENIR（Auro3D 9.1；36G）/ROM_DATA/downloads/flac_192/album_partII_flac_192.zip Apr 03 07:50:00 musical-fidelity volumio[19698]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 07:50:00 musical-fidelity volumio[19698]: Error: Unable to resolve or reject the same promise twice Apr 03 07:50:00 musical-fidelity volumio[19698]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 03 07:50:00 musical-fidelity volumio[19698]: at /volumio/app/plugins/music_service/mpd/index.js:2520:21 Apr 03 07:50:00 musical-fidelity volumio[19698]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 03 07:50:00 musical-fidelity volumio[19698]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 03 07:50:00 musical-fidelity volumio[19698]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 03 07:50:00 musical-fidelity volumio[19698]: at emitOne (events.js:116:13) Apr 03 07:50:00 musical-fidelity volumio[19698]: at Socket.emit (events.js:211:7) Apr 03 07:50:00 musical-fidelity volumio[19698]: at addChunk (_stream_readable.js:263:12) Apr 03 07:50:00 musical-fidelity volumio[19698]: at readableAddChunk (_stream_readable.js:246:13) Apr 03 07:50:00 musical-fidelity volumio[19698]: at Socket.Readable.push (_stream_readable.js:208:10) Apr 03 07:50:00 musical-fidelity volumio[19698]: at Pipe.onread (net.js:607:20) Apr 03 07:50:00 musical-fidelity volumio[19698]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 07:50:01 musical-fidelity sudo[24414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-03 07:49 Apr 03 07:50:01 musical-fidelity sudo[24414]: 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="6d9442f761618503268d0366db805a252514dec1" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="mf" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 13 16:55:24 CEST 2022" VOLUMIO_VERSION="1.079" VOLUMIO_HARDWARE="pi" VOLUMIO_VENDOR="Musical Fidelity" VOLUMIO_MODEL="MX-Stream" VOLUMIO_VENDOR_MODEL="Musical Fidelity MX-Stream" VOLUMIO_HASH="feef4003d1cbcf074f611a1d9cb9a86e"