New issue
Advanced search Search tips
Note: Color blocks (like █ or █) mean that a user may not be available. Tooltip shows the reason.

Issue 591801 link

Starred by 2 users
Status: WontFix
Owner:
achuith@chromium.org
Closed: Feb 2017
Cc:
cywang@chromium.org
steve...@chromium.org
█ wiley@chromium.org
ihf@chromium.org
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

graphics_idle: Chrome crashes during login into guest mode.

Project Member Reported by sbasi@chromium.org, Mar 3 2016 
This failed in the CQ:

http://cautotest.corp.google.com/afe/#tab_id=view_job&object_id=55195159

02/26 20:18:12.119 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::START	----	----	timestamp=1456546691	localtime=Feb 26 20:18:11	
02/26 20:18:12.120 INFO |        server_job:0128| START	----	----	timestamp=1456546691	localtime=Feb 26 20:18:11	
02/26 20:18:12.290 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::	START	graphics_Idle	graphics_Idle	timestamp=1456546691	localtime=Feb 26 20:18:11	
02/26 20:18:12.290 INFO |        server_job:0128| 	START	graphics_Idle	graphics_Idle	timestamp=1456546691	localtime=Feb 26 20:18:11	
02/26 20:18:42.334 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::		FAIL	graphics_Idle	graphics_Idle	timestamp=1456546714	localtime=Feb 26 20:18:34	Unhandled WebSocketConnectionClosedException: 
02/26 20:18:42.335 INFO |        server_job:0128| 		FAIL	graphics_Idle	graphics_Idle	timestamp=1456546714	localtime=Feb 26 20:18:34	Unhandled WebSocketConnectionClosedException: 
02/26 20:18:42.336 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::  Traceback (most recent call last):
02/26 20:18:42.336 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/common_lib/test.py", line 741, in _call_test_function
02/26 20:18:42.336 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      return func(*args, **dargs)
02/26 20:18:42.336 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/common_lib/test.py", line 398, in execute
02/26 20:18:42.337 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      dargs)
02/26 20:18:42.337 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/common_lib/test.py", line 284, in _call_run_once_with_retry
02/26 20:18:42.337 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      postprocess_profiled_run, args, dargs)
02/26 20:18:42.337 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/common_lib/test.py", line 313, in _call_run_once
02/26 20:18:42.337 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self.run_once(*args, **dargs)
02/26 20:18:42.338 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/tests/graphics_Idle/graphics_Idle.py", line 36, in run_once
02/26 20:18:42.338 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      with chrome.Chrome(logged_in=False, extra_browser_args=['--kiosk']):
02/26 20:18:42.338 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/autotest/common_lib/cros/chrome.py", line 114, in __init__
02/26 20:18:42.338 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._browser = browser_to_create.Create(finder_options)
02/26 20:18:42.339 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_finder.py", line 51, in Create
02/26 20:18:42.394 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._credentials_path)
02/26 20:18:42.395 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_with_oobe.py", line 14, in __init__
02/26 20:18:42.395 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      backend, platform_backend, credentials_path)
02/26 20:18:42.395 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py", line 55, in __init__
02/26 20:18:42.395 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._browser_backend.Start()
02/26 20:18:42.396 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_backend.py", line 130, in Start
02/26 20:18:42.396 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self.oobe.NavigateGuestLogin()
02/26 20:18:42.396 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_backend.py", line 184, in oobe
02/26 20:18:42.396 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      return self.misc_web_contents_backend.GetOobe()
02/26 20:18:42.397 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/misc_web_contents_backend.py", line 30, in GetOobe
02/26 20:18:42.397 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      return self[0]
02/26 20:18:42.397 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend_list.py", line 64, in __getitem__
02/26 20:18:42.397 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      return self.GetBackendFromContextId(context_id)
02/26 20:18:42.398 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend_list.py", line 75, in GetBackendFromContextId
02/26 20:18:42.398 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      context_id)
02/26 20:18:42.398 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/devtools_client_backend.py", line 441, in GetInspectorBackend
02/26 20:18:42.398 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._app_backend.app, self._devtools_client, context)
02/26 20:18:42.399 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 68, in __init__
02/26 20:18:42.399 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._websocket, timeout=timeout)
02/26 20:18:42.399 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_page.py", line 26, in __init__
02/26 20:18:42.400 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._EnablePageNotifications(timeout=timeout)
02/26 20:18:42.400 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_page.py", line 80, in _EnablePageNotifications
02/26 20:18:42.400 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      res = self._inspector_websocket.SyncRequest(request, timeout)
02/26 20:18:42.400 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 110, in SyncRequest
02/26 20:18:42.400 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      res = self._Receive(timeout)
02/26 20:18:42.401 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 149, in _Receive
02/26 20:18:42.401 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      data = self._socket.recv()
02/26 20:18:42.401 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 596, in recv
02/26 20:18:42.401 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      opcode, data = self.recv_data()
02/26 20:18:42.401 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 606, in recv_data
02/26 20:18:42.402 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      frame = self.recv_frame()
02/26 20:18:42.402 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 637, in recv_frame
02/26 20:18:42.402 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      self._frame_header = self._recv_strict(2)
02/26 20:18:42.402 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 746, in _recv_strict
02/26 20:18:42.402 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      bytes = self._recv(shortage)
02/26 20:18:42.403 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::    File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 739, in _recv
02/26 20:18:42.403 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::      raise WebSocketConnectionClosedException()
02/26 20:18:42.403 DEBUG|     site_autotest:0186| AUTOTEST_STATUS::  WebSocketConnectionClosedException


