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

Issue 651325 link

Starred by 1 user

Issue metadata

Status: Untriaged
Owner: ----
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 3
Type: Bug



Sign in to add a comment

Storm of "Valid eCryptfs headers not found in file header region or xattr region" messages

Project Member Reported by djkurtz@chromium.org, Sep 29 2016

Issue description

Chrome Version: 54.0.2840.13
Chrome OS Version: 8743.11.0
Chrome OS Platform: elm

From: https://feedback.corp.google.com/#/Report/21300270540

Steps To Reproduce:
(1) Device crashed
(2) Reboot
(3) Inspect dmesg

Expected Result:

Actual Result:

dmesg starts with the "Valid eCryptfs headers" storm.
Note, that there were so many of these messages that the dmesg is truncated, so any earlier messages were lost.
...
[   22.698770] Valid eCryptfs headers not found in file header region or xattr region, inode 261921
[   22.699597] Valid eCryptfs headers not found in file header region or xattr region, inode 261839
[   22.700307] Valid eCryptfs headers not found in file header region or xattr region, inode 261917
[   22.703872] Valid eCryptfs headers not found in file header region or xattr region, inode 261813
[   22.704558] Valid eCryptfs headers not found in file header region or xattr region, inode 261849
[   22.705209] Valid eCryptfs headers not found in file header region or xattr region, inode 261863
[   22.705902] Valid eCryptfs headers not found in file header region or xattr region, inode 261909
[   22.706521] Valid eCryptfs headers not found in file header region or xattr region, inode 261809
[   22.707144] Valid eCryptfs headers not found in file header region or xattr region, inode 261897
[   22.711595] Valid eCryptfs headers not found in file header region or xattr region, inode 134287
[   22.712426] Valid eCryptfs headers not found in file header region or xattr region, inode 261859
[   22.713171] Valid eCryptfs headers not found in file header region or xattr region, inode 134295
[   22.723551] Valid eCryptfs headers not found in file header region or xattr region, inode 132729
[   22.727688] Valid eCryptfs headers not found in file header region or xattr region, inode 132735
[   22.729825] Valid eCryptfs headers not found in file header region or xattr region, inode 132737
[   22.732114] Valid eCryptfs headers not found in file header region or xattr region, inode 132736

How frequently does this problem reproduce? (Always, sometimes, hard to
reproduce?)

There have been similar reports over the years, usually associated with hard crashes / sudden shutdowns.
  issue 217829
   issue 258142 
  http://crosbug.com/p/53119
  http://crosbug.com/p/52831
  http://crosbug.com/p/57396


What is the impact to the user, and is there a workaround? If so, what is
it?

I'd like to confirm that this is normal behavior after a hard crash and completely recoverable.
 

Comment 1 by akhal...@gmail.com, Nov 18 2016

I'm seeing the same issue after a system crash:

