Moode 4.3: Can Pair but Connect Fails w/ `connect error: Permission denied (13)'

[Drewt]

I am running Moode 4.3 and have rebuilt it twice from scratch and both with the same result: 
I can Pair a bluetooth device (UE Boom 2) but I get the following in syslog when I try to connect. Using `tail -f` the mpd.log registers nothing. It was working and then I mucked around in the BT config

Code:

Oct 15 06:30:11 moode systemd[1]: Stopping Music Player Daemon...
Oct 15 06:30:11 moode systemd[1]: Stopped Music Player Daemon.
Oct 15 06:30:11 moode systemd[1]: Started Music Player Daemon.
Oct 15 06:30:14 moode bluetoothd[1113]: connect error: Permission denied (13)
Oct 15 06:30:14 moode bluetoothd[1113]: a2dp-source profile connect failed for 88:C6:26:92:D7:9B: Device or resource busy
Oct 15 06:30:19 moode bluetoothd[1113]: connect error: Permission denied (13)

I am not sure where to look for a hint as to what the problem might be. 
Any hint would be appreciated.

[Tim Curtis]

The symptom "It was working and then I mucked around in the BT config" suggests that reverting the changes should fix the issue.

[Drewt]

Hi Tim,

I reset the system by d/l the Moode 4.3 ISO and trying to setup the UE speaker. I turned on Bluetooth, Paired the speaker and got the same error above when trying to connect.
The Speaker can connect and play from my android tablet and phone. I can also pair to the player and play music from these two device to the pi.

Stumped!
Drew

FWIW I have included below the `systemcfg status' output for bluealsa and bluetooth ad well as my system info':

Code:

● bluealsa.service - BluezAlsa proxy
 Loaded: loaded (/etc/systemd/system/bluealsa.service; disabled; vendor preset: enabled)
 Active: active (running) since Tue 2018-10-16 07:16:03 EDT; 16min ago
Main PID: 1113 (bluealsa)
 CGroup: /system.slice/bluealsa.service
         └─1113 /usr/bin/bluealsa -p a2dp-source -p a2dp-sink

Oct 16 07:16:03 moode systemd[1]: Started BluezAlsa proxy.
● bluetooth.service - Bluetooth service
 Loaded: loaded (/lib/systemd/system/bluetooth.service; disabled; vendor preset: enabled)
 Active: active (running) since Tue 2018-10-16 07:16:03 EDT; 16min ago
   Docs: man:bluetoothd(8)
Main PID: 1100 (bluetoothd)
 Status: "Running"
 CGroup: /system.slice/bluetooth.service
         └─1100 /usr/sbin/bluetoothd --noplugin=sap

Oct 16 07:16:03 moode systemd[1]: Starting Bluetooth service...
Oct 16 07:16:03 moode bluetoothd[1100]: Bluetooth daemon 5.49
Oct 16 07:16:03 moode systemd[1]: Started Bluetooth service.
Oct 16 07:16:03 moode bluetoothd[1100]: Starting SDP server
Oct 16 07:16:03 moode bluetoothd[1100]: Bluetooth management interface 1.14 initialized
Oct 16 07:16:03 moode bluetoothd[1100]: Endpoint registered: sender=:1.36 path=/A2DP/SBC/Source/1
Oct 16 07:16:03 moode bluetoothd[1100]: Endpoint registered: sender=:1.36 path=/A2DP/SBC/Sink/1

This output comes from the failed Connect:
Oct 16 07:32:48 moode bluetoothd[1100]: connect error: Permission denied (13)
Oct 16 07:32:48 moode bluetoothd[1100]: a2dp-source profile connect failed for 88:C6:26:92:D7:9B: Device or
Oct 16 07:32:50 moode bluetoothd[1100]: connect error: Permission denied (13)

S Y S T E M    P A R A M E T E R S  

Date and time    = 2018-10-16 07:35:08
System uptime    = up 2 hours, 50 minutes
Timezone    = America/Detroit
moOde    = Release 4.3 2018-09-27

Host name    = moode
ETH0  IP    = 192.168.1.98
ETH0  MAC    = b8:27:eb:bf:87:e9
WLAN0 IP    = unassigned
WLAN0 MAC    = b8:27:eb:ea:d2:bc
WiFi country    = US

HDWR REV    = Pi-3B+ 1GB v1.3
SoC = BCM2835
CORES    = 4
ARCH    = armv7l
RASPBIAN    = 9.4
KERNEL    = 4.14.72-v7+
KTIMER FREQ    = 100 Hz
USB BOOT    = enabled
Warranty    = OK

ROOT size    = 2.9G
ROOT used = 72%
ROOT avail    = 795M
FS expand    = not expanded
MEM free = 404 MB
MEM used = 68 MB
Temperature = 56.9°C

