pulseaudio-hfp-v7-variant6.log

Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] client.c: Created 8 "Native client (UNIX socket client)"
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Protocol version: remote 33, local 33
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1001 success=1
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: SHM possible: yes
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Negotiated SHM: yes
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Memfd possible: yes
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] log.c: Invalid UTF-8 string following below:
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 Ки� each, total size is 64.0 Ми�, maximum usable slot size is 65472
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Enabling srbchannel...
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] module-augment-properties.c: Looking for .desktop file for python3.6m
Jan 13 12:50:49 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Client enabled srbchannel.


Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/adapter.c:connected_callback() hci0 device 5C:FB:7C:6A:01:38 connected eir_len 16
Jan 13 12:51:07 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/profile.c:ext_confirm() incoming connect from 5C:FB:7C:6A:01:38
Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/service.c:btd_service_ref() 0x557c1e2a38a0: ref=2
Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/profile.c:ext_confirm() Headset Voice gateway authorizing connection from 5C:FB:7C:6A:01:38
Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/agent.c:agent_ref() 0x557c1e2a8fb0: ref=2
Jan 13 12:51:07 node-calculate2 bluetoothd[5058]: src/agent.c:agent_authorize_service() authorize service request was sent for /org/bluez/hci0/dev_5C_FB_7C_6A_01_38


Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/agent.c:agent_ref() 0x557c1e2a8fb0: ref=3
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/profile.c:ext_auth() 5C:FB:7C:6A:01:38 authorized to connect to Headset Voice gateway
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/agent.c:agent_unref() 0x557c1e2a8fb0: ref=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/agent.c:agent_unref() 0x557c1e2a8fb0: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/profile.c:ext_connect() Headset Voice gateway connected to 5C:FB:7C:6A:01:38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e2a38a0: device 5C:FB:7C:6A:01:38 profile :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: disconnected -> connecting (0)
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: dbus: path=/Profile/HSPAGProfile, interface=org.bluez.Profile1, member=NewConnection
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: dbus: NewConnection path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38, fd=38, profile headset_head_unit
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: doing listen
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd38 state: disconnected -> idle
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd38 available for profile headset_head_unit
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e2a38a0: device 5C:FB:7C:6A:01:38 profile :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: connecting -> connected (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/device.c:device_profile_connected() :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb Success (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38 profile a2dp-sink
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:a2dp_sink_connect() path /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_ref() 0x557c1e2b6b30: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_ref() 0x557c1e2b6b30: ref=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2988d0: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_DISCONNECTED -> SINK_STATE_CONNECTING
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:sink_connect() stream creation in progress
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e29a460: device 5C:FB:7C:6A:01:38 profile a2dp-sink state changed: disconnected -> connecting (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 5C:FB:7C:6A:01:38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=672
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_register_remote_sep() seid 2 type 1 media 0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:register_remote_sep() Found remote SEP: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_register_remote_sep() seid 1 type 1 media 0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:register_remote_sep() Found remote SEP: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep1
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:session_cb() 
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in use 0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 2 type 1 media 0 in use 0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:discover_cb() err (nil)
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: RFCOMM << AT+VGS=10
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] backend-native.c: RFCOMM >> OK
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:discovery_complete() Discovery complete
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2988d0: ref=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2988d0: ref=3
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/media.c:media_endpoint_async_call() Calling SelectConfiguration: name = :1.34 path = /MediaEndpoint/A2DPSource/sbc
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2988d0: ref=2
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SelectConfiguration
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP 0x557c1e2a1990
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2988d0: ref=3
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_set_configuration() 0x557c1e2b6b30: int_seid=2, acp_seid=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2988d0: ref=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2988d0: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:session_cb() 
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setconf_cfm() Source 0x557c1e2a1990: Set_Configuration_Cfm
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2988d0: ref=2
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.34 path = /MediaEndpoint/A2DPSource/sbc
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaTransport1 found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SetConfiguration
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 state: disconnected -> idle
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 available for profile a2dp_sink
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2988d0: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:session_cb() 
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to 5C:FB:7C:6A:01:38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:handle_transport_connect() Flushable packets enabled
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:handle_transport_connect() sk 43, omtu 672, send buffer size 106496
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:open_cfm() Source 0x557c1e2a1990: Open_Cfm
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2988d0: ref=0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_free() 0x557c1e2988d0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_unref() 0x557c1e2b6b30: ref=1
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e29a460: device 5C:FB:7C:6A:01:38 profile a2dp-sink state changed: connecting -> connected (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/device.c:device_profile_connected() a2dp-sink Success (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38 profile avrcp-controller
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avrcp.c:avrcp_connect() path /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e29cc90: device 5C:FB:7C:6A:01:38 profile avrcp-controller state changed: disconnected -> connecting (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:btd_service_ref() 0x557c1e29a460: ref=3
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: plugins/policy.c:service_cb() Added a2dp-sink reconnect 0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_CONNECTING -> SINK_STATE_CONNECTED
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 State=TRANSPORT_STATE_IDLE Playing=0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 5C:FB:7C:6A:01:38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: Can't open input device: No such file or directory (2)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: AVRCP: failed to init uinput for 5C:FB:7C:6A:01:38
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avrcp.c:controller_init() 0x557c1e2b0f40 version 0x0105
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e29a810: device 5C:FB:7C:6A:01:38 profile audio-avrcp-target state changed: disconnected -> connected (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/player.c:media_player_controller_create() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/player0
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avrcp.c:target_init() 0x557c1e2947a0 version 0x0105
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/service.c:change_state() 0x557c1e29cc90: device 5C:FB:7C:6A:01:38 profile avrcp-controller state changed: connecting -> connected (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: src/device.c:device_profile_connected() avrcp-controller Success (0)
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
Jan 13 12:51:15 node-calculate2 bluetoothd[5058]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x31, company 0x001958 len 0x0005
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaPlayer1 found, skipping
Jan 13 12:51:15 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Timeout expired, and device /org/bluez/hci0/dev_5C_FB_7C_6A_01_38 still has disconnected profiles: handsfree
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38 autodetect_mtu=0
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluez5-device.c: device support HSP and HFP, selecting HFP only
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] module-card-restore.c: Restoring port latency offsets for card bluez_card.5C_FB_7C_6A_01_38.
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: Looking for initial profile for card bluez_card.5C_FB_7C_6A_01_38
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: a2dp_sink availability unknown
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: handsfree availability no
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: off availability yes
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: bluez_card.5C_FB_7C_6A_01_38: active_profile: a2dp_sink
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: Created 1 "bluez_card.5C_FB_7C_6A_01_38"
Jan 13 12:51:18 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.34
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_ref() 0x557c1e2b6b30: ref=2
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0x557c1e2a1990 locked
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_ref() 0x557c1e2b6b30: ref=3
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2b9800: ref=1
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0: TRANSPORT_STATE_IDLE -> TRANSPORT_STATE_REQUESTING
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_request_create() Request created: method=Acquire id=4
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_add() Owner :1.34 Request Acquire
Jan 13 12:51:18 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_transport_set_owner() Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 Owner :1.34
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:session_cb() 
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_parse_resp() START request succeeded
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:start_cfm() Source 0x557c1e2a1990: Start_Cfm
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0: fd(43) ready
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_remove() Owner :1.34 Request Acquire
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0: TRANSPORT_STATE_REQUESTING -> TRANSPORT_STATE_ACTIVE
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2b9800: ref=0
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_free() 0x557c1e2b9800
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_unref() 0x557c1e2b6b30: ref=2
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> STREAMING
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_CONNECTED -> SINK_STATE_PLAYING
Jan 13 12:51:19 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 State=TRANSPORT_STATE_ACTIVE Playing=1
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 acquired: fd 40
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 state: idle -> playing
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile a2dp_sink to availability status yes
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] device-port.c: Setting port headset-output to status yes
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] a2dp-codec-sbc.c: SBC parameters: allocation=Loudness, subbands=8, blocks=16, mode=JointStereo bitpool=40 codesize=512 frame_length=93
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: front-left: 47055 /  72%,   front-right: 47055 /  72%
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: Created sink 1 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink" with sample spec s16le 2-канальный 4410 and channel map front-left,front-right
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     bluetooth.protocol = "a2dp_sink"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.description = "JBL E45BT"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.api = "bluez"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.class = "sound"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.bus = "bluetooth"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.form_factor = "headset"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     bluez.class = "0x240404"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     bluez.alias = "JBL E45BT"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c:     device.intended_roles = "phone"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: Created source 1 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor" with sample spec s16le 2-канальный 4410 and channel map front-left,front-right
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.description = "Monitor of JBL E45BT"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.class = "monitor"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.api = "bluez"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.bus = "bluetooth"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.form_factor = "headset"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     bluez.class = "0x240404"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     bluez.alias = "JBL E45BT"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c:     device.intended_roles = "phone"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: IO Thread starting up
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] util.c: Failed to acquire real-time scheduling: Нет такого файла или каталога
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 resuming
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 7168
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [bluetooth] a2dp-codec-sbc.c: Using SBC codec implementation: MMX
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: state: INIT -> IDLE
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: state: INIT -> IDLE
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] core.c: default_source: auto_null.monitor -> bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink becomes idle, timeout in 5 seconds.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-always-sink.c: A new sink has been discovered. Unloading null-sink.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] core.c: default_sink: auto_null -> bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module.c: Loaded "module-bluez5-device" (index: #26; argument: "path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38 autodetect_mtu=0").
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module.c: Unloading "module-null-sink" (index: #15).
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: auto_null: state: SUSPENDED -> UNLINKED
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: auto_null.monitor: state: SUSPENDED -> UNLINKED
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [null-sink] module-null-sink.c: Thread shutting down
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: Freeing sink 0 "auto_null"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: Freeing source 0 "auto_null.monitor"
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] module.c: Unloaded "module-null-sink" (index: #15).
Jan 13 12:51:19 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 169931 us (= 29976 bytes) in audio stream
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] a2dp-codec-sbc.c: Bitpool has changed to 35
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 186757 us (= 32944 bytes) in audio stream
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] a2dp-codec-sbc.c: Bitpool has changed to 32
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 7168 to 8192
Jan 13 12:51:20 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 136462 us (= 24072 bytes) in audio stream
Jan 13 12:51:21 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166598 us (= 29388 bytes) in audio stream
Jan 13 12:51:21 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166394 us (= 29352 bytes) in audio stream
Jan 13 12:51:21 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166439 us (= 29360 bytes) in audio stream
Jan 13 12:51:22 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 165555 us (= 29204 bytes) in audio stream
Jan 13 12:51:22 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166439 us (= 29360 bytes) in audio stream
Jan 13 12:51:22 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166326 us (= 29340 bytes) in audio stream
Jan 13 12:51:23 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166507 us (= 29372 bytes) in audio stream
Jan 13 12:51:23 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 165442 us (= 29184 bytes) in audio stream
Jan 13 12:51:23 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 166530 us (= 29376 bytes) in audio stream
Jan 13 12:51:23 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Skipping 55487 us (= 9788 bytes) in audio stream
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink idle for too long, suspending ...
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0: TRANSPORT_STATE_ACTIVE -> TRANSPORT_STATE_SUSPENDING
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_ref() 0x557c1e2b6b30: ref=3
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_ref() 0x557c1e2b9d50: ref=1
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_request_create() Request created: method=Release id=5
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_add() Owner :1.34 Request Release
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:session_cb() 
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_parse_resp() SUSPEND request succeeded
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING -> OPEN
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_PLAYING -> SINK_STATE_CONNECTED
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 State=TRANSPORT_STATE_SUSPENDING Playing=0
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:suspend_cfm() Source 0x557c1e2a1990: Suspend_Cfm
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_request_reply() Request Release Reply Success
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_remove() Owner :1.34 Request Release
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0x557c1e2a1990 unlocked
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0: TRANSPORT_STATE_SUSPENDING -> TRANSPORT_STATE_IDLE
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 Owner :1.34
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/transport.c:media_owner_free() Owner :1.34
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_unref() 0x557c1e2b9d50: ref=0
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/a2dp.c:setup_free() 0x557c1e2b9d50
Jan 13 12:51:24 node-calculate2 bluetoothd[5058]: profiles/audio/avdtp.c:avdtp_unref() 0x557c1e2b6b30: ref=2
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [bluetooth] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 released
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [bluetooth] module-bluez5-device.c: Audio stream torn down
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: suspend_cause: (none) -> IDLE
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: state: IDLE -> SUSPENDED
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: suspend_cause: (none) -> IDLE
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: state: IDLE -> SUSPENDED
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0 state: playing -> idle
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile a2dp_sink to availability status unknown
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] device-port.c: Setting port headset-output to status unknown
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 13 12:51:24 node-calculate2 pulseaudio[5755]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd0
Jan 13 12:51:26 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:req_timeout() transaction 3 retry true
Jan 13 12:51:36 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:req_timeout() transaction 3 retry false
Jan 13 12:51:46 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:req_timeout() transaction 4 retry true
Jan 13 12:51:56 node-calculate2 bluetoothd[5058]: profiles/audio/avctp.c:req_timeout() transaction 4 retry false


