16:15:47.022+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0
16:15:47.022+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0 state changed from idle to playing
16:15:47.022+0900   375   375 D PULSEAUDIO: [pulseaudio] card.c: Setting card bluez_card.00_18_09_24_68_25 profile a2dp_sink to availability status yes
16:15:47.022+0900   375   375 D PULSEAUDIO: [pulseaudio] device-port.c: Setting port headset-output to status yes
16:15:47.027+0900   375   375 I PULSEAUDIO: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.00_18_09_24_68_25 because its transport state changed to playing
16:15:47.027+0900   375   375 I PULSEAUDIO: [pulseaudio] dbus-util.c: end processing dbus message.
16:15:47.062+0900   375   620 D PULSEAUDIO: [bluetooth] protocol-native.c: Requesting rewind due to rewrite.
16:15:47.067+0900   375   620 D PULSEAUDIO: [bluetooth] protocol-native.c: Requesting rewind due to rewrite.
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] dbus-util.c: start processing dbus message.
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: dbus: path=/MediaEndpoint/A2DPSource, interface=org.bluez.MediaEndpoint1, member=ClearConfiguration
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Clearing transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0 profile a2dp_sink
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0 state changed from playing to disconnected
16:19:35.027+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ========= sink [bluez_sink.00_18_09_24_68_25][2], bt_off_idx was [-1], now set to [2]
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] module-policy.c: stream[1], policy[(null)]. stream move sink[bluez_sink.00_18_09_24_68_25] =====> sink[null]
16:19:35.027+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ------- sink-input [1] was sink [bluez_sink.00_18_09_24_68_25][2] : Trying to mute!!!
16:19:35.027+0900   375   375 I PULSEAUDIO: [pulseaudio] module-policy.c: set_mute stream_idx:1 type:-1 direction:2 mute:1
16:19:35.032+0900   375   620 E PULSEAUDIO: [bluetooth] module-bluez5-device.c: Failed to write data to socket: Broken pipe
16:19:35.032+0900   375   620 I PULSEAUDIO: [bluetooth] module-bluez5-device.c: IO thread failed
16:19:35.032+0900   375   620 D PULSEAUDIO: [bluetooth] sink.c: Requesting rewind due to started move
16:19:35.032+0900   375   375 D PULSEAUDIO: [pulseaudio] volume.c: Assertion 'pa_cvolume_compatible_with_channel_map(v, from)' failed at pulse/volume.c:515, function pa_cvolume_remap.
16:19:35.032+0900   375   375 D PULSEAUDIO: [pulseaudio] sink-input.c: Trying to change sample rate
16:19:35.032+0900   375   375 D PULSEAUDIO: [pulseaudio] sink-input.c: Successfully moved sink input 1 to null.
16:19:35.032+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ------- sink-input [1], sink [null][1], bt_off_idx [2] : skip un-mute...
16:19:35.032+0900   375   375 I PULSEAUDIO: [pulseaudio] module-policy.c: stream[31], policy[(null)]. stream move sink[bluez_sink.00_18_09_24_68_25] =====> sink[null]
16:19:35.032+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ------- sink-input [31] was sink [bluez_sink.00_18_09_24_68_25][2] : Trying to mute!!!
16:19:35.032+0900   375   375 I PULSEAUDIO: [pulseaudio] module-policy.c: set_mute stream_idx:31 type:-1 direction:2 mute:1
16:19:35.032+0900   375   620 D PULSEAUDIO: [bluetooth] sink-input.c: Have to rewind 2840 bytes on implementor.
16:19:35.032+0900   375   620 D PULSEAUDIO: [bluetooth] sink.c: Requesting rewind due to started move
16:19:35.037+0900   375   375 I PULSEAUDIO: [pulseaudio] sink.c: SINK[bluez_sink.00_18_09_24_68_25] state changed (PA_SINK_RUNNING) => (PA_SINK_IDLE)
16:19:35.037+0900   375   413 D PULSEAUDIO: [null-sink] sink.c: Requesting rewind due to finished move
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] sink-input.c: Successfully moved sink input 31 to null.
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] sink.c: SINK[null] state changed (PA_SINK_SUSPENDED) => (PA_SINK_RUNNING)
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ------- sink-input [31], sink [null][1], bt_off_idx [2] : skip un-mute...
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: unload sink in dependencies
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-rescue-streams.c: No sink inputs to move away.
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] sink.c: SINK[bluez_sink.00_18_09_24_68_25] state changed (PA_SINK_IDLE) => (PA_SINK_UNLINKED)
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-rescue-streams.c: No source outputs to move away.
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-suspend-on-idle.c: source [0x408f1f00][3] is unlinked, now update pm
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-suspend-on-idle.c: [PM] suspend [source:3] ret[-1] list[0x409100d0] before:[sink:1][sink:2] after:[sink:1][sink:2]
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: ========= sink [bluez_sink.00_18_09_24_68_25][2]
16:19:35.042+0900   375   375 D PULSEAUDIO: [pulseaudio] module-policy.c: bt_off_idx is cleared to [-1]
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-suspend-on-idle.c: sink [0x408f05e8][2] is unlinked, now update pm
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-suspend-on-idle.c: [PM] suspend [sink:2] ret[-1] list[0x409100d0] before:[sink:1][sink:2] after:[sink:1]
16:19:35.042+0900   375   620 I PULSEAUDIO: [bluetooth] module-bluez5-device.c: IO thread shutting down
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-bluez5-device.c: Switching the profile to off due to IO thread failure.
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0 auto-released by BlueZ or already released
16:19:35.042+0900   375   375 I PULSEAUDIO: [pulseaudio] bluez5-util.c: Allow Release!
16:19:35.047+0900   375   375 E PULSEAUDIO: [pulseaudio] bluez5-util.c: Failed to release transport /org/bluez/hci0/dev_00_18_09_24_68_25/fd0: Method "Release" with signature "" on interface "org.bluez.MediaTransport1" doesn't exist
16:19:35.047+0900   375   375 I PULSEAUDIO: [pulseaudio] module-bluez5-device.c: Wait for sync with bluez's dbus signal
16:19:35.152+0900   375   375 I PULSEAUDIO: [pulseaudio] module-bluez5-device.c: Audio stream torn down
16:19:35.157+0900   375   375 I PULSEAUDIO: [pulseaudio] sink.c: Freeing sink 2 "bluez_sink.00_18_09_24_68_25"
16:19:35.157+0900   375   375 I PULSEAUDIO: [pulseaudio] source.c: Freeing source 3 "bluez_sink.00_18_09_24_68_25.monitor"
16:19:35.157+0900   375   375 I PULSEAUDIO: [pulseaudio] card.c: Changed profile of card 0 "bluez_card.00_18_09_24_68_25" to off
16:19:35.157+0900   375   375 E PULSEAUDIO: [pulseaudio] asyncmsgq.c: Assertion 'pa_atomic_load(&(a)->_ref) > 0' failed at pulsecore/asyncmsgq.c:176, function pa_asyncmsgq_get(). Aborting.