2016-11-17T17:10:43.110080-08:00 ERR cryptohomed[914]: TPM error 0x21 (Decryption error): Error calling Tspi_Data_Unbind
2016-11-17T17:10:43.110304-08:00 ERR cryptohomed[914]: The TPM failed to unwrap the intermediate key with the supplied credentials
2016-11-17T17:10:43.379923-08:00 ERR cryptohomed[914]: TPM error 0x21 (Decryption error): Error calling Tspi_Data_Unbind
2016-11-17T17:10:43.380569-08:00 ERR cryptohomed[914]: The TPM failed to unwrap the intermediate key with the supplied credentials
2016-11-17T17:10:43.380612-08:00 ERR cryptohomed[914]: Failed to decrypt any keysets for 6c416bda53b8e9ff9c064567fb8e1e65373f96ba
2016-11-17T17:10:43.380793-08:00 WARNING cryptohomed[914]: PKCS#11 initialization requested but cryptohome is not mounted.
2016-11-17T17:10:44.211797-08:00 ERR bluetoothd[1550]: Unable to get :1.11/org/chromium/bluetooth_profile/704ee561_3782_405a_a14b_2d47a2ddcddf/704ee561-3782-405a-a14b-2d47a2ddcddf SDP record: Host is down
2016-11-17T17:10:48.459836-08:00 INFO session_manager[788]: [INFO:policy_key.cc(54)] No policy key on disk at /home/root/6c416bda53b8e9ff9c064567fb8e1e65373f96ba/session_manager/policy/key
2016-11-17T17:10:48.461702-08:00 ERR session_manager[788]: [ERROR:policy_store.cc(34)] Could not read policy off disk at /home/root/6c416bda53b8e9ff9c064567fb8e1e65373f96ba/session_manager/policy/policy: No such file or directory
2016-11-17T17:10:48.461737-08:00 WARNING session_manager[788]: [WARNING:user_policy_service_factory.cc(85)] Failed to load user policy data, continuing anyway.
2016-11-17T17:10:48.481767-08:00 INFO cryptohomed[914]: Putting a Pkcs11_Initialize on the mount thread.
2016-11-17T17:10:48.493269-08:00 INFO chapsd[906]: Opening database in: /home/root/6c416bda53b8e9ff9c064567fb8e1e65373f96ba/chaps
2016-11-17T17:10:48.544142-08:00 INFO chapsd[906]: Slot 1 ready for token at /home/root/6c416bda53b8e9ff9c064567fb8e1e65373f96ba/chaps
2016-11-17T17:10:48.544626-08:00 INFO cryptohomed[914]: A Pkcs11_Init event got finished.
2016-11-17T17:10:48.544635-08:00 INFO cryptohomed[914]: PKCS#11 initialization succeeded.
2016-11-17T17:10:48.565362-08:00 DEBUG kernel: [  103.881234] Valid eCryptfs headers not found in file header region or xattr region, inode 524944
2016-11-17T17:10:48.645795-08:00 INFO session_manager[788]: [INFO:session_manager_impl.cc(372)] Starting user session
2016-11-17T17:10:48.722379-08:00 DEBUG kernel: [  104.038639] Valid eCryptfs headers not found in file header region or xattr region, inode 535112
2016-11-17T17:10:48.742570-08:00 NOTICE dbus[454]: [system] Activating service name='org.chromium.EasyUnlock' (using servicehelper)
2016-11-17T17:10:48.948364-08:00 DEBUG kernel: [  104.263963] Valid eCryptfs headers not found in file header region or xattr region, inode 524940
2016-11-17T17:10:49.063561-08:00 DEBUG kernel: [  104.379965] Valid eCryptfs headers not found in file header region or xattr region, inode 533257
2016-11-17T17:10:49.142351-08:00 DEBUG kernel: [  104.458622] Valid eCryptfs headers not found in file header region or xattr region, inode 796801
2016-11-17T17:10:49.171871-08:00 INFO chapsd[906]: Waiting for private objects to be loaded.
2016-11-17T17:10:49.189014-08:00 DEBUG kernel: [  104.505425] Valid eCryptfs headers not found in file header region or xattr region, inode 524798
2016-11-17T17:10:49.249364-08:00 DEBUG kernel: [  104.565230] Valid eCryptfs headers not found in file header region or xattr region, inode 655675
2016-11-17T17:10:49.267763-08:00 INFO easy_unlock[27901]: Starting EasyUnlock dbus service.
2016-11-17T17:10:49.268540-08:00 NOTICE dbus[454]: [system] Successfully activated service 'org.chromium.EasyUnlock'
2016-11-17T17:10:49.336364-08:00 DEBUG kernel: [  104.651978] Valid eCryptfs headers not found in file header region or xattr region, inode 655503
2016-11-17T17:10:49.382361-08:00 DEBUG kernel: [  104.698328] Valid eCryptfs headers not found in file header region or xattr region, inode 524354
2016-11-17T17:10:49.421180-08:00 DEBUG kernel: [  104.737294] Valid eCryptfs headers not found in file header region or xattr region, inode 529006
2016-11-17T17:10:49.507561-08:00 INFO chapsd[906]: Done waiting for private objects.
2016-11-17T17:10:49.507577-08:00 INFO chapsd[906]: Master key is ready for token at /home/root/6c416bda53b8e9ff9c064567fb8e1e65373f96ba/chaps
2016-11-17T17:10:49.517095-08:00 DEBUG kernel: [  104.833071] Valid eCryptfs headers not found in file header region or xattr region, inode 655579
2016-11-17T17:10:49.562369-08:00 DEBUG kernel: [  104.878713] Valid eCryptfs headers not found in file header region or xattr region, inode 524939
2016-11-17T17:10:49.593368-08:00 DEBUG kernel: [  104.909187] Valid eCryptfs headers not found in file header region or xattr region, inode 524802
2016-11-17T17:10:49.632389-08:00 DEBUG kernel: [  104.947869] Valid eCryptfs headers not found in file header region or xattr region, inode 525369
2016-11-17T17:10:49.673380-08:00 DEBUG kernel: [  104.989544] Valid eCryptfs headers not found in file header region or xattr region, inode 525372
2016-11-17T17:10:49.688372-08:00 DEBUG kernel: [  105.004295] Valid eCryptfs headers not found in file header region or xattr region, inode 796865
2016-11-17T17:10:49.710363-08:00 DEBUG kernel: [  105.026517] Valid eCryptfs headers not found in file header region or xattr region, inode 524744
2016-11-17T17:10:49.789445-08:00 INFO cryptohomed[914]: Label already exists.
2016-11-17T17:10:49.893500-08:00 INFO session_manager[788]: [INFO:policy_service.cc(196)] Persisted policy to disk.
2016-11-17T17:10:49.929045-08:00 INFO session_manager[788]: [INFO:child_exit_handler.cc(77)] Handling 27967 exit.
2016-11-17T17:10:50.096372-08:00 DEBUG kernel: [  105.412424] Valid eCryptfs headers not found in file header region or xattr region, inode 524694
2016-11-17T17:10:50.129539-08:00 WARNING cryptohomed[914]: RemoveKeyset: key to remove not found
2016-11-17T17:10:50.623620-08:00 INFO session_manager[788]: [INFO:policy_service.cc(196)] Persisted policy to disk.
2016-11-17T17:10:50.661368-08:00 DEBUG kernel: [  105.976903] Valid eCryptfs headers not found in file header region or xattr region, inode 529006
2016-11-17T17:10:50.700638-08:00 INFO session_manager[788]: [INFO:child_exit_handler.cc(77)] Handling 28081 exit.
2016-11-17T17:10:51.001364-08:00 DEBUG kernel: [  106.317373] Valid eCryptfs headers not found in file header region or xattr region, inode 524347
2016-11-17T17:10:51.641380-08:00 ERR shill[956]: [ERROR:rtnl_handler.cc(339)] Not implemented reached in void shill::RTNLHandler::ParseRTNL(shill::InputData*)
2016-11-17T17:10:51.657368-08:00 DEBUG kernel: [  106.972849] Valid eCryptfs headers not found in file header region or xattr region, inode 787202
2016-11-17T17:10:51.779034-08:00 ERR shill[956]: [ERROR:rtnl_handler.cc(339)] Not implemented reached in void shill::RTNLHandler::ParseRTNL(shill::InputData*)
2016-11-17T17:10:51.823364-08:00 DEBUG kernel: [  107.139636] Valid eCryptfs headers not found in file header region or xattr region, inode 655541
2016-11-17T17:10:52.347401-08:00 ERR bluetoothd[1550]: Unable to get :1.11/org/chromium/bluetooth_profile/704ee561_3782_405a_a14b_2d47a2ddcddf/704ee561-3782-405a-a14b-2d47a2ddcddf SDP record: Host is down
2016-11-17T17:10:58.995391-08:00 DEBUG kernel: [  114.310978] Valid eCryptfs headers not found in file header region or xattr region, inode 528999
Gwendal?  Yes, I think we've seen the issue for a long time.  I believe we should recover correctly in userspace -- is there any other issue besides the strange error messages?
Project Member

Comment 3 by sheriffbot@chromium.org, Nov 20 2017

Labels: Hotlist-Recharge-Cold
Status: Untriaged (was: Available)
This issue has been Available for over a year. If it's no longer important or seems unlikely to be fixed, please consider closing it out. If it is important, please re-triage the issue.

Sorry for the inconvenience if the bug really should have been left as Available. If you change it back, also remove the "Hotlist-Recharge-Cold" label.

For more details visit https://www.chromium.org/issue-tracking/autotriage - Your friendly Sheriffbot
Components: Internals>Logging
Is this still an issue? Please close if this is no longer valid.

Sign in to add a comment