Few more instances on TOT: https://wmatrix.googleplex.com/unfiltered?hide_missing=True&tests=graphics_Idle&days_back=50&releases=51

Ilja suspects this is a mildy bad chrome change that slipped through the PFQ.

CC-ing garderners and sheriffs.

Comment 1 by ihf@chromium.org, Mar 3 2016 

Crash reporting didn't work

Could not load the device policy file.
Received crash notification for chrome[17254] sig 11, user 1000 (developer build - not testing - always dumping)
State of crashed process [17254]: D (disk sleep)
Could not open core file /var/spool/crash/chrome.20160226.201828.17254.core: No such file or directory
Leaving core file at /var/spool/crash/chrome.20160226.201828.17254.core due to conversion error
Writing conversion problems as separate crash report.

--

Probably a renderer crash:
[17089:17089:0226/201819:VERBOSE1:wizard_controller.cc(269)] Starting OOBE wizard with screen: 
[17089:17089:0226/201819:VERBOSE1:wizard_controller.cc(405)] Showing network screen.
[17089:17089:0226/201819:ERROR:webui_login_view.cc(462)] WebUILoginView loading progress updated to 1
[17089:17089:0226/201819:FATAL:login_display_host_impl.cc(815)] Renderer crash on login window

--

But there are several

2016-02-27T04:18:17.742139+00:00 INFO session_manager[17041]: [INFO:browser_job.cc(102)] Running child chrome...
2016-02-27T04:18:17.742178+00:00 INFO session_manager[17041]: [INFO:session_manager_service.cc(225)] Browser is 17089
2016-02-27T04:18:18.768946+00:00 INFO kernel: [  127.503315] max98090 7-0010: DMIC Mux: put_dmic_mux enable DMIC
2016-02-27T04:18:18.768976+00:00 INFO kernel: [  127.504998] max98090 7-0010: DMIC Mux: put_dmic_mux disable DMIC
2016-02-27T04:18:18.883922+00:00 INFO permission_broker[1103]: ProcessPath(/dev/bus/usb/004/001)
2016-02-27T04:18:19.014345+00:00 WARNING crash_reporter[17184]: Could not load the device policy file.
2016-02-27T04:18:19.015070+00:00 WARNING crash_reporter[17184]: Received crash notification for chrome[17180] sig 4, user 1000 (developer build - not testing - always dumping)
2016-02-27T04:18:19.022602+00:00 INFO crash_reporter[17184]: State of crashed process [17180]: S (sleeping)
2016-02-27T04:18:19.037982+00:00 INFO metrics_daemon[1739]: [INFO:metrics_daemon.cc(385)] Got org.chromium.CrashReporter.UserCrash D-Bus signal
2016-02-27T04:18:19.039529+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.DailyUseTime for reading: No such file or directory
2016-02-27T04:18:19.039575+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.UserCrashInterval for reading: No such file or directory
2016-02-27T04:18:19.039598+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.KernelCrashInterval for reading: No such file or directory
2016-02-27T04:18:19.041195+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/daily.cycle for reading: No such file or directory
2016-02-27T04:18:19.042431+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.AnyCrashesDaily for reading: No such file or directory
2016-02-27T04:18:19.043224+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.UserCrashesDaily for reading: No such file or directory
2016-02-27T04:18:19.044278+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.KernelCrashesDaily for reading: No such file or directory
2016-02-27T04:18:19.045076+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.UncleanShutdownsDaily for reading: No such file or directory
2016-02-27T04:18:19.046291+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/weekly.cycle for reading: No such file or directory
2016-02-27T04:18:19.047006+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.AnyCrashesWeekly for reading: No such file or directory
2016-02-27T04:18:19.047728+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.UserCrashesWeekly for reading: No such file or directory
2016-02-27T04:18:19.048458+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.KernelCrashesWeekly for reading: No such file or directory
2016-02-27T04:18:19.049310+00:00 WARNING metrics_daemon[1739]: [WARNING:persistent_integer.cc(79)] cannot open /var/lib/metrics/Platform.UncleanShutdownsWeekly for reading: No such file or directory
2016-02-27T04:18:19.064559+00:00 INFO permission_broker[1103]:   AllowUsbDeviceRule: ALLOW
2016-02-27T04:18:19.064665+00:00 INFO permission_broker[1103]:   AllowTtyDeviceRule: IGNORE
2016-02-27T04:18:19.209610+00:00 INFO permission_broker[1103]:   DenyClaimedUsbDeviceRule: DENY
2016-02-27T04:18:19.209718+00:00 INFO permission_broker[1103]: Verdict for /dev/bus/usb/004/001: DENY
2016-02-27T04:18:19.209823+00:00 ERR permission_broker[1103]: OpenPath(...): Domain=permission_broker, Code=permission_denied, Message=Permission to open '/dev/bus/usb/004/001' denied
2016-02-27T04:18:19.210356+00:00 INFO permission_broker[1103]: ProcessPath(/dev/bus/usb/002/001)
2016-02-27T04:18:19.318964+00:00 INFO kernel: [  128.053449] exynos-dp 145b0000.display-port-controller: EDID data does not include any extensions.
2016-02-27T04:18:19.338958+00:00 ERR kernel: [  128.072729] exynos-dp 145b0000.display-port-controller: EDID Read success!
2016-02-27T04:18:19.338995+00:00 INFO kernel: [  128.075192] exynos-dp 145b0000.display-port-controller: Link Training Clock Recovery success
2016-02-27T04:18:19.343939+00:00 INFO kernel: [  128.076980] exynos-dp 145b0000.display-port-controller: Link Training success!
2016-02-27T04:18:19.400610+00:00 INFO permission_broker[1103]:   AllowUsbDeviceRule: ALLOW
2016-02-27T04:18:19.400716+00:00 INFO permission_broker[1103]:   AllowTtyDeviceRule: IGNORE
2016-02-27T04:18:19.473381+00:00 INFO crash_reporter[17184]: Stored minidump to /var/spool/crash/chrome.20160226.201819.17180.dmp
2016-02-27T04:18:19.476942+00:00 INFO crash_reporter[17184]: Leaving core file at /var/spool/crash/chrome.20160226.201819.17180.core due to developer image
2016-02-27T04:18:19.489864+00:00 INFO permission_broker[1103]:   DenyClaimedUsbDeviceRule: DENY
2016-02-27T04:18:19.490312+00:00 INFO permission_broker[1103]: Verdict for /dev/bus/usb/002/001: DENY
2016-02-27T04:18:19.490676+00:00 ERR permission_broker[1103]: OpenPath(...): Domain=permission_broker, Code=permission_denied, Message=Permission to open '/dev/bus/usb/002/001' denied
2016-02-27T04:18:19.624106+00:00 WARNING crash_reporter[17224]: Could not load the device policy file.
2016-02-27T04:18:19.624672+00:00 WARNING crash_reporter[17224]: Received crash notification for chrome[17089] sig 6, user 1000 (developer build - not testing - always dumping)
2016-02-27T04:18:19.629555+00:00 INFO crash_reporter[17224]: State of crashed process [17089]: S (sleeping)
2016-02-27T04:18:19.632595+00:00 INFO metrics_daemon[1739]: [INFO:metrics_daemon.cc(385)] Got org.chromium.CrashReporter.UserCrash D-Bus signal
2016-02-27T04:18:23.687634+00:00 INFO crash_reporter[17224]: Stored minidump to /var/spool/crash/chrome.20160226.201819.17089.dmp
2016-02-27T04:18:23.687683+00:00 INFO crash_reporter[17224]: Leaving core file at /var/spool/crash/chrome.20160226.201819.17089.core due to developer image
2016-02-27T04:18:23.764948+00:00 INFO session_manager[17041]: [INFO:child_exit_handler.cc(73)] 17092 is not a managed job.
2016-02-27T04:18:23.911054+00:00 INFO session_manager[17041]: [INFO:child_exit_handler.cc(77)] Handling 17089 exit.
2016-02-27T04:18:23.911169+00:00 ERR session_manager[17041]: [ERROR:child_exit_handler.cc(85)]   Exited with signal 6
2016-02-27T04:18:23.911223+00:00 INFO session_manager[17041]: [INFO:session_manager_service.cc(274)] Exiting process is chrome.
2016-02-27T04:18:23.911272+00:00 INFO session_manager[17041]: [INFO:browser_job.cc(111)] Terminating process group: Ensuring browser processes are gone.
2016-02-27T04:18:23.911321+00:00 INFO session_manager[17041]: [INFO:system_utils_impl.cc(49)] Sending 9 to -17089 as 1000
2016-02-27T04:18:24.563928+00:00 INFO session_manager[17041]: [INFO:browser_job.cc(135)] Cleaned up child 17089
2016-02-27T04:18:24.565628+00:00 INFO session_manager[17041]: [INFO:browser_job.cc(102)] Running child chrome...
2016-02-27T04:18:24.565666+00:00 INFO session_manager[17041]: [INFO:session_manager_service.cc(225)] Browser is 17254
2016-02-27T04:18:28.790466+00:00 WARNING crash_reporter[17341]: Could not load the device policy file.
2016-02-27T04:18:28.790695+00:00 WARNING crash_reporter[17341]: Received crash notification for chrome[17254] sig 11, user 1000 (developer build - not testing - always dumping)
2016-02-27T04:18:28.794324+00:00 INFO crash_reporter[17341]: State of crashed process [17254]: D (disk sleep)
2016-02-27T04:18:28.799690+00:00 INFO metrics_daemon[1739]: [INFO:metrics_daemon.cc(385)] Got org.chromium.CrashReporter.UserCrash D-Bus signal

Comment 2 by achuith@chromium.org, Aug 1 2016

Cc: steve...@chromium.org
Owner: achuith@chromium.org

Comment 3 by enne@chromium.org, Jan 25 2017

Components: -Internals>Graphics Internals>GPU
Status: Assigned (was: Untriaged)
Is this still an issue?

Comment 4 by achuith@chromium.org, Feb 8 2017

Status: WontFix (was: Assigned)
►

Sign in to add a comment