CPU GOV    = performance
MPD SCHDPOL    = TS
P3-WIFI    = On
P3-BT    = On
HDMI    = On
ETH0 CHECK    = On
MAX USB CUR    = Off
UAC2 FIX    = Off
SSH server    = Off

LED0    = on
LED1    = on

C O R E    S E R V E R S  

PHP-FPM    = 7.0.30
NGINX    = 1.10.3
SQLite    = 3.16.2

C P U    L O A D  

CPU    %usr    %sys    %idle
all    0.13    0.63    99.25
0    0.00    0.51    99.49
1    0.00    1.50    98.50
2    0.50    0.00    99.50
3    0.00    0.50    99.50

P R O C E S S    L O A D  

PRI RTPRIO COMMAND         %MEM PSR %CPU
19      - sysinfo.sh       0.2   1  2.0
19      - php-fpm7.0       1.3   2  1.1
29      - mpd              2.1   1  0.7
19      - sudo             0.3   0  0.5
19      - worker.php       1.4   2  0.2
19      - systemd-journal  0.4   2  0.1
19      - php-fpm7.0       1.3   2  0.1
19      - php-fpm7.0       1.2   0  0.1
19      - systemd          0.6   2  0.0

C L O C K    F R E Q U E N C I E S  

   arm   = 1400 MHz          pwm   = 0 MHz
   core  = 400 MHz             emmc  = 200 MHz
   h264  = 300 MHz             pixel = 25 MHz
   isp   = 300 MHz             vec   = 126 MHz
   v3d   = 300 MHz             hdmi  = 163 MHz
   uart  = 47 MHz             dpi   = 0 MHz

SD card    = 50 MHz

S Y S T E M    V O L T A G E S  

core    = 1.3438V
sdram controller    = 1.2500V
sdram I/O    = 1.2500V
sdram chip    = 1.2250V

U I  C U S T O M I Z A T I O N S  

Theme    = Chikory Root
Accent color    = Fallenleaf
Alpha blend    = 1.0
Adaptive background    = No
Background image    = No
Playback history    = No
Extra metadata    = Yes
Artist list order    = AlbumArtist
Cover search pri    = Embedded cover
Hi-res covers    = No
Pixel ratio    = 0.800000011920929

A U D I O    P A R A M E T E R S  

Audio device    = Audiophonics ES9028 DAC
Interface    = I2S
Hdwr volume    = None
Mixer name    = Digital
Output stream    = Closed
ALSA version    = 1.1.3-5+rpi3
SoX version    = 0.1.2-2

Volume knob    = 0
Volume mute    = Unmuted

Bluetooth controller    = On
Airplay receiver    = Off
Spotify receiver    = 1
Squeezelite    = Off
UPnP client    = On
DLNA server    = Off

Rotary encoder    = Off
Encoder params    = 100 2 3 4 5
Crossfeed    = Off
Parametric EQ    = Off
Graphic EQ    = Off
Auto-shuffle    = Off
Autoplay    = On
MPD crossfade    = Off

M P D    S E T T I N G S  

Version    = 0.20.20
Volume control    = disabled
ALSA device    = hw:0
SoX resampling    = *:24:*
SoX quality    = very high
SoX multithreading    = off
Audio buffer (kb)    = 4096
Buffer before play    = 10%
Output buffer size (kb)    = 81920
Volume normalization    = No
DSD over PCM (DoP)    = no
Replay gain    = off

B L U E T O O T H    S E T T I N G S  

Bluetooth ver    = 5.49
Bluealsa ver    = 1.2.0
Speaker sharing    = No
Resume MPD    = No

A I R P L A Y    S E T T I N G S  

Version    = 3.2.1
Friendly name    = Moode Airplay
ALSA device    = hw:0
Volume mixer    = auto
Resume MPD    = No
Output bit depth    = S16
Output sample rate    = 44100
Session interruption    = no
Session timeout (ms)    = 120
Audio buffer (secs)    = 0.0

S P O T I F Y    S E T T I N G S  

Friendly name    = Moode Spotify
ALSA device    = default:CARD=sndrpirpidac
Resume MPD    = No
Bit rate    = 320
Initial volume    = 0
Volume curve    = Logarithmic
Volume normalization    = No
Normalization pregain    = 0

S Q U E E Z E L I T E    S E T T I N G S  

Version    = 1.8.7-1052 "DSD/SRC enabled"
Friendly name    = Moode
ALSA device    = hw:0
ALSA params    = 80:4::1
Output buffers    = 40000:100000
Task priority    = 45
Codec list    = flac,pcm,mp3,ogg,aac,alac,dsd
Resume MPD    = No
Other options    = -W -D 500 -R E -S /var/loca

