Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
09-15-2023, 06:11 PM
(This post was last modified: 11-13-2023, 10:56 AM by Tim Curtis.)
Hello,
I have raspberry pi 3b
+ GPIO DAC
+ 100W amplifier
+ large speakers
+ moOde player
and use it as media system
one of usage is to listen sound from phones (Android) and TV (LG 43UK6300PLB, WebOS 4.4.2-11) via Bluetooth (BT).
Issue background:
I had 8.0.2 version. I could connect my TV to my media system and listen via its speakers instead of the TV speakers.
I had no problems with phones. But the TV, sometimes, lost sound, and after a few minutes I had BT disconnection. After that, media system tried to connect again, I confirmed, sound appeared then disappeared and so on. Sometime I could watch a film without a problem, sometime the issue repeated every 5-10 minutes.
Current Issue:
I have decided to upgrade moOde up to 8.3.6. Actually I have reinstalled from scratch. And now I have no sound from my TV at all. I can connect to the TV via BT. Sometime the connection is lost immediately, sometime BT works without sound. About Android phones - no problem, I can connect any phone and listen music via the media system.
May be it's related to BT configuration moOde vs TV (BT version, codec or something like) and I can configure something on moOde side.
Another question about BT related changes 8.0.2 vs 8.3.6. Or related with raspberry os.
I am trying to watch system logs via ssh...
Posts: 12,080
Threads: 272
Joined: Mar 2018
Reputation:
461
Typically Bluetooth connection issues are due to some sort of interference resulting in a poor quality or low strength signal, or too much distance between the devices. Bluetooth under ideal conditions is limited to around 30 feet.
I'll see if can test with my TV tonight.
Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
(09-15-2023, 08:43 PM)Tim Curtis Wrote: Typically Bluetooth connection issues are due to some sort of interference resulting in a poor quality or low strength signal, or too much distance between the devices. Bluetooth under ideal conditions is limited to around 30 feet.
I'll see if can test with my TV tonight.
Thank you for the answer!
Distance in my case is about 1.5 meters (5 feet). There is no metal shielding. Also I have private house - there are no other BT devices around.
My next step: I think to check versions changes related to BT, audio and OS, and try to downgrade moOde.
Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
I have gathered logs
duding these logs I
- connected BT from moOde web interface
- connection lost immediately
- connected BT from TV settings
- connection was work but no sound
- shut down
messages:
Code: Sep 16 07:29:44 frolov-media kernel: [46609.895365] input: LG TV[[LG] webOS TV] (AVRCP) as /devices/virtual/input/input19
Sep 16 07:30:21 frolov-media kernel: [46647.062462] input: LG TV[[LG] webOS TV] (AVRCP) as /devices/virtual/input/input20
user.log:
Code: Sep 16 07:37:56 frolov-media blkmapd[165]: exit on signal(15)
deamon.log:
Code: Sep 16 07:02:56 frolov-media winbindd[478]: [2023/09/16 07:02:56.225194, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:02:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:02:56 frolov-media winbindd[478]: [2023/09/16 07:02:56.227967, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:02:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:07:56 frolov-media winbindd[478]: [2023/09/16 07:07:56.228369, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:07:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:07:56 frolov-media winbindd[478]: [2023/09/16 07:07:56.229811, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:07:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:10:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:10:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:10:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:12:56 frolov-media winbindd[478]: [2023/09/16 07:12:56.231150, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:12:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:12:56 frolov-media winbindd[478]: [2023/09/16 07:12:56.232675, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:12:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:17:56 frolov-media winbindd[478]: [2023/09/16 07:17:56.234640, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:17:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:17:56 frolov-media winbindd[478]: [2023/09/16 07:17:56.236045, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:17:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:20:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:20:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:20:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:22:56 frolov-media winbindd[478]: [2023/09/16 07:22:56.237696, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:22:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:22:56 frolov-media winbindd[478]: [2023/09/16 07:22:56.239166, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:22:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:27:56 frolov-media winbindd[478]: [2023/09/16 07:27:56.240105, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:27:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:27:56 frolov-media winbindd[478]: [2023/09/16 07:27:56.241525, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:27:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: SBC
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: aptX
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SelectConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:272: A2DP peer capabilities blob [len=7]: 4f000000010013
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:117: Loading storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:412: A2DP Sink (aptX) configured for device 20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:415: A2DP selected configuration blob [len=7]: 4f000000010012
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:417: PCM configuration: channels: 2, sampling: 48000
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:29:44 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:29:44 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:29:44 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:29:44 frolov-media mpd[203734]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:29:44 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:29:44 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:44 frolov-media systemd[1]: Started Bluealsa-aplay.
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203741] D: aplay.c:831: Creating IO worker 20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203741] D: aplay.c:1181: Starting main loop
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:499: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [203744] D: dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:525: Starting IO loop
Sep 16 07:29:45 frolov-media systemd-udevd[203730]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluetoothd[1105]: /org/bluez/hci0/dev_20_3D_BD_F3_57_3B/sep2/fd19: fd(39) ready
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:741: New A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:742: A2DP socket MTU: 15: R:672 W:668
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1528: Starting transport: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:448: Created BT socket duplicate: [15]: 18
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: a2dp-aptx.c:253: IO loop: START: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1949: Created new IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:462: Closing BT socket duplicate [15]: 18
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: a2dp-aptx.c:292: IO loop: EXIT: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:768: Releasing A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:795: Closing A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:1983: Exiting IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.ClearConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:453: Disconnecting media endpoint: /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1891: Closing PCM: 16
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:593: BlueALSA source PCM disconnected: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:46 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:475: Exiting IO worker 20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203731] D: ba-transport.c:610: PCM clients check keep-alive: 0 ms
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1195: Freeing transport: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:154: Saving storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-device.c:142: Freeing device: 20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media systemd[1]: Stopping Bluealsa-aplay...
Sep 16 07:29:46 frolov-media systemd[1]: bluealsa-aplay@b8:27:eb:de:93:4f.service: Succeeded.
Sep 16 07:29:46 frolov-media systemd[1]: Stopped Bluealsa-aplay.
Sep 16 07:29:46 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:46 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:50 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:29:50 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:29:50 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:29:50 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:29:51 frolov-media mpd[203824]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:29:51 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:30:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:30:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:30:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: SBC
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: aptX
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:117: Loading storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:412: A2DP Sink (aptX) configured for device 20:3D:BD:F3:57:3B
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:415: A2DP selected configuration blob [len=7]: 4f000000010012
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:417: PCM configuration: channels: 2, sampling: 48000
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:21 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:30:21 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:30:21 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:30:21 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:30:21 frolov-media mpd[203981]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:21 frolov-media bluetoothd[1105]: /org/bluez/hci0/dev_20_3D_BD_F3_57_3B/fd20: fd(39) ready
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:741: New A2DP transport: 15
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:742: A2DP socket MTU: 15: R:672 W:668
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1528: Starting transport: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:448: Created BT socket duplicate: [15]: 16
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [203984] D: a2dp-aptx.c:253: IO loop: START: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1949: Created new IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:30:22 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:462: Closing BT socket duplicate [15]: 16
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: a2dp-aptx.c:292: IO loop: EXIT: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:768: Releasing A2DP transport: 15
Sep 16 07:30:22 frolov-media systemd[1]: Started Bluealsa-aplay.
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:795: Closing A2DP transport: 15
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:1983: Exiting IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203994] D: aplay.c:831: Creating IO worker 20:3D:BD:F3:57:3B
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203994] D: aplay.c:1181: Starting main loop
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:499: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203997] D: dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:525: Starting IO loop
Sep 16 07:30:22 frolov-media systemd-udevd[203969]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:47 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:475: Exiting IO worker 20:3D:BD:F3:57:3B
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1891: Closing PCM: 16
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [203970] D: ba-transport.c:610: PCM clients check keep-alive: 0 ms
Sep 16 07:30:47 frolov-media systemd[1]: Stopping Bluealsa-aplay...
Sep 16 07:30:47 frolov-media systemd[1]: bluealsa-aplay@b8:27:eb:de:93:4f.service: Succeeded.
Sep 16 07:30:47 frolov-media systemd[1]: Stopped Bluealsa-aplay.
Sep 16 07:30:47 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:47 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.ClearConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:453: Disconnecting media endpoint: /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1195: Freeing transport: A2DP Sink (aptX)
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:154: Saving storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-device.c:142: Freeing device: 20:3D:BD:F3:57:3B
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:55 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:55 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:32:56 frolov-media winbindd[478]: [2023/09/16 07:32:56.242046, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:32:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:32:56 frolov-media winbindd[478]: [2023/09/16 07:32:56.243307, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:32:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:33:06 frolov-media rngd[418]: stats: bits received from HRNG source: 440064
Sep 16 07:33:06 frolov-media rngd[418]: stats: bits sent to kernel pool: 390240
Sep 16 07:33:06 frolov-media rngd[418]: stats: entropy added to kernel pool: 390240
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2 successes: 22
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2 failures: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Poker: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Runs: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Long run: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: HRNG source speed: (min=354.746; avg=831.620; max=918.297)Kibits/s
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS tests speed: (min=25.196; avg=46.479; max=52.256)Mibits/s
Sep 16 07:33:06 frolov-media rngd[418]: stats: Lowest ready-buffers level: 2
Sep 16 07:33:06 frolov-media rngd[418]: stats: Entropy starvations: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Sep 16 07:37:51 frolov-media systemd[1]: Stopping A high performance web server and a reverse proxy server...
Sep 16 07:37:56 frolov-media winbindd[478]: [2023/09/16 07:37:56.243753, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:37:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:37:56 frolov-media winbindd[478]: [2023/09/16 07:37:56.245008, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:37:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:37:56 frolov-media systemd[1]: nginx.service: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped A high performance web server and a reverse proxy server.
Sep 16 07:37:56 frolov-media systemd[1]: nginx.service: Consumed 3.550s CPU time.
Sep 16 07:37:56 frolov-media systemd[1]: Removed slice system-bluealsa\x2daplay.slice.
Sep 16 07:37:56 frolov-media systemd[1]: system-bluealsa\x2daplay.slice: Consumed 2.037s CPU time.
Sep 16 07:37:56 frolov-media systemd[1]: Removed slice system-modprobe.slice.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Bluetooth.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Multi-User System.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Login Prompts.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target RPC Port Mapper.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Timers.
Sep 16 07:37:56 frolov-media systemd[1]: e2scrub_all.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Periodic ext4 Online Metadata Check for All Filesystems.
Sep 16 07:37:56 frolov-media systemd[1]: fstrim.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Discard unused blocks once a week.
Sep 16 07:37:56 frolov-media systemd[1]: logrotate.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily rotation of log files.
Sep 16 07:37:56 frolov-media systemd[1]: man-db.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily man-db regeneration.
Sep 16 07:37:56 frolov-media systemd[1]: sysstat-collect.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Run system activity accounting tool every 10 minutes.
Sep 16 07:37:56 frolov-media systemd[1]: sysstat-summary.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Generate summary of yesterday's process accounting.
Sep 16 07:37:56 frolov-media systemd[1]: systemd-tmpfiles-clean.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily Cleanup of Temporary Directories.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target System Time Synchronized.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target System Time Set.
Sep 16 07:37:56 frolov-media systemd[1]: systemd-rfkill.socket: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Got SIGTERM, quitting.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
Sep 16 07:37:56 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-adapter.c:144: Freeing adapter: hci0
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Bluealsa proxy...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::58b2:39b6:6d5a:9622.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Getty on tty1...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.12.
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface lo.IPv6 with address ::1.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping pNFS block layout mapping daemon...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/LDAC/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/LDAC/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/source/1
Sep 16 07:37:56 frolov-media systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/sink/2
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Authorization Manager...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/3
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/3
Sep 16 07:37:56 frolov-media avahi-daemon[382]: avahi-daemon 0.8 exiting.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Sep 16 07:37:56 frolov-media systemd[1]: Stopping LSB: rng-tools (Debian variant)...
Sep 16 07:37:56 frolov-media systemd[1]: Condition check resulted in Turns off Raspberry Pi display backlight on shutdown/reboot being skipped.
Sep 16 07:37:56 frolov-media systemd[1]: rpi-eeprom-update.service: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Check for Raspberry Pi EEPROM updates.
syslog:
Code: Sep 16 07:02:56 frolov-media winbindd[478]: [2023/09/16 07:02:56.225194, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:02:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:02:56 frolov-media winbindd[478]: [2023/09/16 07:02:56.227967, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:02:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:07:56 frolov-media winbindd[478]: [2023/09/16 07:07:56.228369, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:07:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:07:56 frolov-media winbindd[478]: [2023/09/16 07:07:56.229811, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:07:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:10:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:10:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:10:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:12:56 frolov-media winbindd[478]: [2023/09/16 07:12:56.231150, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:12:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:12:56 frolov-media winbindd[478]: [2023/09/16 07:12:56.232675, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:12:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:17:56 frolov-media winbindd[478]: [2023/09/16 07:17:56.234640, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:17:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:17:56 frolov-media winbindd[478]: [2023/09/16 07:17:56.236045, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:17:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:20:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:20:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:20:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:22:56 frolov-media winbindd[478]: [2023/09/16 07:22:56.237696, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:22:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:22:56 frolov-media winbindd[478]: [2023/09/16 07:22:56.239166, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:22:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:27:56 frolov-media winbindd[478]: [2023/09/16 07:27:56.240105, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:27:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:27:56 frolov-media winbindd[478]: [2023/09/16 07:27:56.241525, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:27:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: SBC
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: aptX
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SelectConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:272: A2DP peer capabilities blob [len=7]: 4f000000010013
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:117: Loading storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:412: A2DP Sink (aptX) configured for device 20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:415: A2DP selected configuration blob [len=7]: 4f000000010012
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:417: PCM configuration: channels: 2, sampling: 48000
Sep 16 07:29:44 frolov-media kernel: [46609.895365] input: LG TV[[LG] webOS TV] (AVRCP) as /devices/virtual/input/input19
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:29:44 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:29:44 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:29:44 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:29:44 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:29:44 frolov-media mpd[203734]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:29:44 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:29:44 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:44 frolov-media systemd[1]: Started Bluealsa-aplay.
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203741] D: aplay.c:831: Creating IO worker 20:3D:BD:F3:57:3B
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203741] D: aplay.c:1181: Starting main loop
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:499: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:44 frolov-media bluealsa[1107]: bluealsa: [203744] D: dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:44 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:525: Starting IO loop
Sep 16 07:29:45 frolov-media systemd-udevd[203730]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluetoothd[1105]: /org/bluez/hci0/dev_20_3D_BD_F3_57_3B/sep2/fd19: fd(39) ready
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:741: New A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:742: A2DP socket MTU: 15: R:672 W:668
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1528: Starting transport: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:448: Created BT socket duplicate: [15]: 18
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: a2dp-aptx.c:253: IO loop: START: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1949: Created new IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:462: Closing BT socket duplicate [15]: 18
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: a2dp-aptx.c:292: IO loop: EXIT: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:768: Releasing A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:795: Closing A2DP transport: 15
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203751] D: ba-transport.c:1983: Exiting IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.ClearConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:453: Disconnecting media endpoint: /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1891: Closing PCM: 16
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:593: BlueALSA source PCM disconnected: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:29:46 frolov-media bluealsa-aplay[203741]: bluealsa-aplay: [203743] D: aplay.c:475: Exiting IO worker 20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [203731] D: ba-transport.c:610: PCM clients check keep-alive: 0 ms
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1195: Freeing transport: A2DP Sink (aptX)
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:154: Saving storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-device.c:142: Freeing device: 20:3D:BD:F3:57:3B
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:29:46 frolov-media systemd[1]: Stopping Bluealsa-aplay...
Sep 16 07:29:46 frolov-media systemd[1]: bluealsa-aplay@b8:27:eb:de:93:4f.service: Succeeded.
Sep 16 07:29:46 frolov-media systemd[1]: Stopped Bluealsa-aplay.
Sep 16 07:29:46 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:46 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:29:50 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:29:50 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:29:50 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:29:50 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:29:51 frolov-media mpd[203824]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:29:51 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:30:04 frolov-media systemd[1]: Starting system activity accounting tool...
Sep 16 07:30:04 frolov-media systemd[1]: sysstat-collect.service: Succeeded.
Sep 16 07:30:04 frolov-media systemd[1]: Finished system activity accounting tool.
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: SBC
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1289: Adding new Stream End-Point: 20:3D:BD:F3:57:3B: aptX
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:117: Loading storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:412: A2DP Sink (aptX) configured for device 20:3D:BD:F3:57:3B
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:415: A2DP selected configuration blob [len=7]: 4f000000010012
Sep 16 07:30:18 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:417: PCM configuration: channels: 2, sampling: 48000
Sep 16 07:30:21 frolov-media kernel: [46647.062462] input: LG TV[[LG] webOS TV] (AVRCP) as /devices/virtual/input/input20
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1193: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded()
Sep 16 07:30:21 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:30:21 frolov-media systemd[1]: mpd.service: Succeeded.
Sep 16 07:30:21 frolov-media systemd[1]: Stopped Music Player Daemon.
Sep 16 07:30:21 frolov-media systemd[1]: Starting Music Player Daemon...
Sep 16 07:30:21 frolov-media mpd[203981]: Ignoring the 'pid_file' setting in systemd mode
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:21 frolov-media bluetoothd[1105]: /org/bluez/hci0/dev_20_3D_BD_F3_57_3B/fd20: fd(39) ready
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:741: New A2DP transport: 15
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:742: A2DP socket MTU: 15: R:672 W:668
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1528: Starting transport: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:448: Created BT socket duplicate: [15]: 16
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [203984] D: a2dp-aptx.c:253: IO loop: START: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1949: Created new IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:30:21 frolov-media systemd[1]: Started Music Player Daemon.
Sep 16 07:30:22 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:462: Closing BT socket duplicate [15]: 16
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: a2dp-aptx.c:292: IO loop: EXIT: a2dp_aptx_dec_thread: A2DP Sink (aptX)
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:768: Releasing A2DP transport: 15
Sep 16 07:30:22 frolov-media systemd[1]: Started Bluealsa-aplay.
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1400: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:795: Closing A2DP transport: 15
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203984] D: ba-transport.c:1983: Exiting IO thread [ba-a2dp-aptx]: A2DP Sink (aptX)
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203994] D: aplay.c:831: Creating IO worker 20:3D:BD:F3:57:3B
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203994] D: aplay.c:1181: Starting main loop
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:499: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:30:22 frolov-media bluealsa[1107]: bluealsa: [203997] D: dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_20_3D_BD_F3_57_3B/a2dpsnk/source
Sep 16 07:30:22 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:525: Starting IO loop
Sep 16 07:30:22 frolov-media systemd-udevd[203969]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:47 frolov-media bluealsa-aplay[203994]: bluealsa-aplay: [203996] D: aplay.c:475: Exiting IO worker 20:3D:BD:F3:57:3B
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1891: Closing PCM: 16
Sep 16 07:30:47 frolov-media bluealsa[1107]: bluealsa: [203970] D: ba-transport.c:610: PCM clients check keep-alive: 0 ms
Sep 16 07:30:47 frolov-media systemd[1]: Stopping Bluealsa-aplay...
Sep 16 07:30:47 frolov-media systemd[1]: bluealsa-aplay@b8:27:eb:de:93:4f.service: Succeeded.
Sep 16 07:30:47 frolov-media systemd[1]: Stopped Bluealsa-aplay.
Sep 16 07:30:47 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:47 frolov-media systemd[1]: Configuration file /etc/systemd/system/bluealsa-aplay@.service is marked executable. Please remove executable permission bits. Proceeding anyway.
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: dbus.c:47: Called: org.bluez.MediaEndpoint1.ClearConfiguration() on /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:453: Disconnecting media endpoint: /org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-transport.c:1195: Freeing transport: A2DP Sink (aptX)
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: storage.c:154: Saving storage: /var/lib/bluealsa/20:3D:BD:F3:57:3B
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-device.c:142: Freeing device: 20:3D:BD:F3:57:3B
Sep 16 07:30:54 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:55 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:30:55 frolov-media bluealsa[1107]: bluealsa: [1107] D: bluez.c:1304: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved()
Sep 16 07:32:56 frolov-media winbindd[478]: [2023/09/16 07:32:56.242046, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:32:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:32:56 frolov-media winbindd[478]: [2023/09/16 07:32:56.243307, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:32:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:33:06 frolov-media rngd[418]: stats: bits received from HRNG source: 440064
Sep 16 07:33:06 frolov-media rngd[418]: stats: bits sent to kernel pool: 390240
Sep 16 07:33:06 frolov-media rngd[418]: stats: entropy added to kernel pool: 390240
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2 successes: 22
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2 failures: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Poker: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Runs: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Long run: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: HRNG source speed: (min=354.746; avg=831.620; max=918.297)Kibits/s
Sep 16 07:33:06 frolov-media rngd[418]: stats: FIPS tests speed: (min=25.196; avg=46.479; max=52.256)Mibits/s
Sep 16 07:33:06 frolov-media rngd[418]: stats: Lowest ready-buffers level: 2
Sep 16 07:33:06 frolov-media rngd[418]: stats: Entropy starvations: 0
Sep 16 07:33:06 frolov-media rngd[418]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Sep 16 07:37:51 frolov-media systemd[1]: Stopping A high performance web server and a reverse proxy server...
Sep 16 07:37:56 frolov-media winbindd[478]: [2023/09/16 07:37:56.243753, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:37:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:37:56 frolov-media winbindd[478]: [2023/09/16 07:37:56.245008, 0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Sep 16 07:37:56 frolov-media winbindd[478]: rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Sep 16 07:37:56 frolov-media systemd[1]: nginx.service: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped A high performance web server and a reverse proxy server.
Sep 16 07:37:56 frolov-media systemd[1]: nginx.service: Consumed 3.550s CPU time.
Sep 16 07:37:56 frolov-media systemd[1]: Removed slice system-bluealsa\x2daplay.slice.
Sep 16 07:37:56 frolov-media systemd[1]: system-bluealsa\x2daplay.slice: Consumed 2.037s CPU time.
Sep 16 07:37:56 frolov-media systemd[1]: Removed slice system-modprobe.slice.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Bluetooth.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Multi-User System.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Login Prompts.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target RPC Port Mapper.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target Timers.
Sep 16 07:37:56 frolov-media systemd[1]: e2scrub_all.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Periodic ext4 Online Metadata Check for All Filesystems.
Sep 16 07:37:56 frolov-media systemd[1]: fstrim.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Discard unused blocks once a week.
Sep 16 07:37:56 frolov-media systemd[1]: logrotate.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily rotation of log files.
Sep 16 07:37:56 frolov-media systemd[1]: man-db.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily man-db regeneration.
Sep 16 07:37:56 frolov-media systemd[1]: sysstat-collect.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Run system activity accounting tool every 10 minutes.
Sep 16 07:37:56 frolov-media systemd[1]: sysstat-summary.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Generate summary of yesterday's process accounting.
Sep 16 07:37:56 frolov-media systemd[1]: systemd-tmpfiles-clean.timer: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Daily Cleanup of Temporary Directories.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target System Time Synchronized.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped target System Time Set.
Sep 16 07:37:56 frolov-media systemd[1]: systemd-rfkill.socket: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Got SIGTERM, quitting.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
Sep 16 07:37:56 frolov-media bluealsa[1107]: bluealsa: [1107] D: ba-adapter.c:144: Freeing adapter: hci0
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Bluealsa proxy...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::58b2:39b6:6d5a:9622.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Getty on tty1...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.12.
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface lo.IPv6 with address ::1.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Music Player Daemon...
Sep 16 07:37:56 frolov-media avahi-daemon[382]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping pNFS block layout mapping daemon...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/LDAC/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/LDAC/source/2
Sep 16 07:37:56 frolov-media blkmapd[165]: exit on signal(15)
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/source/1
Sep 16 07:37:56 frolov-media systemd[1]: Stopping The PHP 7.4 FastCGI Process Manager...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptXHD/sink/2
Sep 16 07:37:56 frolov-media systemd[1]: Stopping Authorization Manager...
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/source/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/source/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/1
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/2
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/aptX/sink/3
Sep 16 07:37:56 frolov-media bluetoothd[1105]: Endpoint unregistered: sender=:1.13 path=/org/bluez/hci0/A2DP/SBC/sink/3
Sep 16 07:37:56 frolov-media avahi-daemon[382]: avahi-daemon 0.8 exiting.
Sep 16 07:37:56 frolov-media systemd[1]: Stopping LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Sep 16 07:37:56 frolov-media systemd[1]: Stopping LSB: rng-tools (Debian variant)...
Sep 16 07:37:56 frolov-media systemd[1]: Condition check resulted in Turns off Raspberry Pi display backlight on shutdown/reboot being skipped.
Sep 16 07:37:56 frolov-media systemd[1]: rpi-eeprom-update.service: Succeeded.
Sep 16 07:37:56 frolov-media systemd[1]: Stopped Check for Raspberry Pi EEPROM updates.
Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
09-16-2023, 05:22 AM
(This post was last modified: 09-16-2023, 05:32 AM by Pavel Frolov.)
So next in my investigation
changes from 8.0.2 to 8.3.6
Code: 8.0.2
RaspiOS Bullseye Lite most recent packages as of 2022-03-26 and Linux kernel 5.15.y
Add BlueZ Config SCAN options and longer trust time
Add pairing agent restart to Bluetooth section of Audio Config
Add moodeutl option to reset Bluetooth to off
8.1.0
RaspiOS Bullseye Lite most recent packages as of 2022-06-22 and Linux kernel 5.15
During startup set ALSA hardware mixer state to 'on'
8.1.1
RaspiOS Bullseye Lite most recent packages as of 2022-06-24 and Linux kernel 5.15
8.1.2
RaspiOS Bullseye Lite most recent packages as of 2022-07-08 and Linux kernel 5.15
Missing alsa.php library causing BlueZ Config to crash
Missing alsa.php library causing Squeezelite Config to crash
8.2.0
RaspiOS Bullseye Lite most recent packages as of 2022-09-17 and Linux kernel 5.15
Bump to Linux kernel 5.15.61 build #1579
Bump to camillagui 1.0.0 rc4, camilla-backend 1.0.0 rc5 and alsa-cdsp 1.2.0
8.2.1
RaspiOS Bullseye Lite most recent packages as of 2022-10-07 and Linux kernel 5.15
8.2.2
RaspiOS Bullseye Lite most recent packages as of 2022-10-24 and Linux kernel 5.15.61
8.2.3
RaspiOS Bullseye Lite most recent packages as of 2022-12-18 and Linux kernel 5.15.76
Bump to Linux kernel 5.15.76 #1597
Remove Bluetooth speaker sharing option (obsolete)
Only list Bluetooth on main menu if its On
Startup hangs if Bluetooth on but no adapter is present
8.2.4
RaspiOS Bullseye Lite most recent packages as of 2022-12-28 and Linux kernel 5.15.76
8.2.5
RaspiOS Bullseye Lite most recent packages as of 2023-01-20, Linux kernel 5.15.84
Bump to Linux kernel 5.15.84 build #1613
Bump default TRX jitter and ALSA buffer from 32/64 to 64/128
8.3.0
RaspiOS Bullseye Lite most recent packages as of 2023-03-14, Linux kernel 5.15.84
Use ALSA instead of MPD to control Hardware volume
Bump to bluez-alsa 4.0.0
Add ALSA buffer_time option to MPD Config
Add moodeutl option for printing ALSA hwparams
Mapped ALSA volume not being used to set ALSA volume max
8.3.1
RaspiOS Bullseye Lite most recent packages as of 2023-04-17, Linux kernel 6.1.21
Raspberry Pi Codec (I2S)
Raspberry Pi DAC+ (I2S)
Raspberry Pi DAC Pro (I2S)
Raspberry Pi DigiAMP+ (I2S)
Bump to Linux kernel 6.1.21 build #1642
Change overlay name rpi-dac to i2s-dac
8.3.2
RaspiOS Bullseye Lite most recent packages as of 2023-04-17, Linux kernel 6.1.21
8.3.3
RaspiOS Bullseye Lite most recent packages as of 2023-06-08 Linux kernel 6.1.21
Bump to bluez-alsa 4.1.0 with aptX/aptX-HD
Incorrect version of bluez-alsa on release 8.3.2 image
8.3.4
RaspiOS Bullseye Lite most recent packages as of 2023-08-17, Linux kernel 6.1.21
Add support for Bluetooth LDAC encoder
Add support for CamillaDSP to Bluetooth inbound connection
Triggerhappy default user permissions not sufficient to run ALSA commands
8.3.5
RaspiOS Bullseye Lite most recent packages as of 2023-08-19, Linux kernel 6.1.21
8.3.6
RaspiOS Bullseye Lite most recent packages as of 2023-09-05, Linux kernel 6.1.21
In-place update overwriting BUFFERTIME in bluealsaaplay.conf
In-place update not adding SBC XQ+ line in bluealsa.service
Important:
Code: 8.0.2
RaspiOS Bullseye Lite most recent packages as of 2022-03-26 and Linux kernel 5.15.y
8.1.2
RaspiOS Bullseye Lite most recent packages as of 2022-07-08 and Linux kernel 5.15
Missing alsa.php library causing BlueZ Config to crash
Missing alsa.php library causing Squeezelite Config to crash
8.2.0
RaspiOS Bullseye Lite most recent packages as of 2022-09-17 and Linux kernel 5.15
Bump to Linux kernel 5.15.61 build #1579
Bump to camillagui 1.0.0 rc4, camilla-backend 1.0.0 rc5 and alsa-cdsp 1.2.0
8.3.0
RaspiOS Bullseye Lite most recent packages as of 2023-03-14, Linux kernel 5.15.84
Use ALSA instead of MPD to control Hardware volume
Bump to bluez-alsa 4.0.0
Add ALSA buffer_time option to MPD Config
Add moodeutl option for printing ALSA hwparams
Mapped ALSA volume not being used to set ALSA volume max
8.3.1
RaspiOS Bullseye Lite most recent packages as of 2023-04-17, Linux kernel 6.1.21
Bump to Linux kernel 6.1.21 build #1642
Change overlay name rpi-dac to i2s-dac
8.3.3
RaspiOS Bullseye Lite most recent packages as of 2023-06-08 Linux kernel 6.1.21
Bump to bluez-alsa 4.1.0 with aptX/aptX-HD
Incorrect version of bluez-alsa on release 8.3.2 image
8.3.4
RaspiOS Bullseye Lite most recent packages as of 2023-08-17, Linux kernel 6.1.21
Add support for Bluetooth LDAC encoder
Add support for CamillaDSP to Bluetooth inbound connection
Triggerhappy default user permissions not sufficient to run ALSA commands
8.3.6
RaspiOS Bullseye Lite most recent packages as of 2023-09-05, Linux kernel 6.1.21
In-place update overwriting BUFFERTIME in bluealsaaplay.conf
In-place update not adding SBC XQ+ line in bluealsa.service
The most important:
Code: 8.0.2
RaspiOS Bullseye Lite most recent packages as of 2022-03-26 and Linux kernel 5.15.y
8.3.0
RaspiOS Bullseye Lite most recent packages as of 2023-03-14, Linux kernel 5.15.84
Bump to bluez-alsa 4.0.0
8.3.1
RaspiOS Bullseye Lite most recent packages as of 2023-04-17, Linux kernel 6.1.21
Bump to Linux kernel 6.1.21 build #1642
8.3.3
RaspiOS Bullseye Lite most recent packages as of 2023-06-08 Linux kernel 6.1.21
Bump to bluez-alsa 4.1.0 with aptX/aptX-HD
8.3.4
RaspiOS Bullseye Lite most recent packages as of 2023-08-17, Linux kernel 6.1.21
Add support for Bluetooth LDAC encoder
Triggerhappy default user permissions not sufficient to run ALSA commands
I will try to downgrade down to versions before the most important from the last list.
They are:
8.3.3 -> 8.3.4
8.3.2 -> 8.3.3
8.3.0 -> 8.3.1
8.2.5 -> 8.3.0
Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
SOLVED (workaround)
I tried to install following versions
8.3.3: the same issue (connect no sound)
8.3.2: cannot connect at all (TV cannot find the media system)
8.3.0: cannot connect at all (TV cannot find the media system)
8.2.5: cannot connect at all (TV cannot find the media system)
1. I found that my initially installed version was without amd64 but I tried all 64 bits.
I tried to install 8.3.6 32 version. Ind got the same..
2 strange that the TV couldn't to find anything. I tried to unplug TV (not just turn off via button). Although I have disabled standby mode on the TV.
After that the TV could find the media system. But issue was the same.
Then I tried to install 8.2.5 32bit 2023-01-20-moode-r825-lite.img and it works!
I didn't try other versions. Stop wasting time 
I hope I will avoid initial issue with lost BT connections.
Posts: 12,080
Threads: 272
Joined: Mar 2018
Reputation:
461
I ran a test last night connecting and playing audio via Bluetooth from my Samsing TU7000 series TV to a moOde system and no issues whatsoever.
The CODEC used was SBC.
Code: pi@trx:~ $ bluealsa-aplay --list-pcms
bluealsa:DEV=A0:D7:F3:2A:12:2D,PROFILE=a2dp,SRV=org.bluealsa
[TV] Samsung TU700D 58 TV, audio-card, capture
A2DP (SBC): S16_LE 2 channels 48000 Hz
Posts: 14
Threads: 2
Joined: Jun 2022
Reputation:
0
(09-16-2023, 02:24 PM)Tim Curtis Wrote: I ran a test last night connecting and playing audio via Bluetooth from my Samsing TU7000 series TV to a moOde system and no issues whatsoever.
The CODEC used was SBC.
Code: pi@trx:~ $ bluealsa-aplay --list-pcms
bluealsa:DEV=A0:D7:F3:2A:12:2D,PROFILE=a2dp,SRV=org.bluealsa
[TV] Samsung TU700D 58 TV, audio-card, capture
A2DP (SBC): S16_LE 2 channels 48000 Hz
Tim, thank you a lot for useful command.
my output now is
Code: pavel@moode:~ $ bluealsa-aplay --list-pcms
bluealsa:SRV=org.bluealsa,DEV=20:3D:BD:F3:57:3B,PROFILE=a2dp
LG TV[[LG] webOS TV], audio-card, capture
A2DP (SBC): S16_LE 2 channels 48000 Hz
can you send more commands with descriptions for debugging BT. In case of BT issues.
Posts: 12,080
Threads: 272
Joined: Mar 2018
Reputation:
461
I don't have much experience debugging BLuetooth but here are some commands that can print various types of info.
bluealsa-cli --help
bluealsa-aplay --help
bluealsa --help
bluetooth --help
bluetoothctl --help
btmon --help
Posts: 5,575
Threads: 168
Joined: Apr 2018
Reputation:
208
(09-15-2023, 06:11 PM)Pavel Frolov Wrote: Hello,
I have raspberry pi 3b
...
Truly a Pi 3B and not a Pi 3B+?
Are you using both the WiFi and Bluetooth interfaces?
As soon as that model was released reports started showing up on the Raspberry Pi Forum about its WiFi and Bluetooth not working well together. Not everyone had a problem and not everyone who did saw the same behavior. There was lots of speculation but I don't know that specific issues were ever resolved at the software level.
There are some steps you could take to see if this is the cause of your problem.
1) try using an external Bluetooth adapter. You have to disable the internal Bluetooth interface in moOde's System Configuration menu.
2) alternatively, try using an external WiFi adapter. You have to disable the internal WiFi interface in moOde's System Configuration menu.
3) alternatively, try connecting to your local area network via the Ethernet interface instead of WiFi. Again, you can disable the internal WiFi interface in moOde's System Configuration menu.
Diagnosing Bluetooth problems from the command line without proper "sniffing" tools is tedious even for experienced Linux programmers.
I use later model Pis with 5GHz WiFi connections and a variety of either Bluetooth sources (e.g., smart phones, tablets, etc.) or Bluetooth sinks (e.g., speakers, headphones, etc.) and have no problem with the Bluetooth connection.
Regards,
Kent
|