Jan 13 12:52:24 node-calculate2 pulseaudio[5755]: [pulseaudio] protocol-native.c: Application "Blueman" requests card profile change. card = bluez_card.5C_FB_7C_6A_01_38, profile = handsfree
Jan 13 12:52:24 node-calculate2 pulseaudio[5755]: [pulseaudio] module-bluez5-device.c: Refused to switch profile to handsfree: Not connected

pulseaudio-hfp-v7-variant7.log

Jan 14 10:21:34 node-calculate2 dbus-daemon[4553]: [system] Activating service name='org.blueman.Mechanism' requested by ':1.56' (uid=1000 pid=5831 comm="/usr/bin/python3.6 /usr/bin/blueman-applet ") (using servicehelper)
Jan 14 10:21:35 node-calculate2 dbus-daemon[4553]: [system] Successfully activated service 'org.blueman.Mechanism'
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/rfkill.c:rfkill_event() RFKILL event idx 1 type 2 op 2 soft 0 hard 0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/rfkill.c:rfkill_event() RFKILL event idx 8 type 2 op 2 soft 0 hard 0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/rfkill.c:rfkill_event() RFKILL unblock for hci0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:set_mode() sending set mode command for index 0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:property_set_mode() sending Set Powered command for index 0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:dev_class_changed_callback() Class: 0x1c010c
Jan 14 10:21:35 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:new_settings_callback() Settings: 0x00000ad1
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:settings_changed() Changed settings: 0x00000001
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:settings_changed() Pending settings: 0x00000000
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:adapter_start() adapter /org/bluez/hci0 has been enabled
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:trigger_passive_scanning() 
Jan 14 10:21:35 node-calculate2 bluetoothd[5071]: src/adapter.c:property_set_mode_complete() Success (0x00)
Jan 14 10:21:35 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Properties changed in adapter /org/bluez/hci0


Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] client.c: Created 106 "Native client (UNIX socket client)"
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Protocol version: remote 33, local 33
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1001 success=1
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: SHM possible: yes
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Negotiated SHM: yes
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Memfd possible: yes
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] log.c: Invalid UTF-8 string following below:
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 Ки� each, total size is 64.0 Ми�, maximum usable slot size is 65472
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Enabling srbchannel...
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] module-augment-properties.c: Looking for .desktop file for python3.6m
Jan 14 10:21:39 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Client enabled srbchannel.


