Assistant keeps external display on when using HDMI audio
Reported by
stephenm...@gmail.com,
Jan 6 2018
|
||||||||||||||||||||||||||||
Issue descriptionChrome Version: 63.0.3239.116 (Official Build) (64-bit) Chrome OS Version: 63.0.3239.116 Chrome OS Platform: Pixelbook Network info: Wireless 5ghz, WPA2, asus ac3100 Please specify Cr-* of the system to which this bug/feature applies (add the label below). Steps To Reproduce: (1) Boot pixel book. (2) Plug apple usb-c to hdmi, usb-a, usb-c adapter (3) Let screen extend (4) Close pixelbook so only external monitor is attached (5) Let system idle Expected Result: Screen will blank Actual Result: Screen will not blank and enter powersave How frequently does this problem reproduce? (Always, sometimes, hard to reproduce?) - Almost always. This worked for a day What is the impact to the user, and is there a workaround? If so, what is it? - I can manually sleep the laptop, but I often forget. Please provide any additional information below. Attach a screen shot or log if possible. - This is my first chromebook, I actually have no idea what to send. For graphics-related bugs, please copy/paste the contents of the about:gpu page at the end of this report. - Not sure if this counts as a graphics bug or not.. Graphics Feature Status Canvas: Hardware accelerated CheckerImaging: Disabled Flash: Hardware accelerated Flash Stage3D: Hardware accelerated Flash Stage3D Baseline profile: Hardware accelerated Compositing: Hardware accelerated Multiple Raster Threads: Enabled Native GpuMemoryBuffers: Hardware accelerated Panel Fitting: Unavailable Rasterization: Hardware accelerated Video Decode: Hardware accelerated Video Encode: Hardware accelerated WebGL: Hardware accelerated WebGL2: Hardware accelerated Driver Bug Workarounds clear_uniforms_before_first_program_use count_all_in_varyings_packing decode_encode_srgb_for_generatemipmap disable_discard_framebuffer msaa_is_slow rely_on_implicit_sync_for_swap_buffers scalarize_vec_and_mat_constructor_args Problems Detected Chrome OS panel fitting is only supported for Intel IVB and SNB Graphics Controllers Disabled Features: panel_fitting Framebuffer discarding causes jumpy scrolling on Mali drivers: 301988 Applied Workarounds: disable_discard_framebuffer Clear uniforms before first program use on all platforms: 124764, 349137 Applied Workarounds: clear_uniforms_before_first_program_use Mesa drivers in ChromeOS handle varyings without static use incorrectly: 333885 Applied Workarounds: count_all_in_varyings_packing Always rewrite vec/mat constructors to be consistent: 398694 Applied Workarounds: scalarize_vec_and_mat_constructor_args On Intel GPUs MSAA performance is not acceptable for GPU rasterization: 527565 Applied Workarounds: msaa_is_slow Disable KHR_blend_equation_advanced until cc shaders are updated: 661715 Applied Workarounds: disable(GL_KHR_blend_equation_advanced), disable(GL_KHR_blend_equation_advanced_coherent) Decode and Encode before generateMipmap for srgb format textures on Chromeos Intel: 634519 Applied Workarounds: decode_encode_srgb_for_generatemipmap Avoid waiting on a egl fence before pageflipping and rely on implicit sync.: 721463 Applied Workarounds: rely_on_implicit_sync_for_swap_buffers Checker-imaging has been disabled via finch trial or the command line. Disabled Features: checker_imaging Version Information Data exported 1/6/2018, 12:59:36 PM Chrome version Chrome/63.0.3239.116 Operating system Linux 4.4.86-11976-gc4c6234ae4f3 Software rendering list version 13.13 Driver bug list version 10.34 ANGLE commit id 2ff870db3a3b 2D graphics backend Skia/63 0 Command Line /opt/google/chrome/chrome --ppapi-flash-path=/opt/google/chrome/pepper/libpepflashplayer.so --ppapi-flash-version=28.0.0.126 --ui-prioritize-in-gpu-process --use-gl=egl --enable-native-gpu-memory-buffers --enable-drm-atomic --enable-hardware-overlays=single-fullscreen,single-on-top --enable-webgl-image-chromium --enable-features=Pepper3DImageChromium,PointerEvent,EnableBackgroundBlur --gpu-sandbox-failures-fatal=yes --enable-logging --log-level=1 --use-cras --enable-wayland-server --user-data-dir=/home/chronos --max-unused-resource-memory-usage-percentage=5 --system-developer-mode --force-clamshell-power-button --login-profile=user --has-chromeos-keyboard --enable-touchview --enable-voice-interaction --default-wallpaper-large=/usr/share/chromeos-assets/wallpaper/oem_large.jpg --default-wallpaper-small=/usr/share/chromeos-assets/wallpaper/oem_small.jpg --default-wallpaper-is-oem --guest-wallpaper-large=/usr/share/chromeos-assets/wallpaper/guest_large.jpg --guest-wallpaper-small=/usr/share/chromeos-assets/wallpaper/guest_small.jpg --arc-availability=officially-supported --enable-arc-oobe-optin --enterprise-enrollment-initial-modulus=15 --enterprise-enrollment-modulus-limit=19 --login-user=stephenmbarcomb@gmail.com --login-profile=4739c226b62f058f701313600895b7afbbb27023 --flag-switches-begin --site-per-process --flag-switches-end --vmodule=*arc/*=1,tablet_power_button_controller=1,*chromeos/login/*=1,auto_enrollment_controller=1,*plugin*=2,*zygote*=1,*/ui/ozone/*=1,*/ui/display/manager/chromeos/*=1,*night_light*=1,power_button_observer=2,webui_login_view=2,lock_state_controller=2,webui_screen_locker=2,screen_locker=2 --enable-features=Pepper3DImageChromium,PointerEvent,EnableBackgroundBlur Driver Information Initialization time 56 In-process GPU false Passthrough Command Decoder false Supports overlays false Sandboxed true GPU0 VENDOR = 0x8086, DEVICE= 0x591e Optimus false Optimus false AMD switchable false Driver vendor Mesa Driver version 17.2.0 Driver date Pixel shader version 3.20 Vertex shader version 3.20 Max. MSAA samples 16 Machine model name Machine model version GL_VENDOR Intel Open Source Technology Center GL_RENDERER Mesa DRI Intel(R) HD Graphics 615 (Kaby Lake GT2) GL_VERSION OpenGL ES 3.2 Mesa 17.2.0-devel (git-277621bbb7) GL_EXTENSIONS GL_EXT_blend_minmax GL_EXT_multi_draw_arrays GL_EXT_texture_filter_anisotropic GL_EXT_texture_compression_dxt1 GL_EXT_texture_format_BGRA8888 GL_OES_compressed_ETC1_RGB8_texture GL_OES_depth24 GL_OES_element_index_uint GL_OES_fbo_render_mipmap GL_OES_mapbuffer GL_OES_rgb8_rgba8 GL_OES_standard_derivatives GL_OES_stencil8 GL_OES_texture_3D GL_OES_texture_float GL_OES_texture_float_linear GL_OES_texture_half_float GL_OES_texture_half_float_linear GL_OES_texture_npot GL_OES_vertex_half_float GL_EXT_texture_sRGB_decode GL_OES_EGL_image GL_OES_depth_texture GL_OES_packed_depth_stencil GL_EXT_texture_type_2_10_10_10_REV GL_OES_get_program_binary GL_APPLE_texture_max_level GL_EXT_discard_framebuffer GL_EXT_read_format_bgra GL_EXT_frag_depth GL_NV_fbo_color_attachments GL_OES_EGL_image_external GL_OES_EGL_sync GL_OES_vertex_array_object GL_OES_viewport_array GL_ANGLE_texture_compression_dxt3 GL_ANGLE_texture_compression_dxt5 GL_EXT_robustness GL_EXT_texture_rg GL_EXT_unpack_subimage GL_NV_draw_buffers GL_NV_read_buffer GL_NV_read_depth GL_NV_read_depth_stencil GL_NV_read_stencil GL_EXT_draw_buffers GL_EXT_map_buffer_range GL_KHR_debug GL_KHR_robustness GL_KHR_texture_compression_astc_ldr GL_OES_depth_texture_cube_map GL_OES_surfaceless_context GL_EXT_color_buffer_float GL_EXT_separate_shader_objects GL_EXT_shader_framebuffer_fetch GL_EXT_shader_integer_mix GL_EXT_tessellation_point_size GL_EXT_tessellation_shader GL_INTEL_conservative_rasterization GL_INTEL_performance_query GL_ANDROID_extension_pack_es31a GL_EXT_compressed_ETC1_RGB8_sub_texture GL_EXT_copy_image GL_EXT_draw_buffers_indexed GL_EXT_draw_elements_base_vertex GL_EXT_gpu_shader5 GL_EXT_polygon_offset_clamp GL_EXT_primitive_bounding_box GL_EXT_shader_io_blocks GL_EXT_texture_border_clamp GL_EXT_texture_buffer GL_EXT_texture_cube_map_array GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent GL_KHR_context_flush_control GL_KHR_robust_buffer_access_behavior GL_NV_image_formats GL_OES_copy_image GL_OES_draw_buffers_indexed GL_OES_draw_elements_base_vertex GL_OES_gpu_shader5 GL_OES_primitive_bounding_box GL_OES_sample_shading GL_OES_sample_variables GL_OES_shader_io_blocks GL_OES_shader_multisample_interpolation GL_OES_tessellation_point_size GL_OES_tessellation_shader GL_OES_texture_border_clamp GL_OES_texture_buffer GL_OES_texture_cube_map_array GL_OES_texture_stencil8 GL_OES_texture_storage_multisample_2d_array GL_EXT_blend_func_extended GL_EXT_buffer_storage GL_EXT_geometry_point_size GL_EXT_geometry_shader GL_EXT_shader_samples_identical GL_KHR_no_error GL_KHR_texture_compression_astc_sliced_3d GL_OES_geometry_point_size GL_OES_geometry_shader GL_OES_shader_image_atomic GL_EXT_clip_cull_distance GL_MESA_shader_integer_functions Disabled Extensions GL_KHR_blend_equation_advanced GL_KHR_blend_equation_advanced_coherent Window system binding vendor Mesa Project Window system binding version 1.4 (DRI2) Window system binding extensions EGL_ANDROID_native_fence_sync EGL_EXT_create_context_robustness EGL_EXT_image_dma_buf_import EGL_EXT_image_dma_buf_import_modifiers EGL_KHR_config_attribs EGL_KHR_create_context EGL_KHR_fence_sync EGL_KHR_get_all_proc_addresses EGL_KHR_gl_renderbuffer_image EGL_KHR_gl_texture_2D_image EGL_KHR_gl_texture_3D_image EGL_KHR_gl_texture_cubemap_image EGL_KHR_image_base EGL_KHR_no_config_context EGL_KHR_reusable_sync EGL_KHR_surfaceless_context EGL_KHR_wait_sync EGL_MESA_configless_context EGL_MESA_drm_image EGL_MESA_image_dma_buf_export Direct rendering Yes Reset notification strategy 0x8252 GPU process crash count 0 Compositor Information Tile Update Mode One-copy Partial Raster Enabled GpuMemoryBuffers Status ATC Software only ATCIA Software only DXT1 Software only DXT5 Software only ETC1 Software only R_8 GPU_READ_CPU_READ_WRITE, GPU_READ_CPU_READ_WRITE_PERSISTENT R_16 Software only RG_88 GPU_READ_CPU_READ_WRITE, GPU_READ_CPU_READ_WRITE_PERSISTENT BGR_565 GPU_READ RGBA_4444 Software only RGBX_8888 GPU_READ, SCANOUT, SCANOUT_CPU_READ_WRITE RGBA_8888 GPU_READ, SCANOUT_CPU_READ_WRITE BGRX_8888 GPU_READ, SCANOUT, SCANOUT_CPU_READ_WRITE BGRA_8888 GPU_READ, SCANOUT_CPU_READ_WRITE, GPU_READ_CPU_READ_WRITE, GPU_READ_CPU_READ_WRITE_PERSISTENT RGBA_F16 Software only YVU_420 GPU_READ YUV_420_BIPLANAR SCANOUT_CAMERA_READ_WRITE UYVY_422 Software only Display(s) Information Info Display[4693195945134337] bounds=0,0 3440x1440, workarea=0,0 3440x1392, scale=1, external Color space information {primaries:BT709, transfer:IEC61966_2_1, matrix:RGB, range:FULL, icc_profile_id:0} Bits per color component 8 Bits per pixel 24 Log Messages [17169:17182:0105/234444.708229:VERBOSE1:drm_device_manager.cc(53)] : Could not initialize DRM device for /sys/devices/platform/vgem/drm/card1 [17169:17182:0105/234445.735449:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=32 connector=48 origin=0,0 size=2400x1600 [17169:17182:0105/234445.882593:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=39 connector=56 origin=0,1660 size=3440x1440 [17169:17182:0105/234451.390788:WARNING:screen_manager.cc(114)] : Display controller (crtc=32) already present. [17169:17182:0105/234451.390849:WARNING:screen_manager.cc(114)] : Display controller (crtc=39) already present. [17169:17182:0105/234451.391312:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=32 connector=48 origin=0,0 size=0x0 [17169:17182:0105/234452.116418:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=39 connector=56 origin=0,0 size=3440x1440 [17169:17182:0105/234452.738817:ERROR:gbm_buffer.cc(72)] : AddFramebuffer2 failed: Invalid argument (22) [17169:17182:0105/234452.739519:ERROR:gbm_buffer.cc(79)] : AddFramebuffer2 failed: Invalid argument (22) [17169:17182:0105/234453.406293:WARNING:screen_manager.cc(114)] : Display controller (crtc=32) already present. [17169:17182:0105/234453.406377:WARNING:screen_manager.cc(114)] : Display controller (crtc=39) already present. [17169:17182:0105/234453.407106:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=32 connector=48 origin=0,0 size=0x0 [17169:17182:0105/234453.407208:VERBOSE1:drm_display.cc(105)] : DRM configuring: device=/sys/devices/pci0000:00/0000:00:02.0/drm/card0 crtc=39 connector=56 origin=0,1660 size=3440x1440 [17169:17169:0106/003513.982699:ERROR:gles2_cmd_decoder.cc(17977)] : [.DisplayCompositor-0x33965d615600]GL ERROR :GL_INVALID_OPERATION : glCreateAndConsumeTextureCHROMIUM: invalid mailbox name [17169:17169:0106/003513.982894:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering. [17169:17169:0106/003513.983015:ERROR:gles2_cmd_decoder.cc(17977)] : [.DisplayCompositor-0x33965d615600]GL ERROR :GL_INVALID_OPERATION : glCreateAndConsumeTextureCHROMIUM: invalid mailbox name [17169:17169:0106/003513.983107:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering. [17169:17169:0106/003513.991462:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering. [17169:17169:0106/003513.991565:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering. [17169:17169:0106/003514.171056:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering. [17169:17169:0106/003514.171175:ERROR:gles2_cmd_decoder.cc(9881)] : [.DisplayCompositor-0x33965d615600]RENDER WARNING: texture bound to texture unit 0 is not renderable. It maybe non-power-of-2 and have incompatible texture filtering.
,
Jan 10 2018
Hi stephenmbarcomb@, Is there audio running at time you leave the system to idle? After reproduction, can you file a feedback report pressing Shift+Alt+i, and mention crbug/799727 in the text box?
,
Jan 11 2018
Hey, thanks for the reply. No music or video tabs playing anything. I just sent the feedback a moment ago. Thank you for looking. -Steve
,
Jan 11 2018
I'm having trouble finding the feedback report. Did you file it using the same email address that you're using here (as opposed to anonymously), or do you remember a phrase you used in the text area?
,
Jan 11 2018
It uses the same email. I had stuck the crbug/799727 bit you mentioned in the previous information in the text box. I will send one again. "crbug/799727 external display sleep not working" Attaching screenshot as well.
,
Jan 12 2018
I'm unable to locate feedback report as well unfortunately. Will try to repro as well as I have pixelbook and same dongle.
,
Jan 13 2018
Are there any logs I could provide, independent of the feedback form, that may help? Thanks, Steve
,
Jan 13 2018
if you can visit chrome://net-internal/#chromeos click 'Store debug Logs' button and then once it completes attach the debug-logs_* tarball it creates (its in your Downloads folder) to this bug.
,
Jan 13 2018
Here's my version of pmset, egrep "suspender|state_controller|activity_logger" var/log/power_manager/powerd.LATEST [0112/170812:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (suspend) lid_closed=suspend use_audio=1 use_video=1 [0112/170813:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170815:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170821:INFO:state_controller.cc(923)] Turning panel off after entering docked mode [0112/170822:INFO:state_controller.cc(970)] Ready to perform lid-closed action (no-op) [0112/170827:INFO:activity_logger.cc(20)] User activity reported [0112/170831:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170832:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170833:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170833:INFO:state_controller.cc(832)] Wake locks: system [0112/170833:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170834:INFO:activity_logger.cc(20)] Audio activity started [0112/170834:INFO:activity_logger.cc(20)] Audio activity stopped [0112/170847:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0112/170849:INFO:state_controller.cc(820)] Updated settings: dim=10m screen_off=11m lock=0s idle_warn=0s idle=15m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170849:INFO:state_controller.cc(820)] Updated settings: dim=5m screen_off=6m lock=0s idle_warn=0s idle=10m (suspend) lid_closed=suspend use_audio=1 use_video=1 [0112/170849:INFO:state_controller.cc(923)] Turning panel on after leaving docked mode [0112/170849:INFO:state_controller.cc(970)] Ready to perform lid-closed action (suspend) [0112/170849:INFO:suspender.cc(399)] Starting request 75890689 [0112/170850:INFO:suspender.cc(470)] Starting suspend **[0112/170911:WARNING:suspender.cc(556)] Suspend attempt #1 canceled due to wake event [0112/170920:INFO:suspender.cc(361)] Aborting request in response to user activity [0112/170920:INFO:suspender.cc(426)] Finishing request 75890689 unsuccessfully [0112/170920:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (suspend) lid_closed=suspend use_audio=1 use_video=1 [0112/170920:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0112/170921:INFO:activity_logger.cc(20)] Audio activity started [0112/170928:INFO:state_controller.cc(923)] Turning panel off after entering docked mode [0112/170929:INFO:state_controller.cc(970)] Ready to perform lid-closed action (no-op) [0112/171221:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/171521:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/171810:INFO:activity_logger.cc(20)] User activity reported [0112/171821:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/172026:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0112/172026:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0112/172035:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0112/172036:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0112/172110:INFO:activity_logger.cc(20)] User activity ongoing; last reported 1 sec ago [0112/172121:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/172235:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0112/172318:INFO:activity_logger.cc(20)] User activity reported [0112/172421:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/172435:INFO:activity_logger.cc(20)] Video activity reported [0112/172455:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0112/172525:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0112/172535:INFO:activity_logger.cc(20)] User activity reported [0112/172628:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0112/172721:INFO:activity_logger.cc(20)] Audio activity ongoing ... [0112/173921:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/174008:INFO:state_controller.cc(89)] Dimming screen after 14m [0112/174008:INFO:state_controller.cc(879)] Audio is currently being sent to display; screen will not be turned off for inactivity [0112/174221:INFO:activity_logger.cc(20)] Audio activity ongoing ... [0112/190021:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/190151:INFO:activity_logger.cc(20)] User activity reported [0112/190151:INFO:state_controller.cc(417)] Scaling delays due to user activity while screen was dimmed or soon after it was turned off [0112/190151:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0112/190151:INFO:state_controller.cc(96)] Undimming screen [0112/190311:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0112/190321:INFO:activity_logger.cc(20)] Audio activity ongoing ... [0112/191521:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/191651:INFO:state_controller.cc(89)] Dimming screen after 14m [0112/191651:INFO:state_controller.cc(879)] Audio is currently being sent to display; screen will not be turned off for inactivity [0112/191821:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/192121:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/192421:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/192639:INFO:activity_logger.cc(20)] User activity reported [0112/192639:INFO:state_controller.cc(96)] Undimming screen [0112/192721:INFO:activity_logger.cc(20)] Audio activity ongoing [0112/192732:INFO:activity_logger.cc(20)] Audio activity stopped [0112/192732:INFO:activity_logger.cc(20)] Audio activity started [0112/192732:INFO:activity_logger.cc(20)] Audio activity stopped [0112/192735:INFO:activity_logger.cc(20)] Audio activity started My summary is device had a failed suspend at 0112/170911 (took 20min to suspend?) followed by what appeared to be audio that ultimately kept the device from entering idle suspend, [0112/191651:INFO:state_controller.cc(879)] Audio is currently being sent to display; screen will not be turned off for inactivity So I guess I'd speculate its the 'google play music' tab from PNGs in #comment5 that's inhibiting display off and its working as intended.
,
Jan 13 2018
Thank you for the reply. Yeah I thought the same thing. I sent the logs then closed google+ and google play music as well. It still will not sleep the display unfortunately. I am looking through the powerd output now. -Steve
,
Jan 13 2018
Update: 1) I forgot to mention that the google music tab was neither playing music nor casting. 2) I just got my monitor to sleep by changing the output source from HDMI to internal speaker. 3) My wife has a pixelbook as well and we just picked her up one of these adapters as well. She waited just over 20 minutes and her display did not sleep. I just had her change the output device from HDMI to the internal speaker. Will report back. -Steve
,
Jan 13 2018
1) Confirmed. Her display will now sleep when the HDMI sound output is not selected. 2) I switched mine back to the HDMI source and it did not sleep. The monitor is a Dell u3417w with speakers and KVM. The display sleeps normally under Ubuntu and Windows, so maybe the pixelbook is holding the audio open somehow? Thanks again for all the help. -Steve
,
Jan 14 2018
3rd confirmation via the Google Product Forums: https://productforums.google.com/forum/?utm_medium=email&utm_source=footer#!msg/pixelbook/4EqEoDPgWYs/qTCrgLHUAgAJ
,
Jan 14 2018
Stephen, while your system is in this state, do you see any streams listed under the Audio tab at chrome://media-internals/? It's expected that the display will remain active if it's the active audio output and if there are one or more active audio streams, but audio streams shouldn't be held open if they aren't used. Issue 753596 is a potential workaround for this.
,
Jan 14 2018
1) I switched the audio output to HDMI and they show as "none" "none" and "none". I tested playing music via the "Play Music" tab I have open and I can see it switch to "none" "Controller 44:7" and "Stream 0:12". When I pause the music I counted to 13 and everything went back to "none" "none" and "none" 2) I switched the audio output to "Internal Speaker" they stayed as "none" "none" and "none". I started up Play Music and they went to "None" "Controller 45:8" and "Stream 0:13". I counted to 15 and everything went back to "none" "none" and "none" 3) Over the last day I have tested back and forth on the audio output selection. It is 100 pct reproducible that HDMI sound output select is preventing sleep and selecting the internal speaker allows sleep. Let me know if there is any additional data I can provide. Thanks, Steve
,
Jan 14 2018
Yes, I don't think that the media-internals contents will change based on the selected output; as I understand it, that page just displays information about audio sources. If you leave the music paused with the output set to HDMI and wait 5 minutes, do you still see "Audio activity ongoing" messages getting logged to file:///var/log/power_manager/powerd.LATEST? If so, I'd expect the screen to be kept on, but I'd also expect at least one audio stream to be listed at media-internals. If you're seeing "Audio activity ongoing" continue to be logged but not seeing any streams listed at media-internals, that's surprising (and suggests an issue with the way that audio is reported).
,
Jan 14 2018
I did the test you requested. file:///var/log/power_manager/powerd.LATEST? [0114/145355:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0114/145411:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/145412:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.330A, full [0114/145442:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.343A, full [0114/145512:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.350A, full [0114/145542:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.348A, full [0114/145612:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.350A, full [0114/145642:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.340A, full [0114/145711:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/145712:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.326A, full [0114/145742:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.332A, full [0114/145812:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.333A, full [0114/145842:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.336A, full [0114/145912:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.340A, full [0114/145942:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.327A, full [0114/150011:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/150012:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.323A, full [0114/150042:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.342A, full [0114/150112:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.349A, full [0114/150142:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.353A, full [0114/150212:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.351A, full [0114/150242:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.379A, full [0114/150311:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/150312:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.348A, full [0114/150342:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.331A, full [0114/150412:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.323A, full [0114/150442:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.336A, full [0114/150512:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.325A, full [0114/150542:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.339A, full [0114/150611:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/150612:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.331A, full [0114/150638:INFO:daemon.cc(1525)] Update operation is UPDATE_STATUS_CHECKING_FOR_UPDATE [0114/150638:INFO:daemon.cc(1525)] Update operation is UPDATE_STATUS_IDLE [0114/150642:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.380A, full [0114/150712:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.349A, full [0114/150735:INFO:state_controller.cc(89)] Dimming screen after 14m [0114/150735:INFO:state_controller.cc(879)] Audio is currently being sent to display; screen will not be turned off for inactivity [0114/150742:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.355A, full [0114/150812:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.365A, full [0114/150842:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.410A, full [0114/150911:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/150912:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.326A, full [0114/150942:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.324A, full [0114/151012:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.328A, full [0114/151042:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.354A, full [0114/151112:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.324A, full [0114/151142:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.327A, full [0114/151211:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/151212:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.352A, full [0114/151242:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.381A, full [0114/151312:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.359A, full [0114/151342:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.364A, full [0114/151412:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.331A, full [0114/151442:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.337A, full [0114/151454:INFO:activity_logger.cc(20)] User activity reported [0114/151454:INFO:state_controller.cc(96)] Undimming screen Media internals: "none" "none" "none"
,
Jan 14 2018
I don't think I ever explicitly stated, but if I have the Play Music tab open, and paused, with the sound output set to 'internal speaker' it does sleep as you would expect. So I think having a Play Music tab open, as long as the music is paused, seems irrelevant to the issue so far. -Steve
,
Jan 14 2018
Thanks for the logs! I think that "sleep" is being used to describe multiple things in this bug: When one or more audio streams are active, regardless of the output device, we keep the system awake so as to not interrupt playback. When audio streams are active and the output device is an external display, we additionally keep the display on (since turning it off would interrupt playback). It's unexpected (to me, at least) that "Audio activity ongoing" messages are logged even though media-internals reports no active audio streams. Can someone from the audio team comment on how that could happen? And just to confirm, Steve, do the "Audio activity ongoing" messages also stop when you change the output to "internal speaker"? (I wouldn't expect them to.)
,
Jan 14 2018
It does, indeed look like they stop. I switched the audio source back to internal speaker before I left for an errand. Here is everything in that file from when I last reported above until I just woke it up: [0114/151511:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/151512:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.371A, full [0114/151514:INFO:activity_logger.cc(20)] Video activity reported [0114/151534:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0114/151542:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.347A, full [0114/151612:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.365A, full [0114/151640:INFO:activity_logger.cc(20)] Video activity reported [0114/151642:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.414A, full [0114/151700:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0114/151712:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.526A, full [0114/151718:INFO:activity_logger.cc(20)] Video activity reported [0114/151738:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0114/151742:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.412A, full [0114/151745:INFO:audio_client.cc(125)] Updated audio devices: headphones unplugged, HDMI inactive [0114/151754:INFO:activity_logger.cc(20)] User activity ongoing; last reported 0 sec ago [0114/151811:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/151812:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.351A, full [0114/151842:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.347A, full [0114/151852:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0114/151910:INFO:activity_logger.cc(20)] User activity reported [0114/151912:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.353A, full [0114/151942:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.351A, full [0114/152012:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.391A, full [0114/152013:INFO:activity_logger.cc(20)] Video activity reported [0114/152033:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0114/152042:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.370A, full [0114/152056:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0114/152111:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152112:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.363A, full [0114/152142:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.361A, full [0114/152212:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.5V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.353A, full [0114/152242:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.378A, full [0114/152312:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.361A, full [0114/152342:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.427A, full [0114/152411:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152412:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.358A, full [0114/152442:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.387A, full [0114/152512:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.358A, full [0114/152542:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.330A, full [0114/152620:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 1.318A, full [0114/152650:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 1.169A, full [0114/152711:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152720:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.1V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 1.082A, full [0114/152751:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 1.033A, full [0114/152821:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.987A, full [0114/152851:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.4V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.957A, full [0114/152921:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.930A, full [0114/152951:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.902A, full [0114/153011:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/153021:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.879A, full [0114/153051:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.857A, full [0114/153121:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.840A, full [0114/153151:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.4V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.820A, full [0114/153221:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.795A, full [0114/153251:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.786A, full [0114/153311:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/153321:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.763A, full [0114/153351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.1V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.739A, full [0114/153421:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.725A, full [0114/153436:INFO:state_controller.cc(89)] Dimming screen after 14m [0114/153451:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.706A, full [0114/153521:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.688A, full [0114/153536:INFO:state_controller.cc(89)] Turning screen off after 15m [0114/153536:INFO:display_power_setter.cc(81)] Asking DisplayService to turn all displays off [0114/153536:INFO:daemon.cc(1412)] Chrome is using presentation display mode [0114/153540:INFO:activity_logger.cc(20)] Audio activity stopped [0114/153551:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.1V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.671A, full [0114/153621:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.653A, full [0114/153651:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.645A, full [0114/153721:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.625A, full [0114/153751:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.607A, full [0114/153821:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.593A, full [0114/153851:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.4V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.577A, full [0114/153921:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.567A, full [0114/153951:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.543A, full [0114/154021:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.536A, full [0114/154051:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.519A, full [0114/154121:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.508A, full [0114/154151:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.4V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.500A, full [0114/154221:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.485A, full [0114/154251:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.470A, full [0114/154321:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.463A, full [0114/154351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.447A, full [0114/154421:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.439A, full [0114/154451:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.430A, full [0114/154521:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.421A, full [0114/154551:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.411A, full [0114/154621:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.393A, full [0114/154651:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.387A, full [0114/154721:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.376A, full [0114/154739:INFO:daemon.cc(1525)] Update operation is UPDATE_STATUS_CHECKING_FOR_UPDATE [0114/154739:INFO:daemon.cc(1525)] Update operation is UPDATE_STATUS_IDLE [0114/154751:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.370A, full [0114/154821:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.359A, full [0114/154851:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.353A, full [0114/154921:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.344A, full [0114/154951:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.334A, full [0114/155021:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.327A, full [0114/155051:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.317A, full [0114/155121:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.314A, full [0114/155151:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.305A, full [0114/155221:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.299A, full [0114/155251:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.293A, full [0114/155321:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.288A, full [0114/155351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.279A, full [0114/155421:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.266A, full [0114/155451:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.264A, full [0114/155521:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.261A, full [0114/155551:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.257A, full [0114/155621:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.248A, full [0114/155651:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.248A, full [0114/155721:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.1V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.238A, full [0114/155751:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.237A, full [0114/155821:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.234A, full [0114/155851:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.222A, full [0114/155921:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.223A, full [0114/155951:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.215A, full [0114/160021:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.210A, full [0114/160051:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.207A, full [0114/160121:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.196A, full [0114/160151:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.198A, full [0114/160221:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.185A, full [0114/160251:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.176A, full [0114/160321:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.179A, full [0114/160351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.177A, full [0114/160435:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.171A, full [0114/160505:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.162A, full [0114/160535:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.158A, full [0114/160605:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.149A, full [0114/160635:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.150A, full [0114/160705:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.147A, full [0114/160735:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.141A, full [0114/160805:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.136A, full [0114/160835:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.137A, full [0114/160905:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.132A, full [0114/160935:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.484/5.484Ah at 0.133A, full [0114/161001:INFO:activity_logger.cc(20)] User activity reported [0114/161001:INFO:state_controller.cc(96)] Undimming screen [0114/161001:INFO:state_controller.cc(96)] Turning screen on [0114/161001:INFO:display_power_setter.cc(81)] Asking DisplayService to turn internal display off and external displays on [0114/161001:INFO:daemon.cc(1412)] Chrome is using presentation display mode [0114/161001:INFO:activity_logger.cc(20)] Audio activity started Media Internals report none x 3 still
,
Jan 15 2018
Thank you all for the detailed reporting and debugging. I will check why Audio activity is not reported correctly.
,
Jan 15 2018
In #22, Here active audio device was changed to speaker: [0114/151745:INFO:audio_client.cc(125)] Updated audio devices: headphones unplugged, HDMI inactive However, there were still audio activity ongoing reported every 300 seconds: [0114/151811:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152111:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152411:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/152711:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/153011:INFO:activity_logger.cc(20)] Audio activity ongoing [0114/153311:INFO:activity_logger.cc(20)] Audio activity ongoing 0114/153321:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.763A, full [0114/153351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.1V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.739A, full [0114/153421:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.725A, full [0114/153436:INFO:state_controller.cc(89)] Dimming screen after 14m [0114/153451:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.706A, full [0114/153521:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.496/5.496Ah at 0.688A, full [0114/153536:INFO:state_controller.cc(89)] Turning screen off after 15m --> powerd decided to turn screen off. [0114/153536:INFO:display_power_setter.cc(81)] Asking DisplayService to turn all displays off [0114/153536:INFO:daemon.cc(1412)] Chrome is using presentation display mode [0114/153540:INFO:activity_logger.cc(20)] Audio activity stopped --> audio activity is stopped. I am using a HoHo adapter on Pixelbook eve R65-10249.0 image. I could not reproduce this issue. I will try to find an apple adapter.
,
Jan 15 2018
Using hoho HDMI adapter, with set_short_powerd_timeouts enabled. The system is put into suspend after youtube playback stopped for several seconds. [0115/141743:INFO:activity_logger.cc(20)] Audio activity ongoing [0115/141746:INFO:daemon.cc(776)] On battery at 98% (displayed as 100%), 5.305/5.420Ah at 0.748A, 6h37m35s until empty (6h25m24s until shutdown) [0115/141756:INFO:activity_logger.cc(20)] User activity reported [0115/141759:INFO:daemon.cc(1436)] Received updated external policy: ac_dim=7m ac_screen_off=7m30s ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=5m30s battery_lock=0s battery_idle_warn=0s battery_idle=6m30s ac_idle=suspend battery_idle=suspend lid_closed=suspend system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, Playing audio) [0115/141759:INFO:state_controller.cc(833)] Updated settings: dim=10s screen_off=15s lock=0s idle_warn=0s idle=20s (suspend) lid_closed=no-op use_audio=1 use_video=1 [0115/141759:INFO:state_controller.cc(845)] Wake locks: system [0115/141801:INFO:daemon.cc(1436)] Received updated external policy: ac_dim=7m ac_screen_off=7m30s ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=5m30s battery_lock=0s battery_idle_warn=0s battery_idle=6m30s ac_idle=suspend battery_idle=suspend lid_closed=suspend use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs) [0115/141801:INFO:state_controller.cc(833)] Updated settings: dim=10s screen_off=15s lock=0s idle_warn=0s idle=20s (suspend) lid_closed=no-op use_audio=1 use_video=1 [0115/141809:INFO:state_controller.cc(89)] Dimming screen after 10s [0115/141809:INFO:state_controller.cc(892)] Audio is currently being sent to display; screen will not be turned off for inactivity [0115/141809:INFO:activity_logger.cc(20)] Audio activity stopped [0115/141816:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0115/141816:INFO:daemon.cc(776)] On battery at 98% (displayed as 100%), 5.300/5.420Ah at 0.653A, 6h40m14s until empty (6h27m57s until shutdown) [0115/141816:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0115/141824:INFO:state_controller.cc(89)] Turning screen off after 15s [0115/141824:INFO:display_power_setter.cc(81)] Asking DisplayService to turn all displays off [0115/141824:INFO:audio_client.cc(125)] Updated audio devices: headphones plugged, HDMI inactive [0115/141824:INFO:daemon.cc(1418)] Chrome is using presentation display mode [0115/141829:INFO:state_controller.cc(971)] Ready to perform idle action (suspend) after 20s [0115/141829:INFO:suspender.cc(383)] Starting request 1145176066 [0115/141829:INFO:daemon.cc(595)] Reading wakeup count from /sys/power/wakeup_count [0115/141829:INFO:daemon.cc(599)] Read wakeup count 10 [0115/141829:INFO:internal_backlight_controller.cc(693)] Setting resume brightness to 7044 (36.14%) [0115/141829:INFO:suspend_delay_controller.cc(137)] Announcing suspend request 1145176066 with 3 pending delay(s) and 0 outstanding delay(s) from previous request [0115/141829:INFO:suspend_delay_controller.cc(86)] Got notification that delay 1145176067 (trunksd) is ready for suspend request 1145176066 from :1.18 [0115/141829:INFO:suspend_delay_controller.cc(86)] Got notification that delay 1145176065 (shill) is ready for suspend request 1145176066 from :1.3 [0115/141829:INFO:suspend_delay_controller.cc(86)] Got notification that delay 1145176066 (chrome) is ready for suspend request 1145176066 from :1.62 [0115/141829:INFO:suspend_delay_controller.cc(223)] Notifying observers that suspend is ready [0115/141829:INFO:suspender.cc(456)] Starting suspend [0115/141829:INFO:main.cc(244)] Running "/usr/bin/powerd_setuid_helper --action=suspend --suspend_wakeup_count_valid --suspend_wakeup_count=10"
,
Jan 15 2018
I apologize, again this is my first chromebook. Can you clarify this: "I am using a HoHo adapter on Pixelbook eve R65-10249.0 image. I could not reproduce this issue." Does this mean you are using chromeos versioned "65"? -Steve
,
Jan 16 2018
Hi Stephen. eve is the board name of pixelbook. I was using R65-10249.0 image to test. The hoho dongle I was using was this one https://www.chromium.org/chromium-os/hoho. Now I got more type-C to HDMI dongles to test, including Apple ones. https://en.j5create.com/collections/type-c/products/jca374?variant=18868539524 https://www.apple.com/shop/product/MJ1K2AM/A/usb-c-digital-av-multiport-adapter https://www.apple.com/shop/product/HJUZ2ZM/A/belkin-usb-c-to-vga-adapter?fnode=8b And I switched my image to R63-10032.86.0 image. I played music using google play music webapp. Close the lid, wait for a while, and stop the music. In /var/log/power_manager/powerd.LATEST I can see audio activity is stopped. [0116/115512:INFO:activity_logger.cc(20)] Audio activity stopped [0116/115517:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0116/115530:INFO:daemon.cc(771)] On battery at 93% (displayed as 96%), 5.055/5.431Ah at 0.572A, 6h52m49s until empty (6h39m31s until shutdown) [0116/115600:INFO:daemon.cc(771)] On battery at 93% (displayed as 96%), 5.050/5.431Ah at 0.607A, 7h39m30s until empty (7h24m40s until shutdown) [0116/115630:INFO:daemon.cc(771)] On battery at 93% (displayed as 96%), 5.046/5.431Ah at 0.571A, 7h46m19s until empty (7h31m15s until shutdown) [0116/115700:INFO:daemon.cc(771)] On battery at 93% (displayed as 96%), 5.041/5.431Ah at 0.580A, 7h49m5s until empty (7h33m55s until shutdown) [0116/115730:INFO:daemon.cc(771)] On battery at 93% (displayed as 95%), 5.036/5.431Ah at 0.576A, 7h54m25s until empty (7h39m4s until shutdown) [0116/115800:INFO:daemon.cc(771)] On battery at 93% (displayed as 95%), 5.031/5.431Ah at 0.585A, 7h51m57s until empty (7h36m40s until shutdown) [0116/115830:INFO:daemon.cc(771)] On battery at 93% (displayed as 95%), 5.026/5.431Ah at 0.566A, 7h58m8s until empty (7h42m38s until shutdown) [0116/115900:INFO:daemon.cc(771)] On battery at 92% (displayed as 95%), 5.021/5.431Ah at 0.583A, 7h55m58s until empty (7h40m31s until shutdown) [0116/115930:INFO:daemon.cc(771)] On battery at 92% (displayed as 95%), 5.017/5.431Ah at 0.562A, 7h58m37s until empty (7h43m5s until shutdown) [0116/120000:INFO:daemon.cc(771)] On battery at 92% (displayed as 95%), 5.012/5.431Ah at 0.563A, 8h19s until empty (7h44m42s until shutdown) [0116/120030:INFO:daemon.cc(771)] On battery at 92% (displayed as 95%), 5.007/5.431Ah at 0.662A, 7h47m32s until empty (7h32m19s until shutdown) I could not reproduce the issue for all the dongles I have in hand including apple dongles. I suspect there is other apps,perhaps from Android, using audio, and maybe that app is sensitive to output type. If some Android app is using audio, it will not be listed in chrome://media-internals. Could you please file another feedback report when you think there should not be any audio activity, but powerd.LATEST still shows audio activity on-going ? Please put " issue 799727 " and your mail address "stephenmbarcomb@gmail.com" in the text field. I can guess whether the audio stream is input or output, and its stream configs. That might give us some clue what is using audio. Thank you very much for the help!
,
Jan 16 2018
Hello, I will do this today, but I am using my computer for work so it may not be until this afternoon. A couple points. 1) I am not sure if it matters, but I am operating the pixelbook in clamshell mode. I am not using both screens simultaneously. 2) I see I was pushed an update from the build I opened the bug against. I am now reading: Version 63.0.3239.140 (Official Build) (64-bit) The reason I mentioned this is that the display may have slept overnight. I was watching youtube last night and, evidently, forgot to switch the output. This might be a mistake, and I hesitated to mention it since this was pre-coffee. I will leave the output to HDMI today to validate and because it's needed for the logs you requested. I never paid attention to the firmware or other versions in the "Detailed Build Information" so I don't know what in there may have also changed. -Steve
,
Jan 17 2018
1) I submitted a feedback report from my wife's pixelbook since hers is still exhibiting the behavior I reported. (with the text you requested) 2) My system is sleeping the display _properly_ with HDMI sound output selected and I have noticed quite a few messages like this in my powerd.LATEST that appear to be new (the state_controller messages): [0116/200338:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200339:INFO:activity_logger.cc(20)] Video activity reported [0116/200351:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.503/5.503Ah at 0.585A, full [0116/200359:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0116/200409:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, Uploading data to gws-us.genesyscloud.com) [0116/200409:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200409:INFO:state_controller.cc(832)] Wake locks: system [0116/200409:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs) [0116/200409:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200421:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.503/5.503Ah at 0.570A, full [0116/200440:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, Uploading data to gws-us.genesyscloud.com) [0116/200440:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200440:INFO:state_controller.cc(832)] Wake locks: system [0116/200440:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs) [0116/200440:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200451:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.503/5.503Ah at 0.555A, full [0116/200521:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.503/5.503Ah at 0.544A, full [0116/200540:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, Uploading data to gws-us.genesyscloud.com) [0116/200540:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200540:INFO:state_controller.cc(832)] Wake locks: system [0116/200540:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=no-op battery_idle=no-op lid_closed=suspend use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs) [0116/200540:INFO:state_controller.cc(820)] Updated settings: dim=14m screen_off=15m lock=0s idle_warn=0s idle=37m (no-op) lid_closed=no-op use_audio=1 use_video=1 [0116/200542:INFO:activity_logger.cc(20)] User activity stopped; last reported 20 sec ago [0116/200551:INFO:daemon.cc(771)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.503/5.503Ah at 0.527A, full [0116/200555:INFO:activity_logger.cc(20)] User activity reported This seems like a lot, maybe it's expected: $ grep 'state_controller.cc(820)] Updated settings' powerd.LATEST | grep ^'\[0116' | wc -l 5530 I checked the other days. My .LATEST starts on the 13th: $ head -n1 powerd.LATEST [0113/114728:INFO:main.cc(286)] vcsid 0.0.2-r2316-8bb2b0e4db3199843091b8c40cd9148f64709aa1 Again, not sure if it means anything, maybe I am just adding noise here: $ grep 'state_controller.cc(820)] Updated settings' powerd.LATEST | grep ^'\[0113' | wc -l 170 $ grep 'state_controller.cc(820)] Updated settings' powerd.LATEST | grep ^'\[0114' | wc -l 154 $ grep 'state_controller.cc(820)] Updated settings' powerd.LATEST | grep ^'\[0115' | wc -l 810 We both have used the systems like normal, so I am not sure what to think. Thank you for the help here. -Steve
,
Jan 17 2018
Hi Steve, I am sorry. I still could not find the report you submitted. Maybe there is something wrong with the report system. I am glad it works fine on your laptop after the update. I am using 63.0.3239.140 chrome version just like yours. If you can still reproduce the issue on your wife's pixelbook, could you please do me a favor to paste the logs in chrome://system ? Specifically, when these happens: 1. Play, and then stop the playback on play music webapp. Audio output choice is HDMI. 2. chrome://media-internals shows there is nothing playing audio. 3. /var/log/power_manager/powerd.LATEST shows Audio activity ongoing. 4. You are pretty sure there is nothing using audio (both input and output, including Android apps) Then, please go to chrome://system In the audio_diagnostics fields, click "Expand", then and copy the contents in it. E.g. This is what I got when my play music webapp is playing audio. It will show all kinds of information that will help debug. === cras_test_client --dump_server_info === System Volume (0-100): 100 Capture Gain (-17.25 - 30.00): 20.00dB User muted: Not muted Output Devices: ID Name 10 kbl_r5514_5663_max: :0,5 9 kbl_r5514_5663_max: :0,4 6 kbl_r5514_5663_max: :0,0 Output Nodes: Stable Id ID Vol Plugged L/R swapped Time Hotword Type Name (8b44524e) 10:0 100 no no 0 HDMI HDMI2 (29dff288) 9:0 100 no no 0 HDMI HDMI1 (72a57953) 6:0 20 yes no 1510016676 INTERNAL_SPEAKER*Speaker (9e934263) 6:1 27 no no 1510016676 HEADPHONE Headphone Input Devices: ID Name 11 kbl_r5514_5663_max: :0,3 8 kbl_r5514_5663_max: :0,1 7 kbl_r5514_5663_max: :0,2 5 Post DSP Loopback 4 Post Mix Pre DSP Loopback Input Nodes: Stable Id ID Gain Plugged L/R swapped Time Hotword Type Name (b7cc29c2) 11:0 0 yes no 1510016676 en_us HOTWORD Wake on Voice (61aab6ba) 8:0 0 no no 0 MIC Mic (6c49f142) 7:0 7 yes no 1510016676 INTERNAL_MIC *Internal Mic (8c74f766) 5:0 0 yes no 0 POST_DSP_LOOPBACK Post DSP Loopback (8a04af91) 4:0 0 yes no 0 POST_MIX_LOOPBACK Post Mix Pre DSP Loopback Attached clients: ID pid uid 0 4425 656365 1 4425 656365 7 1296 1000 9 8431 1000 Num active streams: 1 Last audio active time: 479, 77940014 === cras_test_client --dump_audio_thread === Audio Debug Stats: -------------devices------------ Output dev: kbl_r5514_5663_max: :0,0 buffer_size: 16384 min_buffer_level: 0 min_cb_level: 1024 max_cb_level: 1115 frame_rate: 48000 num_channels: 2 est_rate_ratio: 1.000000 num_underruns: 0 num_severe_underruns: 0 -------------stream_dump------------ stream: 70001 dev: 6 direction: Output stream_type: CRAS_STREAM_TYPE_DEFAULT buffer_frames: 2048 cb_threshold: 1024 frame_rate: 44100 num_channels: 2 longest_fetch_sec: 0.000000000 num_overruns: 0 channel map:0 1 -1 -1 -1 -1 -1 -1 -1 -1 -1 Thank you very much again!
,
Jan 18 2018
Pixel feedback is being tracked separately. stephenmbarcomb@'s feedback is at https://feedback.corp.google.com/product/5015361/neutron?lView=rl&lRSort=1&lROrder=2&lRFilter=1&lReportSearch=user:stephenmbarcomb@gmail.com
,
Jan 18 2018
Apologies for the late reply. We had a snowstorm which caused a loss of power yesterday. My machine had started exhibiting the symptoms again, so for simplicity this is from my pixelbook. Let me know if you would like her logging data as well. -Steve
,
Jan 29 2018
Thank you Kalin for the pointer!
Thank you Stephen for the report!
Your log was very useful.
Num active streams: 1
12931.506236802 STREAM_REMOVED id:1d0000 ---------------> stream was removed
12931.506240354 ODEV_NO_STREAMS dev:10
12931.506316603 SET_DEV_WAKE dev:10 hw_level:3080 sleep:2056
12931.506317844 DEV_SLEEP_TIME dev:10 wake:000012931.552936169
12931.506320958 SLEEP sleep:000000000.046631739 longest_wake:001838042
12931.553136237 WAKE num_fds:0
12931.553213286 FILL_ODEV_ZEROS dev:10 write:1032
12931.553275147 SET_DEV_WAKE dev:10 hw_level:2048 sleep:1024
12931.553276299 DEV_SLEEP_TIME dev:10 wake:000012931.576493552
12931.553279012 SLEEP sleep:000000000.023231650 longest_wake:001838042
12931.576609179 WAKE num_fds:0
12931.576662503 FILL_ODEV_ZEROS dev:10 write:1016
12931.576689204 SET_DEV_WAKE dev:10 hw_level:2048 sleep:1024
12931.576690499 DEV_SLEEP_TIME dev:10 wake:000012931.599905994
12931.576694088 SLEEP sleep:000000000.023232185 longest_wake:001838042
12931.600130166 WAKE num_fds:0
12931.600202197 SLEEP sleep:000000000.000000000 longest_wake:001838042
12941.511604017 WAKE num_fds:1
12941.511617297 PB_MSG msg_id:1
12941.511619488 DEV_REMOVED dev:10 -------------------> device was removed.
12941.511638997 SLEEP sleep:000000000.000000000 longest_wake:001838042
13352.957099484 WAKE num_fds:1
13352.957103266 PB_MSG msg_id:5
13352.957172168 SLEEP sleep:000000000.000000000 longest_wake:001838042
13352.989721720 WAKE num_fds:1
13352.989725763 PB_MSG msg_id:5
So this seems to be a bug in CRAS.
The number of active stream is not updated correctly even when stream and device were removed gracefully.
Louis will take a look.
,
Jan 29 2018
,
Jan 29 2018
,
Jan 29 2018
Is there any additional information I can provide or do you have everything you need? Thank you all again for the efforts. -Steve
,
Jan 29 2018
Hi Steve, Could you please grab audio_diagnostic at these two timings? a. when music was playing, and the music player is the only audio source. b. 30 seconds after music was stopped, and you think there should be no audio playing at all. From a to b, the correct number of active stream should be 1 -> 0. I am wondering what would be the change of number of active streams. 2->1 and 1->1 will give us different clues to the underlying issue. Thank you so much!
,
Jan 30 2018
Audio diags with music playing. For whatever reason chrome://system takes forever to load, like 2-3 full minutes. Diags with the music stopped incoming shortly.
,
Jan 30 2018
"No audio" diags. Please let me know if there are any other tests you would like me to do. Thanks, Steve
,
Jan 30 2018
Hi Steve, Thank you again!
In music_playing.out, there are two streams, 1 input, 1 output.
Num active streams: 2
Last audio active time: 983202, 362164419
=== cras_test_client --dump_audio_thread ===
Audio Debug Stats:
-------------devices------------
Output dev: kbl_r5514_5663_max: :0,5
buffer_size: 16384
min_buffer_level: 0
min_cb_level: 1024
max_cb_level: 1024
frame_rate: 44100
num_channels: 2
est_rate_ratio: 1.000000
num_underruns: 0
num_severe_underruns: 0
Input dev: kbl_r5514_5663_max: :0,2
buffer_size: 8192
min_buffer_level: 0
min_cb_level: 128
max_cb_level: 385
frame_rate: 48000
num_channels: 4
est_rate_ratio: 1.000000
num_underruns: 0
num_severe_underruns: 6
-------------stream_dump------------
stream: 2280000 dev: 10
direction: Output
stream_type: CRAS_STREAM_TYPE_DEFAULT
buffer_frames: 2048
cb_threshold: 1024
frame_rate: 44100
num_channels: 2
longest_fetch_sec: 0.020694747
num_overruns: 0
channel map:0 1 -1 -1 -1 -1 -1 -1 -1 -1 -1
stream: 2230001 dev: 7
direction: Input
stream_type: CRAS_STREAM_TYPE_DEFAULT
buffer_frames: 256
cb_threshold: 128
frame_rate: 16000
num_channels: 1
longest_fetch_sec: 0.000000000
num_overruns: 0
channel map:0 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1
In no_audio.out, there is no stream, but the stream count is 1:
Num active streams: 1
Last audio active time: 983592, 770021785
=== cras_test_client --dump_audio_thread ===
Audio Debug Stats:
-------------devices------------
-------------stream_dump------------
And, the interesting thing is, device was removed, but stream was not removed:
983558.849612670 READ_AUDIO_DONE read_remainder:0
983558.849613837 CAPTURE_POST stream:2230001 thresh:128 rd_buf:1
983558.849627798 DEV_SLEEP_TIME dev:7 wake:000983558.857516211
983558.849628965 SLEEP sleep:000000000.007889053 longest_wake:003152362
983558.857533551 WAKE num_fds:0
983558.857560562 READ_AUDIO_TSTAMP dev:7 tstamp:000983558.857559648
983558.857567337 READ_AUDIO dev:7 hw_level:8192 read:385
983558.857626120 CONV_COPY wr_buf:0 shm_writable:128 offset:0
983558.857628881 CAPTURE_WRITE stream:2230001 write:128 shm_fr:0
983558.857630043 READ_AUDIO_DONE read_remainder:0
983558.857631228 CAPTURE_POST stream:2230001 thresh:128 rd_buf:0
983558.857646670 DEV_SLEEP_TIME dev:7 wake:000983558.865516210
983558.857647826 SLEEP sleep:000000000.007870187 longest_wake:003152362
983558.865535027 WAKE num_fds:0
983558.865558828 DEV_REMOVED dev:7
983558.865565429 SLEEP sleep:000000000.000000000 longest_wake:003152362 ---------------> Device removed.
983592.713329292 WAKE num_fds:1
983592.713334295 PB_MSG msg_id:5
983592.713357675 SLEEP sleep:000000000.000000000 longest_wake:003152362
983592.771718447 WAKE num_fds:1
983592.771722424 PB_MSG msg_id:5
Ideally, there should be a STREAM_REMOVED before DEV_REMOVED.
But there was no STREAM_REMOVED.
I need to check why that happened.
,
Jan 30 2018
We have more findings: This input stream is from assistant app stream: 2230001 dev: 7 direction: Input stream_type: CRAS_STREAM_TYPE_DEFAULT buffer_frames: 256 cb_threshold: 128 frame_rate: 16000 num_channels: 1 longest_fetch_sec: 0.000000000 num_overruns: 0 channel map:0 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 1. Assistant app creates this input stream and listen to hotword. It should be closed when screen is off. We need to check whether this behavior happened correctly. 2. Another issue is, when driver has severe underrun, CRAS needs to reset the audio device. Currently we only do this for output device, not input device. When severe underrun happens, we close input device. That's why we see DEV_REMOVED, but no STREAM_REMOVED. I will check both issue 1 and 2 .
,
Jan 30 2018
I think I solved the mystery.
The ideal scenario for power manager to decide to suspend is:
1. assistant app start recording
2. no user activity
3. power manager decides to turn off screen.
4. assistant app stop recording.
5. power manager decides to suspend.
The fact that there is a stream in audio_diags_no_audio.out is expected.
When screen is on, there will be an input stream created by assistant app.
So, we can ignore #40 for now.
Now, the problem is why the screen does not turn off in step 3.
From previous log, we know powerd will not turn off display when it sees audio activity. E.g:
[0112/174008:INFO:state_controller.cc(879)] Audio is currently being sent to display; screen will not be turned off for inactivity
However, the logic is not correct.
src/platform2/power_manager/powerd/policy/state_controller.cc
void StateController::UpdateState() {
...
if (screen_dimmed_ && !screen_was_dimmed && audio_activity_->active() &&
delegate_->IsHdmiAudioActive()) {
LOG(INFO) << "Audio is currently being sent to display; screen will not be "
<< "turned off for inactivity";
...
}
However, the audio_activity_->active() means there is audio activity (might be input or output).
delegate_->IsHdmiAudioActive() means HDMI is selected (might not have activity on it).
So, when these two condition met:
1. assistant app is recording, audio_activity_->active() will be true.
2. HDMI is selected as active device, delegate_->IsHdmiAudioActive() will be true.
power manager decides not to turn off display.
Then, since power manager does not turn off display, assistant app decides to keep recording, waiting for Hotword Ok Google.
The result is, display will never be turned off, and system will not suspend.
I think to fix this, we need to make API clearer.
That is, dbus client like power manager should be able to query whether the active stream is output or not.
In addition, it might be better to have a different API to answer the specific question:
"Is there any output stream on HDMI ?"
Since it might happen when an output stream is pinned to a different device, while HDMI device is selected by user as active device.
I will let Louis take over this, since there will be API change for issue https://bugs.chromium.org/p/chromium/issues/detail?id=753596, which Louis is actively working on.
,
Jan 30 2018
,
Jan 30 2018
Hi Steve, apology for the inconvenience. Thank you very much for helping us find the root cause. Unfortunately, it will not be an easy fix to be pushed to you in R64. Before the real fix is pushed, please switch to speaker for active output for device to sleep properly. Or, if you don't use assistant app at all, you can disable it by: in chrome://settings, search "assistant", and disable it. Thanks again!
,
Jan 30 2018
Thanks for the update. I will give disabling the assistant a try since I use the hdmi audio more than I use the assistant. (I have my phone/google home). -Steve
,
Jan 30 2018
This isn't the first time that hotwording has blocked suspend. I wrote https://crrev.com/c/191680 in 2016 to make powerd disregard audio input for deferring suspend, but didn't check it in (I think that someone just fixed the hotwording implementation instead). Should I land it now?
,
Jan 31 2018
Hi derat, That's great! I totally forgot it! Also it was me that added the GetNumberOfActiveOutputStreams API :p https://chromium-review.googlesource.com/c/chromiumos/third_party/adhd/+/191395 Your power manager patch will need to be rebased to platform2. Could you please rebase it and land it ? Thanks!
,
Jan 31 2018
I am not sure if anyone needs affirmation, but disabling the assistant while leaving HDMI enabled does allow the screen to sleep. Does the mentioned patch change anything timeline wise, or I guess "what is a reasonable expectation for a fix to be in the stable channel?" Thanks, Steve
,
Jan 31 2018
Updated the old change and reuploaded it as https://crrev.com/c/894827. #48: Probably Chrome 65, i.e. mid-March.
,
Feb 2 2018
The following revision refers to this bug: https://chromium.googlesource.com/chromiumos/platform2/+/0b851b078c59b6f16653fea49d7eac58d6f0043b commit 0b851b078c59b6f16653fea49d7eac58d6f0043b Author: Daniel Erat <derat@chromium.org> Date: Fri Feb 02 02:08:53 2018 power: Ignore audio input for deferring suspend. Avoid keeping the system awake due to active audio input streams. BUG= chromium:336459 , chromium:799727 TEST=manual powerd no longer logs audio activity when arecord is running but still logs it for aplay Change-Id: I7765bdc60222f080a422744e23f98bbebd41d564 Reviewed-on: https://chromium-review.googlesource.com/894827 Commit-Ready: Dan Erat <derat@chromium.org> Tested-by: Dan Erat <derat@chromium.org> Reviewed-by: Cheng-Yi Chiang <cychiang@chromium.org> [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/system/audio_client.cc [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/system/audio_client_interface.h [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/daemon.cc [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/system/audio_client.h [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/system/audio_client_stub.cc [modify] https://crrev.com/0b851b078c59b6f16653fea49d7eac58d6f0043b/power_manager/powerd/system/audio_client_stub.h
,
Feb 26 2018
,
Mar 23 2018
Was this supposed to have been added in Chrome OS 65? I still have the audio activity ongoing after the update this afternoon: [0323/141653:INFO:state_controller.cc(917)] Audio is currently being sent to display; screen will not be turned off for inactivity [0323/141721:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.541A, full [0323/141751:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.528A, full [0323/141754:INFO:activity_logger.cc(20)] Audio activity ongoing [0323/141821:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.507A, full [0323/141851:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.502A, full [0323/141921:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.488A, full [0323/141951:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.481A, full [0323/142021:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.467A, full [0323/142051:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.456A, full [0323/142054:INFO:activity_logger.cc(20)] Audio activity ongoing [0323/142121:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.443A, full [0323/142151:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.432A, full [0323/142221:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.422A, full [0323/142251:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.417A, full [0323/142321:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.405A, full [0323/142351:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.394A, full [0323/142354:INFO:activity_logger.cc(20)] Audio activity ongoing [0323/142421:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.386A, full [0323/142451:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.381A, full [0323/142521:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.374A, full [0323/142526:INFO:daemon.cc(1425)] Chrome is using presentation display mode [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.0/0003:04D9:0169.000B/input/input16 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off wakeup wakeup_only_when_usable inhibit role_external_input [0323/142529:INFO:input_device_controller.cc(223)] Un-inhibiting /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.0/0003:04D9:0169.000B/input/input16 [0323/142529:INFO:input_device_controller.cc(211)] Enabling wakeup for /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.0/0003:04D9:0169.000B/input/input16 through /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1 [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.1/0003:04D9:0169.000C/input/input17 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off wakeup wakeup_only_when_usable inhibit role_external_input [0323/142529:INFO:input_device_controller.cc(223)] Un-inhibiting /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.1/0003:04D9:0169.000C/input/input17 [0323/142529:INFO:input_device_controller.cc(211)] Enabling wakeup for /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.1/0003:04D9:0169.000C/input/input17 through /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1 [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.2/0003:046D:C52B.000F/0003:046D:405E.0010/input/input18 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off wakeup wakeup_only_when_usable inhibit role_external_input [0323/142529:INFO:input_device_controller.cc(223)] Un-inhibiting /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.2/0003:046D:C52B.000F/0003:046D:405E.0010/input/input18 [0323/142529:INFO:input_device_controller.cc(211)] Enabling wakeup for /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.2/0003:046D:C52B.000F/0003:046D:405E.0010/input/input18 through /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.2 [0323/142529:INFO:input_watcher.cc(406)] Watching power button: /dev/input/event8 [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.0/0003:04D9:0169.000B/input/input16/event8 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off role_external_input [0323/142529:INFO:input_watcher.cc(406)] Watching power button: /dev/input/event10 [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.2/0003:046D:C52B.000F/0003:046D:405E.0010/input/input18/event10 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off role_external_input [0323/142529:INFO:input_watcher.cc(406)] Watching power button: /dev/input/event9 [0323/142529:INFO:udev.cc(276)] Adding device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-1/1-1.1/1-1.1.1/1-1.1.1:1.1/0003:04D9:0169.000C/input/input17/event9 with tags usable_when_docked usable_when_laptop usable_when_tablet usable_when_display_off role_external_input [0323/142533:INFO:activity_logger.cc(20)] User activity reported [0323/142533:INFO:state_controller.cc(437)] Scaling delays due to user activity while screen was dimmed or soon after it was turned off [0323/142533:INFO:state_controller.cc(855)] Updated settings: dim=14m screen_off=14m30s lock=0s idle_warn=0s idle=37m (suspend) lid_closed=no-op use_audio=1 use_video=1 [0323/142533:INFO:state_controller.cc(96)] Undimming screen [0323/142551:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.361A, full [0323/142555:INFO:activity_logger.cc(20)] Video activity reported [0323/142621:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.353A, full [0323/142646:INFO:activity_logger.cc(20)] Video activity stopped; last reported 20 sec ago [0323/142651:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.3V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.355A, full [0323/142654:INFO:activity_logger.cc(20)] Audio activity ongoing [0323/142713:INFO:activity_logger.cc(20)] Video activity reported [0323/142721:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.339A, full [0323/142751:INFO:daemon.cc(782)] On AC (USB_PD, 0.000A at 20.2V, max 1.9A at 18.8V) with battery at 100%, 5.548/5.548Ah at 0.336A, full
,
Mar 23 2018
#52: Sorry, I think I must've misread the release calendar earlier. :-/ The release-R65-10323.B branch was cut on Jan 19, so the change in #50 missed it. My apologies. I'll request a merge (the change has had a long time to bake, so this seems safe to me), but I don't know whether any additional stable releases are planned on 65.
,
Mar 24 2018
I am uncertain how chrome OS releases work. If it does not make a 65 release will it appear in 66?
,
Mar 24 2018
Yes, it will.
,
Mar 26 2018
At this point we are getting too late for 65, this should be in 66 though. |
||||||||||||||||||||||||||||
►
Sign in to add a comment |
||||||||||||||||||||||||||||
Comment 1 by vsu...@google.com
, Jan 10 2018Components: Platform