M O O D E    L O G  

  20181016 044508 worker: - Start
  20181016 044509 worker: Successfully daemonized
  20181016 044509 worker: Session loaded
  20181016 044509 worker: Debug logging (off)
  20181016 044509 worker: - Platform
  20181016 044510 worker: Host (moode)
  20181016 044510 worker: Hdwr (Pi-3B+ 1GB v1.3)
  20181016 044510 worker: Arch (armv7l)
  20181016 044510 worker: Rasp (9.4)
  20181016 044510 worker: Kver (4.14.72-v7+)
  20181016 044510 worker: Ktyp (Standard)
  20181016 044510 worker: Gov  (performance)
  20181016 044510 worker: Rel  (Moode 4.3 2018-09-27)
  20181016 044510 worker: Upd  (None)
  20181016 044510 worker: MPD  (0.20.20)
  20181016 044510 worker: USB boot enabled
  20181016 044510 worker: File system not expanded yet
  20181016 044510 worker: HDMI port on
  20181016 044510 worker: File check ok
  20181016 044510 worker: - Network
  20181016 044511 worker: eth0 exists
  20181016 044511 worker: eth0 (192.168.1.98)
  20181016 044511 worker: wlan0 exists
  20181016 044511 worker: wifi country (US)
  20181016 044511 worker: wlan0 SSID is blank
  20181016 044511 worker: eth0 addr exists, AP mode not started
  20181016 044511 worker: wlan0 address not assigned
  20181016 044511 worker: - Audio
  20181016 044511 worker: ALSA outputs unmuted
  20181016 044511 worker: ALSA card number (0)
  20181016 044511 worker: Audio out (I2S audio device)
  20181016 044511 worker: Audio dev (Audiophonics ES9028 DAC)
  20181016 044511 worker: ALSA mixer name (Digital)
  20181016 044511 worker: MPD volume control (disabled)
  20181016 044511 worker: Hdwr volume controller not detected
  20181016 044511 worker: - Services
  20181016 044511 worker: Reset renderer active
  20181016 044513 worker: MPD started
  20181016 044513 worker: MPD scheduler policy (time-share)
  20181016 044513 worker: Configure MPD outputs
  20181016 044513 worker: MPD output 1 ALSA default (on)
  20181016 044513 worker: MPD output 2 ALSA crossfeed (off)
  20181016 044513 worker: MPD output 3 ALSA parametric eq (off)
  20181016 044513 worker: MPD output 4 ALSA graphic eq (off)
  20181016 044513 worker: MPD output 5 ALSA bluetooth (off)
  20181016 044513 worker: MPD crossfade (off)
  20181016 044513 worker: Spotify receiver started
  20181016 044513 worker: UPnP renderer started
  20181016 044513 worker: Bluetooth controller started
  20181016 044520 worker: Bluetooth controller initialized
  20181016 044520 worker: - Music sources
  20181016 044520 worker: USB sources (none attached)
  20181016 044520 worker: NAS sources (mountall initiated)
  20181016 044520 worker: - Miscellaneous
  20181016 044520 worker: Volume level (0) restored
  20181016 044520 worker: ALSA output (closed)
  20181016 044520 worker: Auto-playing id ()
  20181016 044520 worker: Maintenance interval (21600)
  20181016 044520 worker: Screen saver activation (60)
  20181016 044520 worker: Watchdog started
  20181016 044520 worker: Ready
  20181016 071603 worker: Job btsvc
  20181016 071603 worker: Bluetooth controller initialized
  20181016 072611 worker: Job alizarin
  20181016 072651 worker: Job carrot
  20181016 072713 worker: Job fallenleaf

[Tim Curtis]

Hi,

Very odd. I have a small Anker SoundCore speaker and no issues.

When my speaker is turned on it automatically connects to the Pi. Maybe your speaker is automatically connecting to your phone or tablet and then appears as "busy" when you try to connect from moOde ??

[Sniglar]

I had a similar experience on an earlier version which was solved by disabling Sox re-sampling.

[Drewt]

Hi,

Very odd. I have a small Anker SoundCore speaker and no issues.

When my speaker is turned on it automatically connects to the Pi. Maybe your speaker is automatically connecting to your phone or tablet and then appears as "busy" when you try to connect from moOde ??

Hi Tim,

FWIW being a year down the track, I eventually resolved the BT connect problem to UE speaker: 

I had changed the default name of the speaker using an app provided by UE which was fine. I then got my problem pairing with MoOde. I gave up. Then, for no particular reason, I decided to revert to the default name. 
The next time I tried pairing it worked.

[Tim Curtis]

Ok cool. Glad to hear its working :-)