Jan 14 10:22:13 node-calculate2 bluetoothd[5071]: src/adapter.c:connected_callback() hci0 device 5C:FB:7C:6A:01:38 connected eir_len 16
Jan 14 10:22:13 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Properties changed in device /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 14 10:22:14 node-calculate2 bluetoothd[5071]: src/profile.c:ext_confirm() incoming connect from 5C:FB:7C:6A:01:38
Jan 14 10:22:14 node-calculate2 bluetoothd[5071]: src/service.c:btd_service_ref() 0x55651332a880: ref=2
Jan 14 10:22:14 node-calculate2 bluetoothd[5071]: src/profile.c:ext_confirm() Headset Voice gateway authorizing connection from 5C:FB:7C:6A:01:38
Jan 14 10:22:14 node-calculate2 bluetoothd[5071]: src/agent.c:agent_ref() 0x55651331cc10: ref=2
Jan 14 10:22:14 node-calculate2 bluetoothd[5071]: src/agent.c:agent_authorize_service() authorize service request was sent for /org/bluez/hci0/dev_5C_FB_7C_6A_01_38


Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/agent.c:agent_ref() 0x55651331cc10: ref=3
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/profile.c:ext_auth() 5C:FB:7C:6A:01:38 authorized to connect to Headset Voice gateway
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/agent.c:agent_unref() 0x55651331cc10: ref=2
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/agent.c:agent_unref() 0x55651331cc10: ref=1
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/profile.c:ext_connect() Headset Voice gateway connected to 5C:FB:7C:6A:01:38
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651332a880: device 5C:FB:7C:6A:01:38 profile :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: disconnected -> connecting (0)
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: dbus: path=/Profile/HSPAGProfile, interface=org.bluez.Profile1, member=NewConnection
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: dbus: NewConnection path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38, fd=34, profile headset_head_unit
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: doing listen
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 state: disconnected -> idle
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 available for profile headset_head_unit
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651332a880: device 5C:FB:7C:6A:01:38 profile :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb state changed: connecting -> connected (0)
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/device.c:device_profile_connected() :1.34/Profile/HSPHSProfile/00001108-0000-1000-8000-00805f9b34fb Success (0)
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38 profile a2dp-sink
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:a2dp_sink_connect() path /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_ref() 0x556513328280: ref=1
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_ref() 0x556513328280: ref=2
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=1
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_DISCONNECTED -> SINK_STATE_CONNECTING
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:sink_connect() stream creation in progress
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651335a5e0: device 5C:FB:7C:6A:01:38 profile a2dp-sink state changed: disconnected -> connecting (0)
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected signaling channel to 5C:FB:7C:6A:01:38
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP imtu=672, omtu=672
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_register_remote_sep() seid 2 type 1 media 0
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:register_remote_sep() Found remote SEP: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_register_remote_sep() seid 1 type 1 media 0
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:register_remote_sep() Found remote SEP: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep1
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaEndpoint1 found, skipping
Jan 14 10:22:21 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:session_cb() 
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_parse_resp() DISCOVER request succeeded
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 1 type 1 media 0 in use 0
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_discover_resp() seid 2 type 1 media 0 in use 0
Jan 14 10:22:21 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:discover_cb() err (nil)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:discovery_complete() Discovery complete
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=3
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/media.c:media_endpoint_async_call() Calling SelectConfiguration: name = :1.34 path = /MediaEndpoint/A2DPSource/sbc
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=2
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SelectConfiguration
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:a2dp_config() a2dp_config: selected SEP 0x55651335a330
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=3
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_set_configuration() 0x556513328280: int_seid=2, acp_seid=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=1
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:session_cb() 
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_parse_resp() SET_CONFIGURATION request succeeded
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setconf_cfm() Source 0x55651335a330: Set_Configuration_Cfm
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/media.c:media_endpoint_async_call() Calling SetConfiguration: name = :1.34 path = /MediaEndpoint/A2DPSource/sbc
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: IDLE -> CONFIGURED
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaTransport1 found, skipping
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource/sbc, interface=org.bluez.MediaEndpoint1, member=SetConfiguration
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 state: disconnected -> idle
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-discover.c: Loading module-bluez5-device path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38 autodetect_mtu=0
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-device.c: device supports HFP but disabling profile as requested
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-card-restore.c: Restoring port latency offsets for card bluez_card.5C_FB_7C_6A_01_38.
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Looking for initial profile for card bluez_card.5C_FB_7C_6A_01_38
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: headset_head_unit availability unknown
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: a2dp_sink availability unknown
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: off availability yes
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: bluez_card.5C_FB_7C_6A_01_38: active_profile: a2dp_sink
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Created 5 "bluez_card.5C_FB_7C_6A_01_38"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=1
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.34
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_ref() 0x556513328280: ref=3
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0x55651335a330 locked
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513325770: ref=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4: TRANSPORT_STATE_IDLE -> TRANSPORT_STATE_REQUESTING
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_request_create() Request created: method=Acquire id=28
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_add() Owner :1.34 Request Acquire
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_transport_set_owner() Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 Owner :1.34
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:session_cb() 
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_parse_resp() OPEN request succeeded
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_connect_cb() AVDTP: connected transport channel to 5C:FB:7C:6A:01:38
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:handle_transport_connect() Flushable packets enabled
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:handle_transport_connect() sk 42, omtu 672, send buffer size 106496
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:open_cfm() Source 0x55651335a330: Open_Cfm
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=1
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: CONFIGURED -> OPEN
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651335a5e0: device 5C:FB:7C:6A:01:38 profile a2dp-sink state changed: connecting -> connected (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/device.c:device_profile_connected() a2dp-sink Success (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: plugins/policy.c:policy_connect() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38 profile avrcp-controller
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avrcp.c:avrcp_connect() path /org/bluez/hci0/dev_5C_FB_7C_6A_01_38
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connecting
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651334e150: device 5C:FB:7C:6A:01:38 profile avrcp-controller state changed: disconnected -> connecting (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/service.c:btd_service_ref() 0x55651335a5e0: ref=3
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: plugins/policy.c:service_cb() Added a2dp-sink reconnect 0
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_CONNECTING -> SINK_STATE_CONNECTED
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 State=TRANSPORT_STATE_REQUESTING Playing=0
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:session_cb() 
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_parse_resp() START request succeeded
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:start_cfm() Source 0x55651335a330: Start_Cfm
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4: fd(42) ready
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_remove() Owner :1.34 Request Acquire
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4: TRANSPORT_STATE_REQUESTING -> TRANSPORT_STATE_ACTIVE
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513325770: ref=0
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_free() 0x556513325770
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_unref() 0x556513328280: ref=2
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> STREAMING
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_CONNECTED -> SINK_STATE_PLAYING
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 acquired: fd 40
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 state: idle -> playing
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile a2dp_sink to availability status yes
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-output to status yes
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] a2dp-codec-sbc.c: SBC parameters: allocation=Loudness, subbands=8, blocks=16, mode=JointStereo bitpool=40 codesize=512 frame_length=93
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: front-left: 47055 /  72%,   front-right: 47055 /  72%
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: Created sink 23 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink" with sample spec s16le 2-канальный 4410 and channel map front-left,front-right
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluetooth.protocol = "a2dp_sink"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.description = "JBL E45BT"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.api = "bluez"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.class = "sound"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.bus = "bluetooth"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.form_factor = "headset"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.class = "0x240404"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.alias = "JBL E45BT"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.intended_roles = "phone"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: Created source 29 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor" with sample spec s16le 2-канальный 4410 and channel map front-left,front-right
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.description = "Monitor of JBL E45BT"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.class = "monitor"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.api = "bluez"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.bus = "bluetooth"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.form_factor = "headset"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.class = "0x240404"
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 State=TRANSPORT_STATE_ACTIVE Playing=1
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.alias = "JBL E45BT"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.intended_roles = "phone"
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: IO Thread starting up
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [bluetooth] util.c: Failed to acquire real-time scheduling: Нет такого файла или каталога
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 resuming
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 7168
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: state: INIT -> IDLE
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: state: INIT -> IDLE
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_source: alsa_input.pci-0000_00_1f.3.analog-stereo -> bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink becomes idle, timeout in 5 seconds.
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_sink: alsa_output.pci-0000_00_1f.3.analog-stereo -> bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] module.c: Loaded "module-bluez5-device" (index: #40; argument: "path=/org/bluez/hci0/dev_5C_FB_7C_6A_01_38 autodetect_mtu=0").
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 available for profile a2dp_sink
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: RFCOMM << AT+VGS=15
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: RFCOMM >> OK
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:avctp_connect_cb() AVCTP: connected to 5C:FB:7C:6A:01:38
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: Can't open input device: No such file or directory (2)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: AVRCP: failed to init uinput for 5C:FB:7C:6A:01:38
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avrcp.c:controller_init() 0x556513340d00 version 0x0105
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x556513349970: device 5C:FB:7C:6A:01:38 profile audio-avrcp-target state changed: disconnected -> connected (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/player.c:media_player_controller_create() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/player0
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avrcp.c:target_init() 0x556513323e40 version 0x0105
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/service.c:change_state() 0x55651334e150: device 5C:FB:7C:6A:01:38 profile avrcp-controller state changed: connecting -> connected (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: src/device.c:device_profile_connected() avrcp-controller Success (0)
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:avctp_set_state() AVCTP Connected
Jan 14 10:22:22 node-calculate2 bluetoothd[5071]: profiles/audio/avrcp.c:handle_vendordep_pdu() AVRCP PDU 0x31, company 0x001958 len 0x0005
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Introspectable found, skipping
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.bluez.MediaPlayer1 found, skipping
Jan 14 10:22:22 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Unknown interface org.freedesktop.DBus.Properties found, skipping
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink idle for too long, suspending ...
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4: TRANSPORT_STATE_ACTIVE -> TRANSPORT_STATE_SUSPENDING
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_ref() 0x556513328280: ref=3
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_ref() 0x556513339640: ref=1
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_request_create() Request created: method=Release id=29
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_add() Owner :1.34 Request Release
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:session_cb() 
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_parse_resp() SUSPEND request succeeded
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING -> OPEN
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38: SINK_STATE_PLAYING -> SINK_STATE_CONNECTED
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 State=TRANSPORT_STATE_SUSPENDING Playing=0
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:suspend_cfm() Source 0x55651335a330: Suspend_Cfm
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_request_reply() Request Release Reply Success
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_remove() Owner :1.34 Request Release
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0x55651335a330 unlocked
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4: TRANSPORT_STATE_SUSPENDING -> TRANSPORT_STATE_IDLE
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 Owner :1.34
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/transport.c:media_owner_free() Owner :1.34
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_unref() 0x556513339640: ref=0
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/a2dp.c:setup_free() 0x556513339640
Jan 14 10:22:27 node-calculate2 bluetoothd[5071]: profiles/audio/avdtp.c:avdtp_unref() 0x556513328280: ref=2
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [bluetooth] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 released
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Audio stream torn down
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: suspend_cause: (none) -> IDLE
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: state: IDLE -> SUSPENDED
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: suspend_cause: (none) -> IDLE
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: state: IDLE -> SUSPENDED
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4 state: playing -> idle
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile a2dp_sink to availability status unknown
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-output to status unknown
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:22:27 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/sep2/fd4
Jan 14 10:22:32 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:req_timeout() transaction 3 retry true
Jan 14 10:22:42 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:req_timeout() transaction 3 retry false
Jan 14 10:22:52 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:req_timeout() transaction 4 retry true
Jan 14 10:23:02 node-calculate2 bluetoothd[5071]: profiles/audio/avctp.c:req_timeout() transaction 4 retry false


Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] protocol-native.c: Application "Blueman" requests card profile change. card = bluez_card.5C_FB_7C_6A_01_38, profile = headset_head_unit
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_sink: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink -> alsa_output.pci-0000_00_1f.3.analog-stereo
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink: state: SUSPENDED -> UNLINKED
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_source: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor -> alsa_input.pci-0000_00_1f.3.analog-stereo
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor: state: SUSPENDED -> UNLINKED
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: IO thread shutting down
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: Freeing sink 23 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: Freeing source 29 "bluez_sink.5C_FB_7C_6A_01_38.a2dp_sink.monitor"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: doing connect
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 acquired: fd 22
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 state: idle -> playing
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile headset_head_unit to availability status yes
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-output to status yes
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-input to status yes
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Restoring volume for sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: mono: 65536 / 100%
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: Created sink 24 "bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit" with sample spec s16le 1-канальный 8000 and channel map mono
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluetooth.protocol = "headset_head_unit"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.intended_roles = "phone"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.description = "JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.api = "bluez"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.class = "sound"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.bus = "bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.form_factor = "headset"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.class = "0x240404"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     bluez.alias = "JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c:     device.icon_name = "audio-headset-bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Restoring mute state for source bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: muted
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: Created source 30 "bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor" with sample spec s16le 1-канальный 8000 and channel map mono
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.description = "Monitor of JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.class = "monitor"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.api = "bluez"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.bus = "bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.form_factor = "headset"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.class = "0x240404"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.alias = "JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.intended_roles = "phone"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Restoring volume for source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: mono: 66352 / 101%
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: Created source 31 "bluez_source.5C_FB_7C_6A_01_38.headset_head_unit" with sample spec s16le 1-канальный 8000 and channel map mono
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluetooth.protocol = "headset_head_unit"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.intended_roles = "phone"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.description = "JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.string = "5C:FB:7C:6A:01:38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.api = "bluez"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.class = "sound"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.bus = "bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.form_factor = "headset"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.path = "/org/bluez/hci0/dev_5C_FB_7C_6A_01_38"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.class = "0x240404"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     bluez.alias = "JBL E45BT"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c:     device.icon_name = "audio-headset-bluetooth"
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: IO Thread starting up
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] util.c: Failed to acquire real-time scheduling: Нет такого файла или каталога
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 resuming
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 1056
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: INIT -> IDLE
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: state: INIT -> IDLE
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-device-restore.c: Could not set format on sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_sink: alsa_output.pci-0000_00_1f.3.analog-stereo -> bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: state: INIT -> IDLE
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-bluetooth-policy.c: Profile headset_head_unit cannot be selected for loopback
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: default_source: alsa_input.pci-0000_00_1f.3.analog-stereo -> bluez_source.5C_FB_7C_6A_01_38.headset_head_unit
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] backend-native.c: RFCOMM >> +VGM=15
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: bluez_card.5C_FB_7C_6A_01_38: active_profile: a2dp_sink -> headset_head_unit
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] module-card-restore.c: Storing card profile for card bluez_card.5C_FB_7C_6A_01_38.
Jan 14 10:23:26 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit idle for too long, suspending ...
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: (none) -> IDLE
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: state: IDLE -> SUSPENDED
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit idle for too long, suspending ...
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [bluetooth] backend-native.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 released
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [bluetooth] module-bluez5-device.c: Audio stream torn down
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: (none) -> IDLE
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: IDLE -> SUSPENDED
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: suspend_cause: (none) -> IDLE
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: state: IDLE -> SUSPENDED
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd34 state: playing -> idle
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile headset_head_unit to availability status unknown
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-output to status unknown
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] device-port.c: Setting port headset-input to status unknown
Jan 14 10:23:31 node-calculate2 pulseaudio[5748]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.


Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] client.c: Created 3 "Native client (UNIX socket client)"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Protocol version: remote 33, local 33
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Got credentials: uid=1000 gid=1001 success=1
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: SHM possible: yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Negotiated SHM: yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Memfd possible: yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Negotiated SHM type: shared memfd
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] log.c: Invalid UTF-8 string following below:
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] memblock.c: Using shared memfd memory pool with 1024 slots of size 64.0 Ки� each, total size is 64.0 Ми�, maximum usable slot size is 65472
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] srbchannel.c: SHM block is 65472 bytes, ringbuffer capacity is 2 * 32712 bytes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Enabling srbchannel...
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-augment-properties.c: Looking for .desktop file for pacat
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Client enabled srbchannel.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-intended-roles.c: Not setting device for stream pacat, because it lacks role.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\""  format.rate = "44100"  format.channels = "2"  format.channel_map = "\"front-left,front-right\""
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c: Trying to change sample spec
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes busy, resuming.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] backend-native.c: doing connect
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36 acquired: fd 42
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36 resuming
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Changing bluetooth buffer size: Changed from 106496 to 1056
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll!
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: IDLE -> (none)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: SUSPENDED -> IDLE
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: suspend_cause: IDLE -> (none)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: state: SUSPENDED -> IDLE
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c: Resampler:
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c:   rate 44100 -> 8000 (method speex-float-3)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c:   format s16le -> s16le (intermediate float32le)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c:   channels 2 -> 1 (resampling 1)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c: Channel matrix:
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c:        I00   I01 
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c:     +------------
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] resampler.c: O00 | 0.500 0.500
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] remap.c: Using stereo to mono remapping
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] speex.c: Choosing speex quality setting 3.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=2, prebuf=0, minreq=1 maxrewind=0
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=2, prebuf=0, minreq=2 maxrewind=0
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c: Created input 1 "pacat" on bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit with sample spec s16le 2-канальный 4410 and channel map front-left,front-right
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.name = "pacat"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     media.name = "pacat"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     native-protocol.peer = "UNIX socket client"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     native-protocol.version = "33"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.process.id = "12504"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.process.user = "sasha"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.process.host = "node-calculate2"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.process.binary = "pacat"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.language = "ru_RU.utf8"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     window.x11.display = ":0.0"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     application.process.machine_id = "9987f2595161290eff11f0d65adb309f"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c:     module-stream-restore.id = "sink-input-by-application-name:pacat"
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Requested tlength=2000.00 ms, minreq=20.00 ms
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Requested latency=1960.00 ms, Received latency=28.00 ms
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=352800, base=4, prebuf=349276, minreq=3528 maxrewind=0
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=352800, base=4, prebuf=349276, minreq=3528 maxrewind=0
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Final latency 2028.00 ms = 1960.00 ms + 2*20.00 ms + 28.00 ms
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: IDLE -> RUNNING
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36 state: idle -> playing
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile headset_head_unit to availability status yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] device-port.c: Setting port headset-output to status yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] device-port.c: Setting port headset-input to status yes
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-bluez5-device.c: Resuming source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit because its transport state changed to playing
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: IDLE -> (none)
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: state: SUSPENDED -> IDLE
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit because its transport state changed to playing
Jan 14 10:29:05 node-calculate2 pulseaudio[12372]: [bluetooth] protocol-native.c: Requesting rewind due to end of underrun.
Jan 14 10:29:10 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Source bluez_source.5C_FB_7C_6A_01_38.headset_head_unit idle for too long, suspending ...
Jan 14 10:29:10 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: (none) -> IDLE
Jan 14 10:29:10 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_source.5C_FB_7C_6A_01_38.headset_head_unit: state: IDLE -> SUSPENDED


Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: RUNNING -> IDLE
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit becomes idle, timeout in 5 seconds.
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] sink-input.c: Freeing input 1 "pacat"
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] client.c: Freed 3 "pacat"
Jan 14 10:29:16 node-calculate2 pulseaudio[12372]: [pulseaudio] protocol-native.c: Connection died.


Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit idle for too long, suspending ...
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [bluetooth] backend-native.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36 released
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [bluetooth] module-bluez5-device.c: Audio stream torn down
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: suspend_cause: (none) -> IDLE
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] sink.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit: state: IDLE -> SUSPENDED
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: suspend_cause: (none) -> IDLE
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] source.c: bluez_sink.5C_FB_7C_6A_01_38.headset_head_unit.monitor: state: IDLE -> SUSPENDED
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] core.c: Hmm, no streams around, trying to vacuum.
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_5C_FB_7C_6A_01_38/fd36 state: playing -> idle
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] card.c: Setting card bluez_card.5C_FB_7C_6A_01_38 profile headset_head_unit to availability status unknown
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] device-port.c: Setting port headset-output to status unknown
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] device-port.c: Setting port headset-input to status unknown
Jan 14 10:29:21 node-calculate2 pulseaudio[12372]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event.
Jan 14 10:23:36 node-calculate2 pulseaudio[5748]: [pulseaudio] module-card-restore.c: Synced.