New issue
Advanced search Search tips

Issue 734331 link

Starred by 5 users

Issue metadata

Status: Verified
Owner:
Closed: Aug 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

HP Chromebook 13 sometimes does not go to sleep due to active audio stream

Reported by chriswil...@gmail.com, Jun 17 2017

Issue description

UserAgent: Mozilla/5.0 (X11; CrOS x86_64 9334.72.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/58.0.3029.140 Safari/537.36
Platform: 9334.72.0 (Official Build) stable-channel chell

Steps to reproduce the problem:
1. step away from the laptop
2. wait the appropriate amount of time so the laptop should suspend
3. note that it does not suspend (sometimes)

What is the expected behavior?
The laptop suspends.

What went wrong?
The laptop does not suspend.

The issue doesn't happen every time but I *think* that, once the issue occurs, it occurs reliably until a machine restart, or possibly a logout.

Did this work before? N/A 

Chrome version: 58.0.3029.140  Channel: stable
OS Version: 9334.72.0
Flash Version: 25.0.0.171
 

Comment 1 by est...@chromium.org, Jun 17 2017

Cc: est...@chromium.org
Components: Privacy
Labels: -Restrict-View-SecurityTeam Restrict-View-ChromePrivacy
Moving over to the privacy queue

Comment 2 by est...@chromium.org, Jun 17 2017

Labels: -Type-Bug-Security Type-Bug
note: this only happens for the secondary account. ie. I log in with account X and then log in w/ account Y while account X is already logged in. The issue only occurs for Y.
Components: -Privacy OS>Kernel>Power
Labels: -Restrict-View-ChromePrivacy
doesn't seem like a privacy issue


Comment 5 by ka...@chromium.org, Jul 18 2017

Cc: derat@chromium.org tbroch@chromium.org
chriswillmorris@, can you send feedback (Shift+Alt+i), after reproducing the issue. Thanks.
Tested on M59 Stable and M61 (ToT) using 'set_short_powerd_timeouts' command to faster repro.  Able to repro on M59, please see stack trace below:

M59:  Suspending is missing:

Google Chrome	59.0.3071.134 (Official Build) (64-bit)
Revision	0
Platform	9460.73.0 (Official Build) stable-channel chell
Firmware Version	Google_Chell.7820.253.0
[0718/151836:INFO:state_controller.cc(89)] Dimming screen after 10s
[0718/151836:INFO:internal_backlight_controller.cc(682)] Setting brightness to 19 (35.4143%) over 200 ms
[0718/151838:INFO:daemon.cc(777)] On battery at 55%, 2.154/3.901Ah at 0.241A, 12h45m45s until empty (11h50m17s until shutdown)[0718/151841:INFO:state_controller.cc(89)] Turning screen off after 15s
[0718/151841:INFO:internal_backlight_controller.cc(682)] Setting brightness to 0 (0%) over 200 ms
[0718/151841:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays off
[0718/151841:INFO:daemon.cc(1394)] Chrome is using normal display mode
[0718/151846:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago
[0718/151848:INFO:activity_logger.cc(20)] Audio activity ongoing
[0718/151856:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 0 (0%) over 2000 ms
[0718/151908:INFO:daemon.cc(777)] On battery at 55%, 2.153/3.901Ah at 0.064A, 16h30m24s until empty (15h18m37s until shutdown)
[0718/151908:INFO:activity_logger.cc(20)] User activity reported
[0718/151908:INFO:state_controller.cc(96)] Undimming screen
[0718/151908:INFO:state_controller.cc(96)] Turning screen on
[0718/151908:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays on
[0718/151909:INFO:internal_backlight_controller.cc(682)] Setting brightness to 53 (55.8%) over 0 ms
[0718/151909:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 10 (10%) over 200 ms
[0718/151909:INFO:daemon.cc(1394)] Chrome is using normal display mode



M61 (ToT) Successful suspend:

Google Chrome	61.0.3159.0 (Official Build) dev (64-bit)
Revision	0
Platform	9756.0.0 (Official Build) dev-channel chell
Firmware Version	Google_Chell.7820.253.0

[0718/154840:INFO:daemon.cc(777)] On battery at 51% (displayed as 50%), 1.968/3.866Ah at 0.290A, 6h12m10s until empty (5h42m56s until shutdown)
[0718/154846:INFO:state_controller.cc(89)] Dimming screen after 10s
[0718/154846:INFO:internal_backlight_controller.cc(682)] Setting brightness to 19 (35.4143%) over 200 ms
[0718/154851:INFO:state_controller.cc(89)] Turning screen off after 15s
[0718/154851:INFO:internal_backlight_controller.cc(682)] Setting brightness to 0 (0%) over 200 ms
[0718/154851:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays off
[0718/154852:INFO:daemon.cc(1396)] Chrome is using normal display mode
[0718/154856:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago
[0718/154859:INFO:state_controller.cc(914)] Ready to perform idle action (suspend) after 20s
[0718/154859:INFO:suspender.cc(402)] Starting request 600506370
[0718/154859:INFO:daemon.cc(602)] Reading wakeup count from /sys/power/wakeup_count
[0718/154859:INFO:daemon.cc(606)] Read wakeup count 961
[0718/154859:INFO:internal_backlight_controller.cc(699)] Setting resume brightness to 53 (55.8%)
[0718/154859:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 0 (0%) over 0 ms
[0718/154859:INFO:suspend_delay_controller.cc(140)] Announcing suspend request 600506370 with 2 pending delay(s) and 0 outstanding delay(s) from previous request
[0718/154859:INFO:suspend_delay_controller.cc(86)] Got notification that delay 600506369 (shill) is ready for suspend request 600506370 from :1.10
[0718/154859:INFO:suspend_delay_controller.cc(86)] Got notification that delay 600506370 (chrome) is ready for suspend request 600506370 from :1.66
[0718/154859:INFO:suspend_delay_controller.cc(232)] Notifying observers that suspend is ready
[0718/154859:INFO:suspender.cc(473)] Starting suspend
[0718/154859:INFO:main.cc(233)] Running "/usr/bin/powerd_setuid_helper --action=suspend  --suspend_wakeup_count_valid --suspend_wakeup_count=961"
[0718/154936:INFO:daemon.cc(698)] powerd_suspend returned 0
[0718/154936:INFO:suspender.cc(429)] Finishing request 600506370 successfully
[0718/154936:INFO:state_controller.cc(96)] Undimming screen
[0718/154936:INFO:internal_backlight_controller.cc(699)] Setting resume brightness to 53 (55.8%)
[0718/154936:INFO:state_controller.cc(96)] Turning screen on
[0718/154936:INFO:internal_backlight_controller.cc(699)] Setting resume brightness to 53 (55.8%)
[0718/154936:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays on
[0718/154936:INFO:internal_backlight_controller.cc(682)] Setting brightness to 53 (55.8%) over 0 ms
[0718/154936:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 10 (10%) over 200 ms
[0718/154936:INFO:daemon.cc(1396)] Chrome is using normal display mode
[0718/154936:INFO:daemon.cc(777)] On battery at 51% (displayed as 50%), 1.967/3.866Ah at 0.088A, 0s until empty (calculating)
[0718/154937:INFO:daemon.cc(1396)] Chrome is using normal display mode

Comment 7 by ka...@chromium.org, Jul 18 2017

'Audio activity ongoing' log present at the M59 build. I do not remember having audio going on during the test. pgangishetty@, can you confirm there was no audio playing while leaving device to suspend?

Comment 8 by derat@chromium.org, Jul 19 2017

Go to chrome://media-internals/ to look for the open audio stream(s).
Stack trace from today's test on M59:  

[0719/103511:INFO:state_controller.cc(89)] Dimming screen after 10s
[0719/103511:INFO:internal_backlight_controller.cc(682)] Setting brightness to 19 (35.4143%) over 200 ms
[0719/103516:INFO:state_controller.cc(89)] Turning screen off after 15s
[0719/103516:INFO:internal_backlight_controller.cc(682)] Setting brightness to 0 (0%) over 200 ms
[0719/103516:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays off
[0719/103516:INFO:daemon.cc(1394)] Chrome is using normal display mode
[0719/103521:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago
[0719/103531:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 0 (0%) over 2000 ms
[0719/103533:INFO:activity_logger.cc(20)] Audio activity ongoing
[0719/103538:INFO:daemon.cc(777)] On battery at 52%, 2.021/3.870Ah at 0.065A, 11h4m46s until empty (10h13m51s until shutdown)
[0719/103604:INFO:activity_logger.cc(20)] User activity reported
[0719/103604:INFO:state_controller.cc(96)] Undimming screen
[0719/103604:INFO:state_controller.cc(96)] Turning screen on
[0719/103604:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays on
[0719/103605:INFO:internal_backlight_controller.cc(682)] Setting brightness to 53 (55.8%) over 0 ms
[0719/103605:INFO:keyboard_backlight_controller.cc(492)] Setting brightness to 10 (10%) over 200 ms
[0719/103605:INFO:daemon.cc(1394)] Chrome is using normal display mode
[0719/103608:INFO:daemon.cc(777)] On battery at 52%, 2.020/3.870Ah at 0.265A, 11h24m37s until empty (10h32m9s until shutdown)
[0719/103624:INFO:state_controller.cc(443)] Reloading prefs for disable_idle_suspend change


Media-internals log & Screenshot here: https://pantheon.corp.google.com/storage/browser/chromiumos-test-logs/bugfiles/cr/734331/

Update on #9 : I did not initiate any audio on any of the 2 user accounts logged in.  

Comment 11 by derat@chromium.org, Jul 19 2017

Cc: dgreid@chromium.org
Components: -OS>Kernel>Power OS>Kernel>Audio
Summary: HP Chromebook 13 sometimes does not go to sleep due to active audio stream (was: HP Chromebook 13 sometimes does not go to sleep)
Over to the audio team to help figure out what's holding the stream open.
Cc: ka...@chromium.org pgangishetty@chromium.org hychao@chromium.org cychiang@chromium.org
Status: Untriaged (was: Unconfirmed)
Cc: -pgangishetty@chromium.org dalecur...@chromium.org
Components: -OS>Kernel>Audio Internals>Media>Audio
In screenshot in the log #9, 1~4 were bell sound of crosh.
5 were incoming message notification sound.
6 and 7 were incoming hangout call sound.

The media-internal logs show that all the streams are not playing.
Their pipeline state are either kSuspended or kStopped.

I am not sure why there is still a output controller 0:2 holding stream 0:0.

Dale probably has better idea what is going on.
bugfiles%2Fcr%2F734331%2FScreenshot 2017-07-19 at 10.47.56 AM.png
198 KB View Download
bugfiles%2Fcr%2F734331%2FAudio Tab screenshot.png
80.5 KB View Download
Probably a stale audio context. See  issue 707462  or  issue 732450 . I just enabled silent audio suspension in https://chromium-review.googlesource.com/c/568817/ so you might see if dev channel fixes this issue.
chriswillmorris@gmail.com, can you upgrade to the latest dev build to see if this issue is fixed?
Hello. I don't use the 'dev' channel and, to be honest, am pretty burnt out on helping to debug Chrome issues. I've spent numerous hours helping people identify Bluetooth and audio issues in the past and am just burnt out. So I won't be helping to confirm this is fixed if it requires switching channels or taking other steps. I know that makes it harder for you all, but it sounds like others have been able to reproduce the problem. And for what it's worth, I stopped using the HP Chromebook 13 and am using a Pixel 2 2015 (Samus), and the issue exists on this device, as well.
Components: -Internals>Media>Audio OS>Kernel>Audio
give to Chromebook team. This is is not observed on my LINK and Nyan device. 
Owner: cychiang@chromium.org
assign to jimmy to track, but it looks like dale's fix will take care of this once it hits stable.
Status: Verified (was: Untriaged)
I verified on my chell using latest dev channel image R62 9806.0.

Sign in to add a comment