Nov 30 15:07:03 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 96 Nov 30 15:07:03 ubuntu-phablet powerd[955]: 2622542 slow avg 245.705476 fast avg 246.621922 last 247.000000 Nov 30 15:07:05 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:05 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:05 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:07:05 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:07:05 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:07:05 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:07:07 ubuntu-phablet powerd[955]: 2626802 slow avg 246.209487 fast avg 246.018387 last 246.000000 Nov 30 15:07:08 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 80 Nov 30 15:07:11 ubuntu-phablet bluetoothd[893]: src/adapter.c:device_found_callback() hci0 addr A0:18:28:F3:DD:08, rssi -78 flags 0x0000 eir_len 15 Nov 30 15:07:11 ubuntu-phablet bluetoothd[893]: src/device.c:device_set_legacy() legacy 0 Nov 30 15:07:12 ubuntu-phablet powerd[955]: 2631691 slow avg 246.691428 fast avg 247.031680 last 247.000000 Nov 30 15:07:15 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:15 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:16 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:07:16 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:07:16 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:07:16 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength -1 Nov 30 15:07:16 ubuntu-phablet powerd[955]: 2635953 slow avg 247.093793 fast avg 247.017793 last 247.000000 Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength -1 Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: 4, id: 1, number: XXXXXXXXXXX called_number: , called_name Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Did not find a call with id: 1 Nov 30 15:07:19 ubuntu-phablet powerd[955]: turn_display_on(1) Nov 30 15:07:19 ubuntu-phablet powerd[955]: signalling activity via HAL Nov 30 15:07:19 ubuntu-phablet powerd[955]: incoming call Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile headset_head_unit for card (current a2dp_sink) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/sink5 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink removed from object /org/pulseaudio/core1/sink5 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort removed from object /org/pulseaudio/core1/sink5/port0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [bluetooth] sink.c: Requesting rewind due to started move Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=250,00 ms, minreq=20,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=210,00 ms, Received latency=210,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 0 to sink.primary. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: Successfully moved sink input 0 "QtPulseAudio:2666" to sink.primary. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [bluetooth] sink.c: Requesting rewind due to started move Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=90,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 6 to sink.primary. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: Successfully moved sink input 6 "media-hub-server" to sink.primary. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/source7 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source removed from object /org/pulseaudio/core1/source7 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: No source outputs to move away. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO thread shutting down Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit transport 0x671db8 transport state idle Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /card_2 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Acquiring transport from ofono for card /card_2 Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: plugins/hfp_ag_bluez5.c:hfp_card_connect() Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: plugins/hfp_ag_bluez5.c:codec_negotiation_done_cb() err 0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile off for card (current headset_head_unit) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to off Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Freeing sink 5 "bluez_sink.00_18_16_49_97_81" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Freeing source 7 "bluez_sink.00_18_16_49_97_81.monitor" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port 16 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport true (ret 1) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: SCO sink not available. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-bluetooth_sco Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port 2147483656 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: set_parameters(): routing=2147483656 (AUDIO_DEVICE_IN_BLUETOOTH_SCO_HEADSET|AUDIO_DEVICE_IN_ALL_SCO : 0x80000008) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-bluetooth_sco_headset Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:07:19 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:19 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:19 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:19 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:19 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:19 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:19 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:19 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.876016]binder_set_nice: 5 callbacks suppressed Nov 30 15:07:19 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:07:19 ubuntu-phablet ofonod[2045]: src/handsfree-audio.c:send_new_connection() 0x210f060, fd: 11, codec: 1 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: dbus: path=/HandsfreeAudioAgent, interface=org.ofono.HandsfreeAudioAgent, member=NewConnection Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: New audio connection on card /card_2 (fd=64, codec=1) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /card_2 state changed from idle to playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit has changed to playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile headset_head_unit for card (current off) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit transport 0x671db8 transport state playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /card_2 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport /card_2 acquired: fd 64 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Configuring transport for profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport for profile headset_head_unit successfully setup Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed no, stream fd 64) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Installing monitor for SCO stream Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit changed to playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile headset_head_unit to availability status yes Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status yes Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-input to status yes Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport already acquired Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport for profile headset_head_unit Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming source source.fake.sco because its transport state changed to playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Suspend cause of source source.fake.sco is 0x0000, resuming Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Source source.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source source.fake.sco state has changed Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming sink sink.fake.sco because its transport state changed to playing Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0000, resuming Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "44100" format.channels = "2" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.primary is 0x0000, resuming Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Resuming... Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Routing: set_parameters(): routing=16; (0x00000010) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.primary.monitor state has changed Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 10 "pulsesink probe" on sink.primary with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "pulsesink probe" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=272,11 ms, minreq=20,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=116,05 ms, Received latency=23,31 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 272,11 ms = 208,80 ms + 2*20,00 ms + 23,31 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream10 Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948410]AudDrv_btcvsd_ioctl cmd = 0xc00443e0 arg = 0 Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948426]AudDrv_btcvsd_Allocate_Buffer(+) isRX=0 Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948472]AudDrv_btcvsd_Allocate_Buffer(-) Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948738]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 3 Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948748]AudDrv SET_BTCVSD_STATE Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948756]SET_BTCVSD_STATE set btsco.uTXState to 0x3x Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948764]SET_BTCVSD_STATE enable BT IRQ disableBTirq = 1 Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948780]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow TRUE!!! Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.948790]Enable_CVSD_Wakeup Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.953341]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow TRUE!!! Nov 30 15:07:19 ubuntu-phablet kernel: [ 2638.975841]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow FALSE!!! Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream10 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 10 "pulsesink probe" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\"" format.channels = "2" format.rate = "44100" format.channel_map = "\"front-left,front-right\"" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Trying to change sample rate Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Rate changed to 44100 Hz Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Resampler: Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: rate 44100 -> 44100 (method copy) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: format float32le -> s16le (intermediate s16le) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: channels 2 -> 2 (resampling 2) Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 11 "Playback Stream" on sink.primary with sample spec float32le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "Playback Stream" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=23,31 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 200,00 ms = 156,69 ms + 2*10,00 ms + 23,31 ms Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream11 Nov 30 15:07:19 ubuntu-phablet powerd[955]: handle_requestSysState from :1.54 (media-hub-playback_lock) - ACTIVE (1) Nov 30 15:07:19 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.54 Nov 30 15:07:19 ubuntu-phablet powerd[955]: watching :1.54 to see when it disappears on dbus Nov 30 15:07:19 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:19 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:19 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:19 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:19 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to uncorking Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:19 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:20 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:20 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:07:20 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:20 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:07:20 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:20 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:21 ubuntu-phablet powerd[955]: 2640891 slow avg 246.988781 fast avg 246.549603 last 246.500000 Nov 30 15:07:21 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 70 Nov 30 15:07:19 ubuntu-phablet bluetoothd[893]: message repeated 3 times: [ profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46] Nov 30 15:07:21 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:07:21 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:07:21 ubuntu-phablet kernel: [ 2641.026556][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:07:21 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:07:21 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:07:22 ubuntu-phablet kernel: [ 2641.777056][mtk_net][ipv6]receive RA with o bit! Nov 30 15:07:22 ubuntu-phablet kernel: [ 2641.777085][mtk_net][ipv6]receive RA with m bit! Nov 30 15:07:22 ubuntu-phablet kernel: [ 2641.777114][mtk_net][ipv6]RDNSS, ignore RA with o bit! Nov 30 15:07:23 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 90f3d593-7f47-4ab8-bb17-f565f2817826 Nov 30 15:07:23 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:23 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:23 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:23 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:23 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:23 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:23 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:23 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:23 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:23 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:23 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:23 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:23 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:23 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:23 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:23 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:23 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:23 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:24 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 58 Nov 30 15:07:20 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Source source.fake.sco idle for too long, suspending ... Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Suspend cause of source source.fake.sco is 0x0004, suspending Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source source.fake.sco state has changed Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco idle for too long, suspending ... Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0004, suspending Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport true (ret 1) Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspend prevention active, not closing SCO over PCM Nov 30 15:07:24 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:07:25 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:25 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:25 ubuntu-phablet powerd[955]: 2644892 slow avg 246.634945 fast avg 246.502490 last 246.500000 Nov 30 15:07:27 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:27 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:27 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 3c7d4a8b-166d-45b4-8b0a-29dfb92c0697 Nov 30 15:07:27 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:27 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:27 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:27 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:27 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:27 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:27 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:27 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:27 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:27 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:27 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:27 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:27 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:27 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:27 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:27 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:28 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 54 Nov 30 15:07:30 ubuntu-phablet powerd[955]: 2650044 slow avg 247.025494 fast avg 247.005964 last 247.000000 Nov 30 15:07:31 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:31 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:31 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 31ad201c-54f8-41bd-a3c5-758e84abdb77 Nov 30 15:07:31 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:31 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:31 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:31 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:31 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:31 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:31 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:31 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:31 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 58 Nov 30 15:07:31 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:31 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:31 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:31 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:31 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:31 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:31 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:31 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:32 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:07:32 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:07:32 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:07:33 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 54 Nov 30 15:07:33 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:33 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:34 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:34 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:35 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:35 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:07:35 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: ddb92bd2-e586-45f3-b676-30cfdd09853b Nov 30 15:07:35 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:35 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:35 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:35 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:35 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:35 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:35 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:35 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to corking Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:35 ubuntu-phablet powerd[955]: 2654732 slow avg 246.855446 fast avg 246.988091 last 247.000000 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream11 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 11 "Playback Stream" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "44100" format.channels = "2" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 12 "pulsesink probe" on sink.primary with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "pulsesink probe" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=272,11 ms, minreq=20,00 ms Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=116,05 ms, Received latency=23,31 ms Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 272,11 ms = 208,80 ms + 2*20,00 ms + 23,31 ms Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:07:35 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:35 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:35 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:35 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:35 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:35 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:35 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:35 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:35 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream12 Nov 30 15:07:36 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength -1 Nov 30 15:07:36 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:play() Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream12 Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 12 "pulsesink probe" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\"" format.channels = "2" format.rate = "44100" format.channel_map = "\"front-left,front-right\"" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Trying to change sample rate Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Rate changed to 44100 Hz Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Resampler: Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: rate 44100 -> 44100 (method copy) Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: format float32le -> s16le (intermediate s16le) Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: channels 2 -> 2 (resampling 2) Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 13 "Playback Stream" on sink.primary with sample spec float32le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "Playback Stream" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:07:36 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=23,31 ms Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 200,00 ms = 156,69 ms + 2*10,00 ms + 23,31 ms Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream13 Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:position_changed() sender=:1.29 path=/_1_46 Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:set_position() Position=0 Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: profiles/audio/avrcp.c:avrcp_player_event() id=4 Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to uncorking Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:07:37 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:07:38 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:07:38 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:07:38 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 64 Nov 30 15:07:39 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 8875194d-f00b-4cfb-9b01-14de272102ad Nov 30 15:07:39 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:39 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:39 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:39 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:39 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:39 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:39 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:39 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:39 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:07:39 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:07:39 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:07:39 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:39 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:07:39 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:39 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:39 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:40 ubuntu-phablet powerd[955]: 2659415 slow avg 244.858740 fast avg 241.218789 last 240.000000 Nov 30 15:07:40 ubuntu-phablet ofonod[2045]: drivers/rilmodem/voicecall.c:ril_answer() Answering current call Nov 30 15:07:40 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_SPEECH_INFO Nov 30 15:07:37 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to corking Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:40 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: 0, id: 1, number: XXXXXXXXXXX called_number: , called_name Nov 30 15:07:40 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Found call with id: 1 Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-droid-card.c: Setting new virtual profile. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port to parking Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-parking Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port to parking Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-parking Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-droid-card.c: Set mode to AUDIO_MODE_IN_CALL. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Set voice control - use_voice_volume: 0, enable: 1 Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Using voice volume control for sink.primary Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "44100" format.channels = "2" format.channel_map = "\"front-left,front-right\"" Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:phone. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 14 "Virtual Stream for Voice Volume Control (Droid)" on sink.primary with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "Virtual Stream for Voice Volume Control (Droid)" Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "phone" Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148651]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 4 Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148665]AudDrv SET_BTCVSD_STATE Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148673]SET_BTCVSD_STATE set btsco.uTXState to 0x4x Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148749]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 0 Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148757]AudDrv SET_BTCVSD_STATE Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148764]SET_BTCVSD_STATE set btsco.uTXState to 0x0x Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148772]SET_BTCVSD_STATE disable BT IRQ disableBTirq = 0 Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148783]Disable_CVSD_Wakeup Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148792]AudDrv_btcvsd_ioctl cmd = 0xc00443e0 arg = 1 Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148800]AudDrv_btcvsd_Free_Buffer(+) isRX=0 Nov 30 15:07:40 ubuntu-phablet kernel: [ 2660.148818]AudDrv_btcvsd_Free_Buffer(-) Nov 30 15:07:40 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:phone" Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-cork.c: Found a 'phone' stream that will trigger the auto-cork. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Created virtual sink input for voice call volume control. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Set voice volume 1,000000 Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Disabling source routing changes. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 0 "droid_card.primary" to voicecall Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream14 Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-cork.c: Found a 'phone' stream that will trigger the auto-cork. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream13 Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 13 "Playback Stream" Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port 16 Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Routing: set_parameters(): routing=16; (0x00000010) Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-bluetooth_sco Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port 2147483656 Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Skipping routing change. Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-bluetooth_sco_headset Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:07:41 ubuntu-phablet kernel: [ 2660.259275]init: sys_prop: permission denied uid:32011 name:af.modem_1.status Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry2, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:41 ubuntu-phablet kernel: [ 2660.276384]init: sys_prop: permission denied uid:32011 name:af.modem_1.status Nov 30 15:07:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:07:41 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:07:41 ubuntu-phablet powerd[955]: handle_clearSysState from :1.54, cookie: 22757f68-04a3-4953-8058-a9df52855da3 Nov 30 15:07:41 ubuntu-phablet powerd[955]: clear_sys_request: media-hub-playback_lock - :1.54 Nov 30 15:07:41 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.54 Nov 30 15:07:41 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.54 is now 0 Nov 30 15:07:41 ubuntu-phablet powerd[955]: no longer watching :1.54, there are no more requests Nov 30 15:07:41 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:41 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:41 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:41 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:41 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 61 Nov 30 15:07:42 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 0e97fc13-0cef-4890-8248-44ab5d1e766a Nov 30 15:07:42 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:07:42 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:07:42 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:07:42 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:07:42 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:07:42 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:07:42 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:07:42 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:07:44 ubuntu-phablet powerd[955]: 2663417 slow avg 245.127235 fast avg 246.930815 last 247.000000 Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: Server: < AT+IPHONEACCEV=2,1,6,2,0\r Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: Server: > \r\nERROR\r\n Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: Server: < AT+IPHONEACCEV=2,1,6,2,0\r Nov 30 15:07:45 ubuntu-phablet ofonod[2045]: Server: > \r\nERROR\r\n Nov 30 15:07:46 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 58 Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary idle for too long, suspending ... Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.primary is 0x0004, suspending Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Device suspended. Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.primary.monitor state has changed Nov 30 15:07:46 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory Nov 30 15:07:46 ubuntu-phablet kernel: [ 2665.423247]init: sys_prop: permission denied uid:32011 name:af.modem_1.status Nov 30 15:07:41 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:07:48 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:07:48 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:07:48 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:07:48 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 77 Nov 30 15:07:49 ubuntu-phablet powerd[955]: 2668186 slow avg 245.453242 fast avg 245.491521 last 245.500000 Nov 30 15:07:50 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 64 Nov 30 15:07:53 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 58 Nov 30 15:07:53 ubuntu-phablet powerd[955]: 2672697 slow avg 246.350169 fast avg 246.503819 last 246.500000 Nov 30 15:07:53 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:07:53 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:07:53 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:07:53 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:07:54 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:play() Nov 30 15:07:54 ubuntu-phablet bluetoothd[893]: profiles/audio/avctp.c:handle_panel_passthrough() AV/C: PLAY pressed Nov 30 15:07:54 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_SPEECH_INFO Nov 30 15:07:54 ubuntu-phablet bluetoothd[893]: profiles/audio/avctp.c:handle_panel_passthrough() AV/C: PLAY released Nov 30 15:07:54 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_disconnected() Got disconnection event for id: 1, reason: 1 Nov 30 15:07:54 ubuntu-phablet powerd[955]: turn_display_on(1) Nov 30 15:07:54 ubuntu-phablet powerd[955]: signalling activity via HAL Nov 30 15:07:54 ubuntu-phablet powerd[955]: call removed Nov 30 15:07:55 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:07:55 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port to parking Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-parking Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port to parking Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-parking Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-droid-card.c: Set mode to AUDIO_MODE_NORMAL. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Set voice control - use_voice_volume: 1, enable: 0 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Removed virtual stream. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Using software volume control for sink.primary Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Enabling source routing changes. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 0 "droid_card.primary" to primary-primary Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream14 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 14 "Virtual Stream for Voice Volume Control (Droid)" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:07:56 ubuntu-phablet kernel: [ 2675.607623]init: sys_prop: permission denied uid:32011 name:af.modem_1.status Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port 2 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport false (ret 0) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming SCO sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0000, resuming Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-speaker Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port 2147483652 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: set_parameters(): routing=2147483652 (AUDIO_DEVICE_IN_BUILTIN_MIC : 0x80000004) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-builtin_mic Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile a2dp_sink for card (current headset_head_unit) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport /card_2 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Trying to release transport for card /card_2 (fd 64) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Transport available for card /card_2 (fd 64), releasing now Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Successfully released transport for card /card_2 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /card_2 state changed from playing to idle Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit has changed to idle Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit changed to idle Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile headset_head_unit to availability status unknown Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status unknown Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-input to status unknown Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport for profile headset_head_unit Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspending source source.fake.sco because the remote end closed the stream Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspending sink sink.fake.sco because the remote end closed the stream Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0001, suspending Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport false (ret 0) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Closing SCO over PCM Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport /card_2 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Trying to release transport for card /card_2 (fd -1) Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Transport /card_2 already released Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Audio stream torn down Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Audio stream torn down Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile a2dp_sink transport 0x6763b0 transport state idle Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.37 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0xb8ad4568 locked Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_ref() 0xb8adaff0: ref=3 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_ref() 0xb8ad6f08: ref=1 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_IDLE -> TRANSPORT_STATE_REQUESTING Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_create() Request created: method=Acquire id=16 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_add() Owner :1.37 Request Acquire Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_transport_set_owner() Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Owner :1.37 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:session_cb() Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_parse_resp() START request succeeded Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:start_cfm() Source 0xb8ad4568: Start_Cfm Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_remove() Owner :1.37 Request Acquire Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_REQUESTING -> TRANSPORT_STATE_ACTIVE Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_unref() 0xb8ad6f08: ref=0 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_free() 0xb8ad6f08 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_unref() 0xb8adaff0: ref=2 Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> STREAMING Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81: SINK_STATE_CONNECTED -> SINK_STATE_PLAYING Nov 30 15:07:56 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 State=TRANSPORT_STATE_ACTIVE Playing=1 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 acquired: fd 64 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Configuring transport for profile a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: SBC parameters: allocation=0, subbands=1, blocks=3, bitpool=53 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport for profile a2dp_sink successfully setup Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Created sink 6 "bluez_sink.00_18_16_49_97_81" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluetooth.protocol = "a2dp_sink" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.description = "MD-HPBT01" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.string = "00:18:16:49:97:81" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.api = "bluez" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.class = "sound" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.bus = "bluetooth" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.form_factor = "headset" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.path = "/org/bluez/hci0/dev_00_18_16_49_97_81" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.class = "0x240404" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.alias = "MD-HPBT01" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.icon_name = "audio-headset-bluetooth" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.intended_roles = "phone" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Created source 8 "bluez_sink.00_18_16_49_97_81.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.description = "Monitor of MD-HPBT01" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.class = "monitor" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.string = "00:18:16:49:97:81" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.api = "bluez" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.bus = "bluetooth" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.form_factor = "headset" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.path = "/org/bluez/hci0/dev_00_18_16_49_97_81" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.class = "0x240404" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.alias = "MD-HPBT01" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.icon_name = "audio-headset-bluetooth" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.intended_roles = "phone" Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO Thread starting up Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] core-util.c: SCHED_RR|SCHED_RESET_ON_FORK worked. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] core-util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 resuming Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Configuring MTU for transport of profile a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll! Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink bluez_sink.00_18_16_49_97_81 state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source bluez_sink.00_18_16_49_97_81.monitor state has changed Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source8 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source8 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort added for object /org/pulseaudio/core1/sink6/port0 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink6 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink6 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink.c: Requesting rewind due to started move Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=250,00 ms, minreq=20,00 ms Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=210,00 ms, Received latency=210,00 ms Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 0 to bluez_sink.00_18_16_49_97_81. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-switch-on-connect.c: Successfully moved sink input 0 "QtPulseAudio:2666" to bluez_sink.00_18_16_49_97_81. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink.c: Requesting rewind due to started move Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=90,00 ms Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 6 to bluez_sink.00_18_16_49_97_81. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-switch-on-connect.c: Successfully moved sink input 6 "media-hub-server" to bluez_sink.00_18_16_49_97_81. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 state changed from idle to playing Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink has changed to playing Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink changed to playing Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile a2dp_sink to availability status yes Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status yes Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport already acquired Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport for profile a2dp_sink Nov 30 15:07:56 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.00_18_16_49_97_81 because its transport state changed to playing Nov 30 15:07:57 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 96 Nov 30 15:07:58 ubuntu-phablet powerd[955]: 2677593 slow avg 246.862448 fast avg 246.992491 last 247.000000 Nov 30 15:07:59 ubuntu-phablet kernel: [ 2678.692568][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:07:59 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 80 Nov 30 15:08:00 ubuntu-phablet kernel: [ 2679.538457][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:00 ubuntu-phablet kernel: [ 2680.046379][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco idle for too long, suspending ... Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 idle for too long, suspending ... Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink bluez_sink.00_18_16_49_97_81 is 0x0004, suspending Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_ACTIVE -> TRANSPORT_STATE_SUSPENDING Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_ref() 0xb8adaff0: ref=3 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_ref() 0xb8ad3068: ref=1 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_create() Request created: method=Release id=17 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_add() Owner :1.37 Request Release Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:session_cb() Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_parse_resp() SUSPEND request succeeded Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING -> OPEN Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81: SINK_STATE_PLAYING -> SINK_STATE_CONNECTED Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 State=TRANSPORT_STATE_SUSPENDING Playing=0 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:suspend_cfm() Source 0xb8ad4568: Suspend_Cfm Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_reply() Request Release Reply Success Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_remove() Owner :1.37 Request Release Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0xb8ad4568 unlocked Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [bluetooth] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 released Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Audio stream torn down Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_SUSPENDING -> TRANSPORT_STATE_IDLE Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Owner :1.37 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_free() Owner :1.37 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_unref() 0xb8ad3068: ref=0 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_free() 0xb8ad3068 Nov 30 15:08:01 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_unref() 0xb8adaff0: ref=2 Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink bluez_sink.00_18_16_49_97_81 state has changed Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source bluez_sink.00_18_16_49_97_81.monitor state has changed Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 state changed from playing to idle Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink has changed to idle Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink changed to idle Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile a2dp_sink to availability status unknown Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status unknown Nov 30 15:08:01 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:02 ubuntu-phablet powerd[955]: 2681682 slow avg 247.090729 fast avg 246.624815 last 246.500000 Nov 30 15:08:04 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:08:04 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:08:04 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:08:05 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:05 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:06 ubuntu-phablet powerd[955]: 2685982 slow avg 247.742952 fast avg 247.258781 last 247.000000 Nov 30 15:08:09 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:08:09 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:08:09 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:08:09 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:08:10 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength -1 Nov 30 15:08:10 ubuntu-phablet powerd[955]: 2690113 slow avg 247.421260 fast avg 247.060106 last 247.000000 Nov 30 15:08:12 ubuntu-phablet bluetoothd[893]: src/adapter.c:device_found_callback() hci0 addr A0:18:28:F3:DD:08, rssi -83 flags 0x0000 eir_len 15 Nov 30 15:08:12 ubuntu-phablet bluetoothd[893]: src/device.c:device_set_legacy() legacy 0 Nov 30 15:08:13 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: 4, id: 1, number: XXXXXXXXXXX called_number: , called_name Nov 30 15:08:13 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_notify() Did not find a call with id: 1 Nov 30 15:08:13 ubuntu-phablet powerd[955]: turn_display_on(1) Nov 30 15:08:13 ubuntu-phablet powerd[955]: signalling activity via HAL Nov 30 15:08:13 ubuntu-phablet powerd[955]: incoming call Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile headset_head_unit for card (current a2dp_sink) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/sink6 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink removed from object /org/pulseaudio/core1/sink6 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort removed from object /org/pulseaudio/core1/sink6/port0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [bluetooth] sink.c: Requesting rewind due to started move Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=250,00 ms, minreq=20,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=210,00 ms, Received latency=210,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 0 to sink.primary. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: Successfully moved sink input 0 "QtPulseAudio:2666" to sink.primary. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [bluetooth] sink.c: Requesting rewind due to started move Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=90,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 6 to sink.primary. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: Successfully moved sink input 6 "media-hub-server" to sink.primary. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device removed from object /org/pulseaudio/core1/source8 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source removed from object /org/pulseaudio/core1/source8 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-rescue-streams.c: No source outputs to move away. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO thread shutdown requested, stopping cleanly Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO thread shutting down Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit transport 0x671db8 transport state idle Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /card_2 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Acquiring transport from ofono for card /card_2 Nov 30 15:08:13 ubuntu-phablet ofonod[2045]: plugins/hfp_ag_bluez5.c:hfp_card_connect() Nov 30 15:08:13 ubuntu-phablet ofonod[2045]: plugins/hfp_ag_bluez5.c:codec_negotiation_done_cb() err 0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile off for card (current headset_head_unit) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to off Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Freeing sink 6 "bluez_sink.00_18_16_49_97_81" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Freeing source 8 "bluez_sink.00_18_16_49_97_81.monitor" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port 16 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport true (ret 1) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: SCO sink not available. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-bluetooth_sco Nov 30 15:08:13 ubuntu-phablet ofonod[2045]: src/handsfree-audio.c:send_new_connection() 0x210f060, fd: 11, codec: 1 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: dbus: path=/HandsfreeAudioAgent, interface=org.ofono.HandsfreeAudioAgent, member=NewConnection Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: New audio connection on card /card_2 (fd=64, codec=1) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /card_2 state changed from idle to playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit has changed to playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile headset_head_unit for card (current off) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile headset_head_unit transport 0x671db8 transport state playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /card_2 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport /card_2 acquired: fd 64 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Configuring transport for profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport for profile headset_head_unit successfully setup Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed no, stream fd 64) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Installing monitor for SCO stream Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit changed to playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile headset_head_unit to availability status yes Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status yes Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-input to status yes Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport already acquired Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport for profile headset_head_unit Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming source source.fake.sco because its transport state changed to playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Suspend cause of source source.fake.sco is 0x0000, resuming Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Source source.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source source.fake.sco state has changed Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming sink sink.fake.sco because its transport state changed to playing Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0000, resuming Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port 2147483656 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: set_parameters(): routing=2147483656 (AUDIO_DEVICE_IN_BLUETOOTH_SCO_HEADSET|AUDIO_DEVICE_IN_ALL_SCO : 0x80000008) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-bluetooth_sco_headset Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:08:13 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:13 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:13 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:13 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:13 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:13 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:13 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:13 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:13 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "44100" format.channels = "2" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.primary is 0x0000, resuming Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Resuming... Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Routing: set_parameters(): routing=16; (0x00000010) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.primary.monitor state has changed Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 15 "pulsesink probe" on sink.primary with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "pulsesink probe" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=272,11 ms, minreq=20,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=116,05 ms, Received latency=23,31 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 272,11 ms = 208,80 ms + 2*20,00 ms + 23,31 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream15 Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080583]AudDrv_btcvsd_ioctl cmd = 0xc00443e0 arg = 0 Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080599]AudDrv_btcvsd_Allocate_Buffer(+) isRX=0 Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080633]AudDrv_btcvsd_Allocate_Buffer(-) Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080822]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 3 Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080831]AudDrv SET_BTCVSD_STATE Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080839]SET_BTCVSD_STATE set btsco.uTXState to 0x3x Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080847]SET_BTCVSD_STATE enable BT IRQ disableBTirq = 1 Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080862]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow TRUE!!! Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.080874]Enable_CVSD_Wakeup Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.088379]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow TRUE!!! Nov 30 15:08:13 ubuntu-phablet kernel: [ 2693.110882]AudDrv_BTCVSD_IRQ_handler pTX->fUnderflow FALSE!!! Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream15 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 15 "pulsesink probe" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\"" format.channels = "2" format.rate = "44100" format.channel_map = "\"front-left,front-right\"" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Trying to change sample rate Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Rate changed to 44100 Hz Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Resampler: Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: rate 44100 -> 44100 (method copy) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: format float32le -> s16le (intermediate s16le) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: channels 2 -> 2 (resampling 2) Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 16 "Playback Stream" on sink.primary with sample spec float32le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "Playback Stream" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=23,31 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 200,00 ms = 156,69 ms + 2*10,00 ms + 23,31 ms Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:08:13 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream16 Nov 30 15:08:13 ubuntu-phablet powerd[955]: handle_requestSysState from :1.54 (media-hub-playback_lock) - ACTIVE (1) Nov 30 15:08:13 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.54 Nov 30 15:08:13 ubuntu-phablet powerd[955]: watching :1.54 to see when it disappears on dbus Nov 30 15:08:13 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:13 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:13 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:13 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:13 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to uncorking Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:08:14 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:08:14 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:14 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:14 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength -1 Nov 30 15:08:15 ubuntu-phablet powerd[955]: 2694162 slow avg 248.198520 fast avg 248.482700 last 248.500000 Nov 30 15:08:15 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:15 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:16 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 74 Nov 30 15:08:17 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 683c6706-77c7-4670-aa29-fb9c5f57eee7 Nov 30 15:08:17 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:17 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:17 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:17 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:17 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:17 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:17 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:17 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:17 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:17 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:13 ubuntu-phablet bluetoothd[893]: message repeated 3 times: [ profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46] Nov 30 15:08:17 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:play() Nov 30 15:08:17 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:17 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:17 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:17 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:17 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:17 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:17 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:17 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:18 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 61 Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Source source.fake.sco idle for too long, suspending ... Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Suspend cause of source source.fake.sco is 0x0004, suspending Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source source.fake.sco state has changed Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco idle for too long, suspending ... Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0004, suspending Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport true (ret 1) Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspend prevention active, not closing SCO over PCM Nov 30 15:08:18 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:08:19 ubuntu-phablet powerd[955]: 2698252 slow avg 248.692081 fast avg 248.984597 last 249.000000 Nov 30 15:08:20 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:08:20 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:08:20 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:08:21 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 54 Nov 30 15:08:21 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:21 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:21 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 08de42ae-2356-462a-84e0-9a29a8d5d24e Nov 30 15:08:21 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:21 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:21 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:21 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:21 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:21 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:21 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:21 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:21 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:21 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:21 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:21 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:21 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:21 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:21 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:21 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:23 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 64 Nov 30 15:08:23 ubuntu-phablet powerd[955]: 2703061 slow avg 248.266713 fast avg 247.359632 last 247.000000 Nov 30 15:08:25 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:25 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:25 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:25 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:25 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: b24d12b3-eca8-40ef-9520-94e79ea13071 Nov 30 15:08:25 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:25 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:25 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:25 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:25 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:25 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:25 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:25 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:25 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:08:25 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:08:25 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:25 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:25 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:25 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:25 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:25 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:25 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:25 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:25 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:08:25 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:08:26 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:play() Nov 30 15:08:27 ubuntu-phablet powerd[955]: 2707063 slow avg 248.184122 fast avg 248.959583 last 249.000000 Nov 30 15:08:28 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 61 Nov 30 15:08:29 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:29 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:29 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 26f9ad70-1c8e-482e-ba99-3524a074ef7c Nov 30 15:08:29 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:29 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:29 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:29 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:29 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:29 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:29 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:29 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to corking Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:08:29 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:29 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:29 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:29 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:29 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:29 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:29 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:29 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream16 Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 16 "Playback Stream" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"s16le\"" format.rate = "44100" format.channels = "2" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 17 "pulsesink probe" on sink.primary with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "pulsesink probe" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=272,11 ms, minreq=20,00 ms Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=116,05 ms, Received latency=23,31 ms Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=43888, base=4, prebuf=40364, minreq=3528 maxrewind=0 Nov 30 15:08:29 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 272,11 ms = 208,80 ms + 2*20,00 ms + 23,31 ms Nov 30 15:08:30 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:08:30 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:08:30 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream17 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream17 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 17 "pulsesink probe" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Negotiated format: pcm, format.sample_format = "\"float32le\"" format.channels = "2" format.rate = "44100" format.channel_map = "\"front-left,front-right\"" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Trying to change sample rate Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Rate changed to 44100 Hz Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-stream-restore.c: Restoring volume for sink input sink-input-by-media-role:alert. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Forcing resampler 'copy', because of fixed, identical sample rates. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: Resampler: Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: rate 44100 -> 44100 (method copy) Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: format float32le -> s16le (intermediate s16le) Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] resampler.c: channels 2 -> 2 (resampling 2) Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=33554432, tlength=0, base=4, prebuf=0, minreq=1 maxrewind=0 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=33554432, tlength=33554432, base=4, prebuf=0, minreq=4 maxrewind=0 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Created input 18 "Playback Stream" on sink.primary with sample spec float32le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.role = "alert" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: media.name = "Playback Stream" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.name = "media-hub-server" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.peer = "UNIX socket client" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: native-protocol.version = "30" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.id = "2502" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.user = "phablet" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.host = "ubuntu-phablet" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.binary = "media-hub-server" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.language = "pt_BR.UTF-8" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.machine_id = "ubuntu-phablet" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: application.process.session_id = "c1" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: module-stream-restore.id = "sink-input-by-media-role:alert" Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=23,31 ms Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq requested: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] memblockq.c: memblockq sanitized: maxlength=4194304, tlength=62336, base=8, prebuf=0, minreq=3528 maxrewind=0 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Final latency 200,00 ms = 156,69 ms + 2*10,00 ms + 23,31 ms Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'alert' stream that will trigger the ducking. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be ducked. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream added for object /org/pulseaudio/core1/playback_stream18 Nov 30 15:08:31 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:position_changed() sender=:1.29 path=/_1_46 Nov 30 15:08:31 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:set_position() Position=0 Nov 30 15:08:31 ubuntu-phablet bluetoothd[893]: profiles/audio/avrcp.c:avrcp_player_event() id=4 Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to end of underrun. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to uncorking Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes busy, resuming. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Implicit underrun of 'Playback Stream' Nov 30 15:08:31 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] protocol-native.c: Requesting rewind due to rewrite. Nov 30 15:08:32 ubuntu-phablet powerd[955]: 2711621 slow avg 249.182345 fast avg 249.956990 last 250.000000 Nov 30 15:08:32 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:32 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:33 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 58 Nov 30 15:08:33 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 5b7d395d-1d52-48a8-b402-b75ba315c543 Nov 30 15:08:33 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:33 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:33 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:33 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:33 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:33 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:33 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:33 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:33 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:33 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:33 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:33 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:33 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:33 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:33 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:33 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:35 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:35 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:36 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:08:36 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:08:36 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:08:36 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:36 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:37 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: f6418065-6db1-4362-8690-15b758fbbdbb Nov 30 15:08:37 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:37 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:37 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:37 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:37 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:37 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:37 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:37 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:37 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 61 Nov 30 15:08:37 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:37 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:37 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:37 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:37 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:37 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:37 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:37 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:38 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:play() Nov 30 15:08:38 ubuntu-phablet kernel: [ 2717.684275][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:39 ubuntu-phablet powerd[955]: 2718185 slow avg 250.383616 fast avg 250.507966 last 250.500000 Nov 30 15:08:40 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: UNSOL_CALL_RING Nov 30 15:08:40 ubuntu-phablet ofonod[2045]: gril/gril.c:handle_unsol_req() RIL Event slot 1: MTK_RIL_UNSOL_CRSS_NOTIFICATION Nov 30 15:08:41 ubuntu-phablet ofonod[2045]: drivers/rilmodem/voicecall.c:lastcause_cb() Call 1 ended with reason 2 Nov 30 15:08:41 ubuntu-phablet ofonod[2045]: src/voicecall.c:ofono_voicecall_disconnected() Got disconnection event for id: 1, reason: 2 Nov 30 15:08:41 ubuntu-phablet powerd[955]: turn_display_on(1) Nov 30 15:08:41 ubuntu-phablet powerd[955]: signalling activity via HAL Nov 30 15:08:41 ubuntu-phablet powerd[955]: call removed Nov 30 15:08:41 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 2130abdf-03c2-48a4-ae07-4f032480a97e Nov 30 15:08:41 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:41 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:41 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:41 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:41 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet powerd[955]: handle_requestSysState from :1.25 (usensord) - ACTIVE (1) Nov 30 15:08:41 ubuntu-phablet powerd[955]: name_watch_add: looking for :1.25 Nov 30 15:08:41 ubuntu-phablet powerd[955]: watching :1.25 to see when it disappears on dbus Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet powerd[955]: handle_requestSysState - SUCCESS Nov 30 15:08:41 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:41 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink-input.c: Requesting rewind due to corking Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/core1, interface = org.PulseAudio.Core1, member = ListenForSignal Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Received message: destination = /org/pulseaudio/stream_restore1/entry4, interface = org.freedesktop.DBus.Properties, member = Get Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:08:41 ubuntu-phablet powerd[955]: handle_clearSysState from :1.54, cookie: 1bd7d376-63cb-4b51-8ba3-73aa1c1b6b2c Nov 30 15:08:41 ubuntu-phablet powerd[955]: clear_sys_request: media-hub-playback_lock - :1.54 Nov 30 15:08:41 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.54 Nov 30 15:08:41 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.54 is now 0 Nov 30 15:08:41 ubuntu-phablet powerd[955]: no longer watching :1.54, there are no more requests Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:41 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:41 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: profiles/audio/media.c:properties_changed() sender=:1.29 path=/_1_46 Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:08:41 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0 Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-role-ducking.c: Found a 'multimedia' stream that should be unducked Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Stream removed from object /org/pulseaudio/core1/playback_stream18 Nov 30 15:08:41 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Freeing input 18 "Playback Stream" Nov 30 15:08:42 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 96 Nov 30 15:08:42 ubuntu-phablet powerd[955]: handle_clearSysState from :1.25, cookie: 8f937854-3cf9-497a-8ed9-644454859cbb Nov 30 15:08:42 ubuntu-phablet powerd[955]: clear_sys_request: usensord - :1.25 Nov 30 15:08:42 ubuntu-phablet powerd[955]: name_watch_remove: looking for :1.25 Nov 30 15:08:42 ubuntu-phablet powerd[955]: name_watch: ref_count for :1.25 is now 0 Nov 30 15:08:42 ubuntu-phablet powerd[955]: no longer watching :1.25, there are no more requests Nov 30 15:08:42 ubuntu-phablet powerd[955]: libsuspend: acquire_wake_lock: powerd_power_request Nov 30 15:08:42 ubuntu-phablet powerd[955]: Enqueue state change to ACTIVE Nov 30 15:08:42 ubuntu-phablet powerd[955]: queue empty && state == current, discarding Nov 30 15:08:42 ubuntu-phablet powerd[955]: libsuspend: release_wake_lock: powerd_power_request Nov 30 15:08:43 ubuntu-phablet powerd[955]: 2722222 slow avg 249.774432 fast avg 248.236078 last 247.000000 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Client telepathy-ofono changes mute of source source.primary. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-sink.c: Sink set port 2 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport false (ret 0) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming SCO sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0000, resuming Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco becomes idle, timeout in 5 seconds. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Changed port of sink 0 "sink.primary" to output-speaker Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Routing: set_parameters(): routing=2; (0x00000002) Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609199]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 4 Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609224]AudDrv SET_BTCVSD_STATE Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609240]SET_BTCVSD_STATE set btsco.uTXState to 0x4x Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609405]AudDrv_btcvsd_ioctl cmd = 0xc00443e1 arg = 0 Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609423]AudDrv SET_BTCVSD_STATE Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609448]SET_BTCVSD_STATE set btsco.uTXState to 0x0x Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609466]SET_BTCVSD_STATE disable BT IRQ disableBTirq = 0 Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609488]Disable_CVSD_Wakeup Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609507]AudDrv_btcvsd_ioctl cmd = 0xc00443e0 arg = 1 Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609526]AudDrv_btcvsd_Free_Buffer(+) isRX=0 Nov 30 15:08:43 ubuntu-phablet kernel: [ 2722.609553]AudDrv_btcvsd_Free_Buffer(-) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: Source set port 2147483652 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] droid-source.c: set_parameters(): routing=2147483652 (AUDIO_DEVICE_IN_BUILTIN_MIC : 0x80000004) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Changed port of source 1 "source.primary" to input-builtin_mic Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Setting new profile a2dp_sink for card (current headset_head_unit) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport /card_2 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Trying to release transport for card /card_2 (fd 64) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Transport available for card /card_2 (fd 64), releasing now Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Successfully released transport for card /card_2 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /card_2 state changed from playing to idle Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit has changed to idle Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile headset_head_unit changed to idle Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile headset_head_unit to availability status unknown Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status unknown Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-input to status unknown Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport for profile headset_head_unit Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspending source source.fake.sco because the remote end closed the stream Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Suspending sink sink.fake.sco because the remote end closed the stream Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.fake.sco is 0x0001, suspending Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.fake.sco state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Updating SCO over PCM state (profile headset_head_unit, changed yes, stream fd 64) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Set bluetooth.hsp.prevent.suspend.transport false (ret 0) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Closing SCO over PCM Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Releasing transport /card_2 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Trying to release transport for card /card_2 (fd -1) Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] backend-ofono.c: Transport /card_2 already released Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Audio stream torn down Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.fake.sco.monitor state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Audio stream torn down Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Initializing profile a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: profile a2dp_sink transport 0x6763b0 transport state idle Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_create() Owner created: sender=:1.37 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:a2dp_sep_lock() SEP 0xb8ad4568 locked Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_ref() 0xb8adaff0: ref=3 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_ref() 0xb8ad6f08: ref=1 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_IDLE -> TRANSPORT_STATE_REQUESTING Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_create() Request created: method=Acquire id=18 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_add() Owner :1.37 Request Acquire Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_transport_set_owner() Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Owner :1.37 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:session_cb() Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_parse_resp() START request succeeded Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:start_cfm() Source 0xb8ad4568: Start_Cfm Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_remove() Owner :1.37 Request Acquire Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_REQUESTING -> TRANSPORT_STATE_ACTIVE Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_unref() 0xb8ad6f08: ref=0 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_free() 0xb8ad6f08 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_unref() 0xb8adaff0: ref=2 Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: OPEN -> STREAMING Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81: SINK_STATE_CONNECTED -> SINK_STATE_PLAYING Nov 30 15:08:43 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 State=TRANSPORT_STATE_ACTIVE Playing=1 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 acquired: fd 64 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Configuring transport for profile a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: SBC parameters: allocation=0, subbands=1, blocks=3, bitpool=53 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport for profile a2dp_sink successfully setup Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Created sink 7 "bluez_sink.00_18_16_49_97_81" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluetooth.protocol = "a2dp_sink" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.description = "MD-HPBT01" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.string = "00:18:16:49:97:81" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.api = "bluez" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.class = "sound" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.bus = "bluetooth" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.form_factor = "headset" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.path = "/org/bluez/hci0/dev_00_18_16_49_97_81" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.class = "0x240404" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: bluez.alias = "MD-HPBT01" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.icon_name = "audio-headset-bluetooth" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: device.intended_roles = "phone" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: Created source 9 "bluez_sink.00_18_16_49_97_81.monitor" with sample spec s16le 2ch 44100Hz and channel map front-left,front-right Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.description = "Monitor of MD-HPBT01" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.class = "monitor" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.string = "00:18:16:49:97:81" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.api = "bluez" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.bus = "bluetooth" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.form_factor = "headset" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.path = "/org/bluez/hci0/dev_00_18_16_49_97_81" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.class = "0x240404" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: bluez.alias = "MD-HPBT01" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.icon_name = "audio-headset-bluetooth" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] source.c: device.intended_roles = "phone" Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: IO Thread starting up Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] core-util.c: SCHED_RR|SCHED_RESET_ON_FORK worked. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] core-util.c: Successfully enabled SCHED_RR scheduling for thread, with priority 5. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 resuming Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Configuring MTU for transport of profile a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Stream properly set up, we're ready to roll! Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink bluez_sink.00_18_16_49_97_81 state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source bluez_sink.00_18_16_49_97_81.monitor state has changed Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/source9 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Source added for object /org/pulseaudio/core1/source9 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluetooth-policy.c: Profile a2dp_sink cannot be selected for loopback Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 becomes idle, timeout in 5 seconds. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.DevicePort added for object /org/pulseaudio/core1/sink7/port0 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Device added for object /org/pulseaudio/core1/sink7 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-dbus.c: Interface org.PulseAudio.Core1.Sink added for object /org/pulseaudio/core1/sink7 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink.c: Requesting rewind due to started move Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=250,00 ms, minreq=20,00 ms Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Traditional mode enabled, modifying sink usec only for compat with minreq. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=210,00 ms, Received latency=210,00 ms Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 0 to bluez_sink.00_18_16_49_97_81. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-switch-on-connect.c: Successfully moved sink input 0 "QtPulseAudio:2666" to bluez_sink.00_18_16_49_97_81. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary becomes idle, timeout in 5 seconds. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] sink.c: Requesting rewind due to started move Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested tlength=200,00 ms, minreq=10,00 ms Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Adjust latency mode enabled, configuring sink latency to half of overall latency. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] protocol-native.c: Requested latency=90,00 ms, Received latency=90,00 ms Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink-input.c: Successfully moved sink input 6 to bluez_sink.00_18_16_49_97_81. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-switch-on-connect.c: Successfully moved sink input 6 "media-hub-server" to bluez_sink.00_18_16_49_97_81. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Changed profile of card 2 "bluez_card.00_18_16_49_97_81" to a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 state changed from idle to playing Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink has changed to playing Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink changed to playing Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile a2dp_sink to availability status yes Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status yes Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Transport already acquired Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Acquiring transport for profile a2dp_sink Nov 30 15:08:43 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Resuming sink bluez_sink.00_18_16_49_97_81 because its transport state changed to playing Nov 30 15:08:45 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:45 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:45 ubuntu-phablet kernel: [ 2722.621804]lipeng debug|[Sound_Speaker_Turnon] Speaker_Volume: 0 Nov 30 15:08:45 ubuntu-phablet kernel: [ 2724.708723][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:47 ubuntu-phablet powerd[955]: 2726271 slow avg 248.869898 fast avg 248.976915 last 249.000000 Nov 30 15:08:47 ubuntu-phablet kernel: [ 2727.064908][WMT-PLAT][I]wmt_plat_eirq_ctrl:BGF INT has been disabled,counter(0) Nov 30 15:08:48 ubuntu-phablet kernel: [ 2727.411503][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.fake.sco idle for too long, suspending ... Nov 30 15:08:48 ubuntu-phablet ofonod[2045]: src/network.c:ofono_netreg_strength_notify() strength 83 Nov 30 15:08:48 ubuntu-phablet kernel: [ 2727.736642][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink bluez_sink.00_18_16_49_97_81 idle for too long, suspending ... Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink bluez_sink.00_18_16_49_97_81 is 0x0004, suspending Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Releasing transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_ACTIVE -> TRANSPORT_STATE_SUSPENDING Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_ref() 0xb8adaff0: ref=3 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_ref() 0xb8ad6f40: ref=1 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_create() Request created: method=Release id=19 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_add() Owner :1.37 Request Release Nov 30 15:08:48 ubuntu-phablet kernel: [ 2727.781699][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200) Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:session_cb() Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_parse_resp() SUSPEND request succeeded Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_sep_set_state() stream state changed: STREAMING -> OPEN Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/sink.c:sink_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81: SINK_STATE_PLAYING -> SINK_STATE_CONNECTED Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_update_playing() /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 State=TRANSPORT_STATE_SUSPENDING Playing=0 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:suspend_cfm() Source 0xb8ad4568: Suspend_Cfm Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_request_reply() Request Release Reply Success Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_remove() Owner :1.37 Request Release Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:a2dp_sep_unlock() SEP 0xb8ad4568 unlocked Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:transport_set_state() State changed /org/bluez/hci0/dev_00_18_16_49_97_81/fd1: TRANSPORT_STATE_SUSPENDING -> TRANSPORT_STATE_IDLE Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_transport_remove_owner() Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Owner :1.37 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/transport.c:media_owner_free() Owner :1.37 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_unref() 0xb8ad6f40: ref=0 Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [bluetooth] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 released Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [bluetooth] module-bluez5-device.c: Audio stream torn down Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/a2dp.c:setup_free() 0xb8ad6f40 Nov 30 15:08:48 ubuntu-phablet bluetoothd[893]: profiles/audio/avdtp.c:avdtp_unref() 0xb8adaff0: ref=2 Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink bluez_sink.00_18_16_49_97_81 state has changed Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source bluez_sink.00_18_16_49_97_81.monitor state has changed Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-suspend-on-idle.c: Sink sink.primary idle for too long, suspending ... Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] sink.c: Suspend cause of sink sink.primary is 0x0004, suspending Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [droid-sink-primary] droid-sink.c: Device suspended. Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Sink sink.primary state has changed Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: Source sink.primary.monitor state has changed Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core.c: All sinks and sources are suspended, vacuuming memory Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Properties changed in transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] bluez5-util.c: Transport /org/bluez/hci0/dev_00_18_16_49_97_81/fd1 state changed from playing to idle Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink has changed to idle Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] module-bluez5-device.c: State of transport for profile a2dp_sink changed to idle Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] card.c: Setting card bluez_card.00_18_16_49_97_81 profile a2dp_sink to availability status unknown Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] device-port.c: Setting port headset-output to status unknown Nov 30 15:08:48 ubuntu-phablet pulseaudio[2158]: [pulseaudio] core-subscribe.c: Dropped redundant event due to change event. Nov 30 15:08:51 ubuntu-phablet powerd[955]: 2730442 slow avg 248.600336 fast avg 249.573437 last 250.000000 Nov 30 15:08:52 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 0 method 0 Nov 30 15:08:52 ubuntu-phablet bluetoothd[893]: src/adapter.c:trigger_start_discovery() Nov 30 15:08:52 ubuntu-phablet bluetoothd[893]: src/adapter.c:cancel_passive_scanning() Nov 30 15:08:55 ubuntu-phablet ofonod[2045]: plugins/upower.c:battery_props_changed() Nov 30 15:08:55 ubuntu-phablet ofonod[2045]: plugins/upower.c:update_battery_level() last_battery_level: 2 battery_level: 2 Nov 30 15:08:55 ubuntu-phablet powerd[955]: 2734662 slow avg 249.666176 fast avg 250.011749 last 250.000000 Nov 30 15:08:57 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() Nov 30 15:08:57 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_timeout() adapter->current_discovery_filter == 0 Nov 30 15:08:57 ubuntu-phablet bluetoothd[893]: src/adapter.c:start_discovery_complete() status 0x00 Nov 30 15:08:57 ubuntu-phablet bluetoothd[893]: src/adapter.c:discovering_callback() hci0 type 7 discovering 1 method 0