New issue
Advanced search Search tips

Issue 890110 link

Starred by 1 user

Issue metadata

Status: Duplicate
Merged: issue 875263
Owner: ----
Closed: Oct 5
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

Chrome login failures during testing on rikku-release

Project Member Reported by derat@chromium.org, Sep 28

Issue description

There were more login failures in the rikku-release run at http://stainless/browse/chromeos-autotest-results/242215280-chromeos-test/ .

Here's the end of the Chrome log from the first one:

[24255:24255:0926/161754.981981:VERBOSE1:login_display_host_webui.cc(760)] Login WebUI >> wp animation done
[24255:24255:0926/161754.982024:VERBOSE1:login_display_host_webui.cc(937)] Login WebUI >> Init postponed WebUI
[24255:24255:0926/161754.982049:VERBOSE1:login_display_host_webui.cc(564)] Login WebUI >> wizard
[24255:24255:0926/161754.986594:VERBOSE1:login_display_host_webui.cc(1026)] Login WebUI >> show login wnd on create
[24255:24255:0926/161755.015443:VERBOSE1:wizard_controller.cc(352)] Starting OOBE wizard with screen: connect
[24255:24255:0926/161755.019286:VERBOSE1:wizard_controller.cc(551)] Showing welcome screen.
[24255:24255:0926/161755.019384:VERBOSE1:wizard_controller.cc(1368)] SetCurrentScreenSmooth: connect
[24255:24320:0926/161755.106269:INFO:nss_cert_database_chromeos.cc(122)] UserCertLogging: Invoked with log_reason=SystemTokenInitiallyLoaded, system_slot_present=1
[24255:24313:0926/161755.106346:INFO:nss_cert_database_chromeos.cc(122)] UserCertLogging: Invoked with log_reason=ListCerts, system_slot_present=1
[24255:24255:0926/161755.280689:WARNING:CONSOLE(0)] "HTML Imports is deprecated and will be removed in M73, around March 2019. Please use ES modules instead. See https://www.chromestatus.com/features/5144752345317376 for more details.", source:  (0)
[24255:24255:0926/161755.491890:WARNING:CONSOLE(442)] "document.registerElement is deprecated and will be removed in M73, around March 2019. Please use window.customElements.define instead. See https://www.chromestatus.com/features/4642138092470272 for more details.", source: chrome://resources/polymer/v1_0/polymer/polymer-micro-extracted.js (442)
[24255:24255:0926/161756.189621:WARNING:CONSOLE(2083)] "Element.createShadowRoot is deprecated and will be removed in M73, around March 2019. Please use Element.attachShadow instead. See https://www.chromestatus.com/features/4507242028072960 for more details.", source: chrome://resources/polymer/v1_0/polymer/polymer-mini-extracted.js (2083)
[24255:24255:0926/161756.230275:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[24255:24255:0926/161757.836705:VERBOSE1:wizard_controller.cc(844)] SkipToLoginForTesting.
[24255:24255:0926/161757.839671:VERBOSE1:auto_enrollment_controller.cc(539)] Auto-enrollment disabled: command line (gaia).
[24255:24255:0926/161757.839716:VERBOSE1:auto_enrollment_controller.cc(732)] New auto-enrollment state: 5
[24255:24255:0926/161757.840059:VERBOSE1:wizard_controller.cc(2092)] Showing enrollment screen. Forcing interactive enrollment: 0.
[24255:24255:0926/161757.840113:VERBOSE1:wizard_controller.cc(1368)] SetCurrentScreenSmooth: oauth-enrollment
[24255:24255:0926/161757.843677:VERBOSE1:gaia_screen_handler.cc(780)] HandleCompleteLogin
[24255:24255:0926/161757.872729:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[24255:24255:0926/161757.884410:ERROR:CONSOLE(10506)] "TypeError: Cannot read property 'showStep' of null
    at Object.api.(anonymous function) [as showStep] (chrome://oobe/oobe.js:416:28)
    at <anonymous>:1:29", source: chrome://oobe/oobe.js (10506)
[24255:24255:0926/161757.884480:ERROR:CONSOLE(416)] "Uncaught TypeError: Cannot read property 'showStep' of null", source: chrome://oobe/oobe.js (416)
[24255:24305:0926/161757.936377:WARNING:spdy_session.cc(3156)] Received HEADERS for invalid stream 1
[24255:24255:0926/161757.939339:VERBOSE1:signin_screen_handler.cc(1426)] Login WebUI >> loginVisible, src: oobe, webui_visible_: 0
[24255:24255:0926/161757.946804:VERBOSE1:webui_login_view.cc(608)] Login WebUI >> login-prompt-visible
[24255:24255:0926/161758.051137:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[24255:24255:0926/161758.229461:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[24255:24255:0926/161758.317966:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[24255:24255:0926/161801.617225:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[24255:24255:0926/161801.651822:WARNING:CONSOLE(17471)] "Unrecognized message from GAIA: showConfirmCancel", source: chrome://oobe/oobe.js (17471)
[24255:24255:0926/161818.022026:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[24255:24255:0926/161843.598833:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[24255:24255:0926/161905.782195:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[24255:24421:0926/161950.224923:WARNING:audio_sync_reader.cc(187)] ASR: No room in socket buffer.: Broken pipe (32)
[24255:24255:0926/161950.348991:VERBOSE1:component_updater_service.cc(107)] CrxUpdateService stopping
[24255:24255:0926/161950.349266:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.
[24255:24255:0926/161950.349290:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.

And here's the second:

[25060:25060:0926/161955.186147:VERBOSE1:login_display_host_webui.cc(760)] Login WebUI >> wp animation done
[25060:25060:0926/161955.186194:VERBOSE1:login_display_host_webui.cc(937)] Login WebUI >> Init postponed WebUI
[25060:25060:0926/161955.rikku-release186289:VERBOSE1:login_display_host_webui.cc(564)] Login WebUI >> wizard
[25060:25060:0926/161955.194455:VERBOSE1:login_display_host_webui.cc(1026)] Login WebUI >> show login wnd on create
[25060:25060:0926/161955.232941:VERBOSE1:wizard_controller.cc(352)] Starting OOBE wizard with screen: connect
[25060:25060:0926/161955.234938:VERBOSE1:wizard_controller.cc(551)] Showing welcome screen.
[25060:25060:0926/161955.235035:VERBOSE1:wizard_controller.cc(1368)] SetCurrentScreenSmooth: connect
[25060:25060:0926/161955.426693:WARNING:CONSOLE(0)] "HTML Imports is deprecated and will be removed in M73, around March 2019. Please use ES modules instead. See https://www.chromestatus.com/features/5144752345317376 for more details.", source:  (0)
[25060:25116:0926/161955.532841:INFO:nss_cert_database_chromeos.cc(122)] UserCertLogging: Invoked with log_reason=SystemTokenInitiallyLoaded, system_slot_present=1
[25060:25121:0926/161955.532969:INFO:nss_cert_database_chromeos.cc(122)] UserCertLogging: Invoked with log_reason=ListCerts, system_slot_present=1
[25060:25060:0926/161955.651153:WARNING:CONSOLE(442)] "document.registerElement is deprecated and will be removed in M73, around March 2019. Please use window.customElements.define instead. See https://www.chromestatus.com/features/4642138092470272 for more details.", source: chrome://resources/polymer/v1_0/polymer/polymer-micro-extracted.js (442)
[25060:25060:0926/161956.467074:WARNING:CONSOLE(2083)] "Element.createShadowRoot is deprecated and will be removed in M73, around March 2019. Please use Element.attachShadow instead. See https://www.chromestatus.com/features/4507242028072960 for more details.", source: chrome://resources/polymer/v1_0/polymer/polymer-mini-extracted.js (2083)
[25060:25060:0926/161956.508618:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[25060:25060:0926/161958.263723:VERBOSE1:wizard_controller.cc(844)] SkipToLoginForTesting.
[25060:25060:0926/161958.267991:VERBOSE1:auto_enrollment_controller.cc(539)] Auto-enrollment disabled: command line (gaia).
[25060:25060:0926/161958.268037:VERBOSE1:auto_enrollment_controller.cc(732)] New auto-enrollment state: 5
[25060:25060:0926/161958.268282:VERBOSE1:wizard_controller.cc(2092)] Showing enrollment screen. Forcing interactive enrollment: 0.
[25060:25060:0926/161958.268336:VERBOSE1:wizard_controller.cc(1368)] SetCurrentScreenSmooth: oauth-enrollment
[25060:25060:0926/161958.270331:VERBOSE1:gaia_screen_handler.cc(780)] HandleCompleteLogin
[25060:25060:0926/161958.301874:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[25060:25060:0926/161958.311658:VERBOSE1:signin_screen_handler.cc(1426)] Login WebUI >> loginVisible, src: oobe, webui_visible_: 0
[25060:25060:0926/161958.312512:VERBOSE1:webui_login_view.cc(608)] Login WebUI >> login-prompt-visible
[25060:25060:0926/161958.329515:ERROR:CONSOLE(10506)] "TypeError: Cannot read property 'showStep' of null
    at Object.api.(anonymous function) [as showStep] (chrome://oobe/oobe.js:416:28)
    at <anonymous>:1:29", source: chrome://oobe/oobe.js (10506)
[25060:25060:0926/161958.329590:ERROR:CONSOLE(416)] "Uncaught TypeError: Cannot read property 'showStep' of null", source: chrome://oobe/oobe.js (416)
[25060:25060:0926/161958.437566:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[25060:25060:0926/161958.776643:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[25060:25060:0926/162002.104762:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[25060:25060:0926/162002.130337:WARNING:CONSOLE(17471)] "Unrecognized message from GAIA: showConfirmCancel", source: chrome://oobe/oobe.js (17471)
[25060:25060:0926/162017.653964:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[25060:25060:0926/162047.254831:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[25060:25060:0926/162113.354751:WARNING:enrollment_screen_handler.cc(670)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update

The "<webview>: The load has aborted with error -3: ERR_ABORTED." warning also appears in issue 888520. Does it indicate a problem?

There are also warnings in /var/log/messages but I don't know if they matter:

2018-09-26T16:17:54.869068-07:00 WARNING cryptohomed[702]: No valid keysets on disk for 3ec6b4ac05703288f138c27c8aa758842fde9162
...
2018-09-26T16:17:58.003549-07:00 INFO session_manager[24240]: [INFO:server_backed_state_key_generator.cc(126)] Stable device secret missing!
...
2018-09-26T16:19:51.571242-07:00 WARNING session_manager[24768]: [WARNING:libpolicy.cc(37)] Could not load the device policy file.
...
2018-09-26T16:19:52.437891-07:00 WARNING cryptohomed[702]: No valid keysets on disk for 3ec6b4ac05703288f138c27c8aa758842fde9162

I'm going to start a doc that lists all of the errors and warnings that I see during login so I can figure out which ones matter and which ones don't, because these logs are currently useless to me. :-(
 
Cc: r...@chromium.org
Also CC rkc on all of these bugs.
Login tests are still failing on the same DUT, chromeos4-row3-rack5-host4. See http://stainless/browse/chromeos-autotest-results/244105947-chromeos-test/ .

Here's the first failure:

2018/10/01 14:45:10 Started test ui.ChromeCrashLoggedIn
2018/10/01 14:45:10 [14:45:10.020] Restarting ui job
2018/10/01 14:45:12 [14:45:11.106] Waiting for org.chromium.SessionManager D-Bus service
2018/10/01 14:45:12 [14:45:11.123] Asking session_manager to enable Chrome testing
2018/10/01 14:45:12 [14:45:11.125] Waiting for Chrome to write its debugging port to /home/chronos/DevToolsActivePort
2018/10/01 14:45:12 [14:45:11.982] Checking cryptohomed service
2018/10/01 14:45:12 [14:45:11.982] Removing cryptohome for testuser@gmail.com
2018/10/01 14:45:13 [14:45:12.024] Finding OOBE DevTools target
2018/10/01 14:45:13 [14:45:12.116] Connecting to Chrome at ws://127.0.0.1:54012/devtools/page/B9FD7D6D4514881E143AF566907B5D6D
2018/10/01 14:45:13 [14:45:12.241] Waiting for OOBE
2018/10/01 14:45:15 [14:45:14.787] Logging in as user "testuser@gmail.com"
2018/10/01 14:45:15 [14:45:14.895] Waiting for cryptohome /home/user/b481409428df59ccad41843fb602b28a58e48c17
2018/10/01 14:47:08 [14:47:07.052] cryptohome status:
{
   "installattrs": {
      "first_install": false,
      "initialized": false,
      "invalid": false,
      "lockbox_index": 536870916,
      "lockbox_nvram_version": 2,
      "secure": false,
      "size": 0,
      "version": 1
   },
   "mounts": [  ],
   "tpm": {
      "being_owned": false,
      "can_connect": true,
      "can_decrypt": false,
      "can_encrypt": false,
      "can_load_srk": false,
      "can_load_srk_pubkey": false,
      "enabled": true,
      "has_context": true,
      "has_cryptohome_key": false,
      "has_key_handle": false,
      "last_error": 8224,
      "owned": false,
      "srk_vulnerable_roca": false
   }
}
2018/10/01 14:47:08 [14:47:07.083] /sys/class/misc/tpm0/device/owned contains "0"
2018/10/01 14:47:08 [14:47:07.083] Error at chrome_crash_logged_in.go:26: Chrome login failed: /home/user/b481409428df59ccad41843fb602b28a58e48c17 not mounted: context deadline exceeded (last error: doesn't exist)

And the second:

2018/10/01 14:47:10 Started test ui.ChromeLogin
2018/10/01 14:47:10 [14:47:09.369] Waiting for org.chromium.SessionManager D-Bus service
2018/10/01 14:47:10 [14:47:09.370] Restarting ui job
2018/10/01 14:47:11 [14:47:10.478] Waiting for org.chromium.SessionManager D-Bus service
2018/10/01 14:47:11 [14:47:10.496] Asking session_manager to enable Chrome testing
2018/10/01 14:47:11 [14:47:10.499] Waiting for Chrome to write its debugging port to /home/chronos/DevToolsActivePort
2018/10/01 14:47:12 [14:47:11.386] Checking cryptohomed service
2018/10/01 14:47:12 [14:47:11.386] Removing cryptohome for testuser@gmail.com
2018/10/01 14:47:12 [14:47:11.426] Finding OOBE DevTools target
2018/10/01 14:47:12 [14:47:11.518] Connecting to Chrome at ws://127.0.0.1:46271/devtools/page/BD9472A7064FC19843FA86C4650C05B6
2018/10/01 14:47:12 [14:47:11.652] Waiting for OOBE
2018/10/01 14:47:15 [14:47:14.271] Logging in as user "testuser@gmail.com"
2018/10/01 14:47:15 [14:47:14.491] Waiting for cryptohome /home/user/b481409428df59ccad41843fb602b28a58e48c17
2018/10/01 14:49:07 [14:49:06.558] cryptohome status:
{
   "installattrs": {
      "first_install": true,
      "initialized": true,
      "invalid": false,
      "lockbox_index": 536870916,
      "lockbox_nvram_version": 2,
      "secure": true,
      "size": 0,
      "version": 1
   },
   "mounts": [  ],
   "tpm": {
      "being_owned": false,
      "can_connect": true,
      "can_decrypt": false,
      "can_encrypt": false,
      "can_load_srk": true,
      "can_load_srk_pubkey": true,
      "enabled": true,
      "has_context": true,
      "has_cryptohome_key": false,
      "has_key_handle": false,
      "last_error": 0,
      "owned": true,
      "srk_vulnerable_roca": true
   }
}
2018/10/01 14:49:07 [14:49:06.589] /sys/class/misc/tpm0/device/owned contains "1"
2018/10/01 14:49:07 [14:49:06.589] Error at chrome_login.go:68: Chrome login failed: /home/user/b481409428df59ccad41843fb602b28a58e48c17 not mounted: context deadline exceeded (last error: doesn't exist)

Here's syslog from the first failure:

2018-10-01T21:45:11.119049+00:00 INFO session_manager[9969]: [INFO:session_manager_service.cc(152)] SessionManagerService starting
...
2018-10-01T21:45:12.023168+00:00 WARNING cryptohomed[833]: No valid keysets on disk for b481409428df59ccad41843fb602b28a58e48c17
...
2018-10-01T21:45:14.884528+00:00 ERR cryptohomed[833]: TpmIsReady: is not owned.
2018-10-01T21:45:14.884550+00:00 ERR cryptohomed[833]: HasAuthorization: TPM not ready.
2018-10-01T21:45:14.884561+00:00 ERR cryptohomed[833]: Destroy() called with insufficient authorization.
2018-10-01T21:45:15.071638+00:00 INFO session_manager[9969]: [INFO:server_backed_state_key_generator.cc(126)] Stable device secret missing!
2018-10-01T21:47:07.084185+00:00 INFO kernel: [ 1286.692075] tpm_tis tpm_tis: command 0x65 (size 22) returned code 0x0
2018-10-01T21:47:07.140060+00:00 INFO session_manager[9969]: [INFO:browser_job.cc(179)] Terminating browser process 9998 with signal 15: exiting cleanly

and the second:

2018-10-01T21:47:10.491281+00:00 INFO session_manager[10921]: [INFO:session_manager_service.cc(152)] SessionManagerService starting
...
2018-10-01T21:47:11.421399+00:00 WARNING cryptohomed[833]: No valid keysets on disk for b481409428df59ccad41843fb602b28a58e48c17
2018-10-01T21:47:11.454397+00:00 INFO bluetoothd[1683]: adapter /org/bluez/hci0 set power to 1
2018-10-01T21:47:11.474980+00:00 INFO kernel: [ 1291.082083] tpm_tis tpm_tis: command 0x7c (size 30) returned code 0x0
2018-10-01T21:47:11.524609+00:00 INFO kernel: [ 1291.131052] tpm_tis tpm_tis: command 0x7c (size 30) returned code 0x0
2018-10-01T21:47:11.573864+00:00 INFO kernel: [ 1291.180043] tpm_tis tpm_tis: command 0x7c (size 30) returned code 0x0
2018-10-01T21:47:11.598254+00:00 WARNING tcsd[790]: TSS: Unloading a public key of size 0!
2018-10-01T21:47:11.598977+00:00 INFO kernel: [ 1291.205417] tpm_tis tpm_tis: command 0xa (size 10) returned code 0x0
2018-10-01T21:47:12.646978+00:00 INFO kernel: [ 1292.253756] tpm_tis tpm_tis: command 0xd (size 624) returned code 0x0
[many similar tpm_tis messages]
2018-10-01T21:47:14.023983+00:00 INFO kernel: [ 1293.629571] tpm_tis tpm_tis: command 0x10 (size 79) returned code 0x0
2018-10-01T21:47:14.053984+00:00 INFO kernel: [ 1293.659471] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x22
2018-10-01T21:47:14.055877+00:00 ERR cryptohomed[833]: Taking TPM ownership took 2638ms
2018-10-01T21:47:14.056072+00:00 INFO cryptohomed[833]: Configuring TPM, ownership taken: 1.
2018-10-01T21:47:14.090978+00:00 INFO kernel: [ 1293.696776] tpm_tis tpm_tis: command 0x65 (size 18) returned code 0x0
[many similar tpm_tis messages]
2018-10-01T21:47:14.558345+00:00 INFO kernel: [ 1294.163386] tpm_tis tpm_tis: command 0xb (size 36) returned code 0x0
2018-10-01T21:47:14.563697+00:00 INFO session_manager[10921]: [INFO:server_backed_state_key_generator.cc(126)] Stable device secret missing!
2018-10-01T21:47:14.720982+00:00 INFO kernel: [ 1294.326330] tpm_tis tpm_tis: command 0xcc (size 146) returned code 0x0
2018-10-01T21:47:14.756251+00:00 INFO cryptohomed[833]: Lockbox created.
2018-10-01T21:47:14.756980+00:00 INFO kernel: [ 1294.362224] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x22
2018-10-01T21:47:14.763352+00:00 INFO cryptohomed[833]: Attestation: Preparing for enrollment...
2018-10-01T21:47:14.788987+00:00 INFO kernel: [ 1294.394263] tpm_tis tpm_tis: command 0xa (size 10) returned code 0x0
2018-10-01T21:47:14.886984+00:00 INFO kernel: [ 1294.492901] tpm_tis tpm_tis: command 0x81 (size 59) returned code 0x0
2018-10-01T21:47:14.929984+00:00 INFO kernel: [ 1294.535135] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x22
2018-10-01T21:47:14.960993+00:00 INFO kernel: [ 1294.566207] tpm_tis tpm_tis: command 0xa (size 10) returned code 0x0
2018-10-01T21:47:15.059979+00:00 INFO kernel: [ 1294.665841] tpm_tis tpm_tis: command 0x81 (size 59) returned code 0x0
2018-10-01T21:47:15.096461+00:00 INFO kernel: [ 1294.701414] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x22
2018-10-01T21:47:15.096393+00:00 INFO cryptohomed[833]: Attestation: Creating identity 0 with identity feature IDENTITY_FEATURE_ENTERPRISE_ENROLLMENT_ID.
2018-10-01T21:47:15.368225+00:00 INFO kernel: [ 1294.972154] tpm_tis tpm_tis: command 0xb (size 36) returned code 0x0
[many similar tpm_tis messages]
2018-10-01T21:47:20.765067+00:00 INFO kernel: [ 1300.368829] tpm_tis tpm_tis: command 0x16 (size 38) returned code 0x0
2018-10-01T21:47:20.795532+00:00 INFO cryptohomed[833]: Attestation: Encrypting endorsement credential for the default PCA.
2018-10-01T21:47:20.795994+00:00 INFO kernel: [ 1300.399049] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x0
2018-10-01T21:47:20.825987+00:00 INFO kernel: [ 1300.429094] tpm_tis tpm_tis: command 0x46 (size 14) returned code 0x0
2018-10-01T21:47:20.849907+00:00 INFO cryptohomed[833]: Attestation: Encrypting endorsement credential for the test PCA.
2018-10-01T21:47:20.850097+00:00 INFO kernel: [ 1300.453137] tpm_tis tpm_tis: command 0x46 (size 14) returned code 0x0
[many similar tpm_tis messages]
2018-10-01T21:47:22.093004+00:00 INFO kernel: [ 1301.695738] tpm_tis tpm_tis: command 0x46 (size 14) returned code 0x0
2018-10-01T21:47:22.110149+00:00 INFO cryptohomed[833]: Attestation: Prepared successfully (7346 ms).
2018-10-01T21:47:22.142019+00:00 INFO kernel: [ 1301.744695] tpm_tis tpm_tis: command 0x65 (size 18) returned code 0x0
2018-10-01T21:48:31.283990+00:00 WARNING kernel: [ 1370.861803] perf samples too long (2521 > 2500), lowering kernel.perf_event_max_sample_rate to 50000
2018-10-01T21:49:06.434999+00:00 INFO kernel: [ 1405.999931] tpm_tis tpm_tis: command 0xa (size 10) returned code 0x0
2018-10-01T21:49:06.526025+00:00 INFO kernel: [ 1406.091636] tpm_tis tpm_tis: command 0x21 (size 59) returned code 0x0
2018-10-01T21:49:06.556994+00:00 INFO kernel: [ 1406.121891] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x22
2018-10-01T21:49:06.590166+00:00 INFO kernel: [ 1406.154858] tpm_tis tpm_tis: command 0x65 (size 22) returned code 0x0
2018-10-01T21:49:06.642443+00:00 WARNING kernel: [ 1406.206993] init: ui main process (10921) killed by KILL signal

End of the first Chrome log:

[9998:9998:1001/144514.877065:VERBOSE1:wizard_controller.cc(866)] SkipToLoginForTesting.
[9998:9998:1001/144514.879677:VERBOSE1:auto_enrollment_controller.cc(539)] Auto-enrollment disabled: command line (gaia).
[9998:9998:1001/144514.880232:VERBOSE1:auto_enrollment_controller.cc(732)] New auto-enrollment state: 5
[9998:9998:1001/144514.880466:VERBOSE1:wizard_controller.cc(2123)] Showing enrollment screen. Forcing interactive enrollment: 0.
[9998:9998:1001/144514.880523:VERBOSE1:wizard_controller.cc(1394)] SetCurrentScreenSmooth: oauth-enrollment
[9998:9998:1001/144514.880777:VERBOSE1:gaia_screen_handler.cc(779)] HandleCompleteLogin
[9998:9998:1001/144514.899905:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[9998:9998:1001/144515.023203:ERROR:CONSOLE(10506)] "TypeError: Cannot read property 'showStep' of null
    at Object.api.(anonymous function) [as showStep] (chrome://oobe/oobe.js:416:28)
    at <anonymous>:1:29", source: chrome://oobe/oobe.js (10506)
[9998:9998:1001/144515.023310:ERROR:CONSOLE(416)] "Uncaught TypeError: Cannot read property 'showStep' of null", source: chrome://oobe/oobe.js (416)
[9998:9998:1001/144515.023368:VERBOSE1:signin_screen_handler.cc(1426)] Login WebUI >> loginVisible, src: oobe, webui_visible_: 0
[9998:9998:1001/144515.023651:VERBOSE1:webui_login_view.cc(608)] Login WebUI >> login-prompt-visible
[9998:9998:1001/144515.174096:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[9998:9998:1001/144515.343921:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[9998:9998:1001/144515.500046:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[9998:9998:1001/144517.490895:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[9998:9998:1001/144518.174808:WARNING:CONSOLE(17500)] "Unrecognized message from GAIA: showConfirmCancel", source: chrome://oobe/oobe.js (17500)
[9998:9998:1001/144544.175787:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[9998:9998:1001/144608.027875:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[9998:9998:1001/144634.693013:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[9998:9998:1001/144707.286410:VERBOSE1:component_updater_service.cc(107)] CrxUpdateService stopping
[9998:9998:1001/144707.289889:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.
[9998:9998:1001/144707.289934:WARNING:pref_notifier_impl.cc(23)] Pref observer found at shutdown.

and the second:

[10937:10937:1001/144714.471422:VERBOSE1:wizard_controller.cc(866)] SkipToLoginForTesting.
[10937:10937:1001/144714.474652:VERBOSE1:auto_enrollment_controller.cc(539)] Auto-enrollment disabled: command line (gaia).
[10937:10937:1001/144714.474694:VERBOSE1:auto_enrollment_controller.cc(732)] New auto-enrollment state: 5
[10937:10937:1001/144714.475258:VERBOSE1:wizard_controller.cc(2123)] Showing enrollment screen. Forcing interactive enrollment: 0.
[10937:10937:1001/144714.475319:VERBOSE1:wizard_controller.cc(1394)] SetCurrentScreenSmooth: oauth-enrollment
[10937:10937:1001/144714.478367:VERBOSE1:gaia_screen_handler.cc(779)] HandleCompleteLogin
[10937:10937:1001/144714.519317:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[10937:10937:1001/144714.523114:VERBOSE1:signin_screen_handler.cc(1426)] Login WebUI >> loginVisible, src: oobe, webui_visible_: 0
[10937:10937:1001/144714.523838:VERBOSE1:webui_login_view.cc(608)] Login WebUI >> login-prompt-visible
[10937:10937:1001/144714.539822:ERROR:CONSOLE(10506)] "TypeError: Cannot read property 'showStep' of null
    at Object.api.(anonymous function) [as showStep] (chrome://oobe/oobe.js:416:28)
    at <anonymous>:1:29", source: chrome://oobe/oobe.js (10506)
[10937:10937:1001/144714.539881:ERROR:CONSOLE(416)] "Uncaught TypeError: Cannot read property 'showStep' of null", source: chrome://oobe/oobe.js (416)
[10937:10937:1001/144714.649637:WARNING:CONSOLE(2651)] "Could not find style data in module named", source: chrome://resources/polymer/v1_0/polymer/polymer-extracted.js (2651)
[10937:10937:1001/144714.923980:WARNING:CONSOLE(288)] "<webview>: The load has aborted with error -3: ERR_ABORTED.", source: extensions::webViewEvents (288)
[10937:10937:1001/144714.997990:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[10937:10937:1001/144716.908042:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[10937:10937:1001/144717.671186:WARNING:CONSOLE(17500)] "Unrecognized message from GAIA: showConfirmCancel", source: chrome://oobe/oobe.js (17500)
[10937:10937:1001/144738.071862:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[10937:10937:1001/144806.997370:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
[10937:10937:1001/144828.298054:WARNING:enrollment_screen_handler.cc(674)] EnrollmentScreenHandler::UpdateState(): state=online, reason=update
Mergedinto: 875263
Status: Duplicate (was: Untriaged)
I think that this is probably a duplicate of  issue 875263 .

Sign in to add a comment