ubuntu-phone team mailing list archive

[Alexander <alexanderkinne@xxxxxxxxx>]

On 25.10.2016 17:29, Pat McGowan wrote:
> /var/log/syslog, there are some similar bug reports you could update
>
> On Tue, Oct 25, 2016 at 11:19 AM, Dominik Wnęk <dominik@xxxxxxxx
> <mailto:dominik@xxxxxxxx>> wrote:
>
>     Last night my bq 5 decided to use up all remaining battery (some
>     50%) and die before it could ring my 7 am alarm. That was _really_
>     not cool.
>
>     I'm attaching my battery graph. Is there some log I could extract
>     from the phone to help with the problem?
>
>     Dom
>
>
>     --
>     Sent using Dekko from my Ubuntu device
>     --
>     Mailing list: https://launchpad.net/~ubuntu-phone
>     <https://launchpad.net/~ubuntu-phone>
>     Post to     : ubuntu-phone@xxxxxxxxxxxxxxxxxxx
>     <mailto:ubuntu-phone@xxxxxxxxxxxxxxxxxxx>
>     Unsubscribe : https://launchpad.net/~ubuntu-phone
>     <https://launchpad.net/~ubuntu-phone>
>     More help   : https://help.launchpad.net/ListHelp
>     <https://help.launchpad.net/ListHelp>

Perhaps its time to get to the heart of the apparent CRITICAL power 
management issue? Instead there are several bugs that will take months 
to get picked up - if at all. (Who wants to touch the hot potatoe?)...

I have observed this phenomenon sporadically overnight in the past and 
it now seems to be a (almost) constant state. After the latest OTA the 
issue has grown with the addition of taking as long to charge the last 3 
percent of the battery as it takes for the 97 percent. So far I have 
attributed this to the explanations offered here about aging batteries. 
I am no longer convinced it is an issue of aging batteries. Something 
within the power management of UT is broken. At least for the vegeta-hd 
channel. Another thing I have observed is that the WLAN indicator goes 
to the not-connected state after a while, although the phone is 
connected to the network. This is also new since the last OTA. Perhaps 
the phone is spending all its energy on that? I will try to switch off 
W-Lan this night and see what happens.

When going to bed i like to listen to a podcast (PodBird) or sometimes 
use a flashlight (U-Torch). I have been attributing the overnight 
battery drain to those applications - I have however observed the drain 
on several occasions while making sure those applications get swiped 
away on the app spread. As far as I understand it should not make a 
difference what application was run last, as the OS will suspend 
anything to conserve battery when not in use? At least it does for 
applications like Oxide - try navigating to some web page and leave the 
phone for a while then go back. You are presented with a low res screen 
grab of where you where only to be brought back to the start page after 
a second or so. Frustrating!

MY REQUEST:
Can we find ONE bug to record this under? I'd be happy to contribute 
with logs and tests as much as I can find the time. Since the last 
charge my phone only took 18 hours (over night) to get down to 22 
percent and most of the time it has been just sitting there supposedly 
not doing anything.

Attached: /var/log/syslog; /var/log/repowerd.log; Various beautiful 
battery graphs, including the one from tonight :-)

--

Regards,
Alexander

Oct 25 06:25:00 black repowerd[703]: message repeated 4 times: [ DefaultStateMachine: handle_user_activity_extending_power_state]
Oct 25 06:25:03 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=19.00
Oct 25 06:25:05 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:11 black repowerd[703]: message repeated 2 times: [ DefaultStateMachine: handle_user_activity_extending_power_state]
Oct 25 06:25:13 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=19.00
Oct 25 06:25:14 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:21 black kernel: [27573.607346][lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c
Oct 25 06:25:21 black kernel: [27604.492526][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200)
Oct 25 06:25:23 black kernel: [27605.887453][lcm_esd_check]buffer[0]=0x1crtc_timer_do_work break, next->expires.tv64=1477369538897572208
Oct 25 06:25:22 black repowerd[703]: message repeated 3 times: [ DefaultStateMachine: handle_user_activity_extending_power_state]
Oct 25 06:25:23 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=19.00
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_emit_Wakeup()
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_clearWakeup(:1.85,1084)
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_requestWakeup(:1.85,indicator-datetime,1477635300)
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_requestWakeup(:1.85,indicator-datetime,1477635300) => 1086
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_requestSysState(:1.104,ubuntu push client,1)
Oct 25 06:25:24 black repowerd[703]: LibsuspendSuspendControl: disallow_suspend(UnityScreenService)
Oct 25 06:25:24 black repowerd[703]: UnityScreenService: dbus_requestSysState(:1.104,ubuntu push client,1) => 590
Oct 25 06:25:28 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:29 black repowerd[703]: UnityScreenService: dbus_requestWakeup(:1.104,ubuntu push client,1477369829)
Oct 25 06:25:29 black repowerd[703]: UnityScreenService: dbus_requestWakeup(:1.104,ubuntu push client,1477369829) => 1087
Oct 25 06:25:29 black repowerd[703]: UnityScreenService: dbus_clearSysState(:1.104,590)
Oct 25 06:25:30 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:32 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:33 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:25:43 black repowerd[703]: message repeated 2 times: [ UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00]
Oct 25 06:25:48 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:53 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:25:55 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:25:57 black signond[1623]: QObject::disconnect: Unexpected null parameter
Oct 25 06:26:03 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:13 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:13 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:26:23 black repowerd[703]: message repeated 2 times: [ DefaultStateMachine: handle_user_activity_extending_power_state]
Oct 25 06:26:23 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:27 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:26:31 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:26:33 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:33 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:26:33 black kernel: [27607.913947][lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c
Oct 25 06:26:33 black kernel: [27676.486694][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, but no rx data(200)
Oct 25 06:26:43 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:53 black repowerd[703]: UPowerPowerSource: change_device(/org/freedesktop/UPower/devices/battery_battery), is_present=1, state=2, percentage=45.00, temperature=20.00
Oct 25 06:26:55 black repowerd[703]: DefaultStateMachine: handle_user_activity_extending_power_state
Oct 25 06:26:55 black kernel: [27676.492479][lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c[lcm_esd_check]buffer[0]=0x1c
Oct 25 06:26:55 black kernel: [27698.488406][WMT-CTRL][W]wmt_ctrl_rx:wmt_ctrl_rx be signaled, b���q�^u���b