Chrome OS Platform:Panther

Steps To Reproduce:
Perform AU from 5500.100.9(M34) => 8172.60.0(51)


Expected Result:
Device should be update to 8172.60.0(51) 

Actual Result:
Postinstal failed.

How frequently does this problem reproduce? (Always, sometimes, hard to
reproduce?)
100%
What is the impact to the user, and is there a workaround? If so, what is
it?

Please provide any additional information below. Attach a screen shot or
log if possible.


[0706/164258:INFO:chrome_browser_proxy_resolver.cc(168)] dbus_g_proxy_call succeeded!
[0706/164258:INFO:libcurl_http_fetcher.cc(87)] Starting/Resuming transfer
[0706/164258:INFO:libcurl_http_fetcher.cc(98)] Using proxy: no
[0706/164258:INFO:libcurl_http_fetcher.cc(51)] We are connected via wifi, Updates allowed: Yes
[0706/164258:INFO:libcurl_http_fetcher.cc(228)] Setting up curl options for HTTPS
[0706/164259:INFO:libcurl_http_fetcher.cc(508)] Setting up timeout source: 1 seconds.
[0706/164259:INFO:libcurl_http_fetcher.cc(309)] HTTP response code: 200
[0706/164259:INFO:libcurl_http_fetcher.cc(378)] Transfer completed (200), 233 bytes downloaded
[0706/164259:INFO:omaha_request_action.cc(823)] Omaha request response: <?xml version="1.0" encoding="UTF-8"?><response protocol="3.0" server="prod"><daystart elapsed_days="3474" elapsed_seconds="60179"/><app appid="{C0E4276B-35C7-023D-BB4A-42D642B91E65}" status="ok"><event status="ok"/></app></response>
[0706/164259:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting FilesystemCopierAction
[0706/164259:INFO:filesystem_copier_action.cc(340)] Filesystem size: 1283457024
[0706/164311:INFO:filesystem_copier_action.cc(308)] Hash: XX7TCDZtBReWGFvur92o9toIBpBphxOJXcw3wENbLX4=
[0706/164311:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting FilesystemCopierAction
[0706/164311:INFO:hardware.cc(73)] MarkPartitionUnbootable: /dev/sda4
[0706/164311:INFO:filesystem_copier_action.cc(340)] Filesystem size: 16777216
[0706/164311:INFO:filesystem_copier_action.cc(308)] Hash: 25vyj2c2XEs0X4CgshZw/zJhRncW1rfTSng+u6xzAy0=
[0706/164311:INFO:action_processor.cc(82)] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting PostinstallRunnerAction
[0706/164325:INFO:subprocess.cc(43)] Subprocess status: 1
[0706/164325:INFO:subprocess.cc(46)] Subprocess output:
dm:dm bht[DEBUG] Setting block_count 313344
dm:dm bht[DEBUG] Setting depth to 3.
dm:dm bht[DEBUG] depth: 0 entries: 1
dm:dm bht[DEBUG] depth: 1 entries: 20
dm:dm bht[DEBUG] depth: 2 entries: 2448
cros_installer: Cannot tell where /sys/dev/char/8:5/subsystem links to: No such file or directory
PostInstall Configured: (B, /dev/sda5, /dev/sda4, /dev/sda12)

FROM (rootfs):
CHROMEOS_AUSERVER=https://tools.google.com/service/update2
CHROMEOS_BOARD_APPID={C0E4276B-35C7-023D-BB4A-42D642B91E65}
CHROMEOS_CANARY_APPID={90F229CE-83E2-4FAF-8479-E368A34938B1}
CHROMEOS_DEVSERVER=
CHROMEOS_RELEASE_APPID={C0E4276B-35C7-023D-BB4A-42D642B91E65}
CHROMEOS_RELEASE_BOARD=panther-signed-mpkeys
CHROMEOS_RELEASE_DESCRIPTION=5500.100.9 (Official Build) stable-channel panther 
CHROMEOS_RELEASE_NAME=Chrome OS
CHROMEOS_RELEASE_TRACK=stable-channel
CHROMEOS_RELEASE_VERSION=5500.100.9
GOOGLE_RELEASE=5500.100.9
ReadFileToString failed to open /mnt/stateful_partition/etc/lsb-release

TO:
CHROMEOS_AUSERVER=https://tools.google.com/service/update2
CHROMEOS_BOARD_APPID={C0E4276B-35C7-023D-BB4A-42D642B91E65}
CHROMEOS_CANARY_APPID={90F229CE-83E2-4FAF-8479-E368A34938B1}
CHROMEOS_DEVSERVER=
CHROMEOS_RELEASE_APPID={C0E4276B-35C7-023D-BB4A-42D642B91E65}
CHROMEOS_RELEASE_BOARD=panther-signed-mpkeys
CHROMEOS_RELEASE_BRANCH_NUMBER=60
CHROMEOS_RELEASE_BUILD_NUMBER=8172
CHROMEOS_RELEASE_BUILD_TYPE=Official Build
CHROMEOS_RELEASE_CHROME_MILESTONE=51
CHROMEOS_RELEASE_DESCRIPTION=8172.60.0 (Official Build) stable-channel panther 
CHROMEOS_RELEASE_NAME=Chrome OS
CHROMEOS_RELEASE_PATCH_NUMBER=0
CHROMEOS_RELEASE_TRACK=stable-channel
CHROMEOS_RELEASE_VERSION=8172.60.0
DEVICETYPE=CHROMEBOX
GOOGLE_RELEASE=8172.60.0

ChromeosChrootPostinst(5500.100.9)
Set boot target to /dev/sda5: Partition 5, Slot B
SetImage
KERNEL_CONFIG: console= loglevel=7 init=/sbin/init cros_secure oops=panic panic=-1 root=/dev/dm-0 rootwait ro dm_verity.error_behavior=3 dm_verity.max_bios=-1 dm_verity.dev_wait=1 dm="1 vroot none ro 1,0 2506752 verity payload=PARTUUID=%U/PARTNROFF=1 hashtree=PARTUUID=%U/PARTNROFF=1 hashstart=2506752 alg=sha1 root_hexdigest=c46ab0722a4b3d4f40d2c054a10cb8b6e0e46023 salt=a967ddb323d5bc8bf18f8bbeccf85d731ed65475a2ff42ba9488374c00feb256" noinitrd vt.global_cursor_default=0 kern_guid=%U add_efi_memmap boot=local noresume noswap i915.modeset=1 tpm_tis.force=1 tpm_tis.interrupts=0 nmi_watchdog=panic,lapic  
Setting up verity.
Finished after 11 seconds.
Clearing network driver boot cache: /var/lib/preload-network-drivers.
Syncing filesystems before changing boot order...
Finished after 1 seconds.
Updating Partition Table Attributes using CgptManager...
Updated kernel 4 with Successful = 0 and NumTriesLeft = 6
Checking /mnt/stateful_partition/unencrypted permission.
Permission is ok.
Unlinked file /var/lib/ureadahead/mnt.stateful_partition.encrypted.pack
Unlinked file /var/lib/ureadahead/pack
Unlinked file /var/lib/ureadahead/mnt.stateful_partition.pack
Unlinked file /var/lib/ureadahead/proc.pack
Starting firmware updater (/tmp/au_postint_mount.wlmYH3/usr/sbin/chromeos-firmwareupdate --mode=autoupdate)
Command: /tmp/au_postint_mount.wlmYH3/usr/sbin/chromeos-firmwareupdate --mode=autoupdate
Starting Google_Panther firmware updater v4 (autoupdate)...
 - Updater package: [Google_Panther.4920.24.26]
 - Current system:  [RO:Google_Panther.4920.24.22 , ACT:Google_Panther.4920.24.22]
 - Write protection: Hardware: ON, Software: Main=off
 * invoke: flashrom -p host  -r _current/bios.bin
 Execution failed (1): flashrom -p host  -r _current/bios.bin
 Messages:
flashrom v0.9.4  : faaccd7 : Apr 06 2016 06:44:18 UTC on Linux 3.8.11 (x86_64), built with libpci 3.1.10, GCC 4.9.x 20150123 (prerelease), little endian
Cannot stat /run/lockCould not acquire lock.
ERROR: Execution FAILED.
ERROR: Execution failed: ./updater4.sh (error code = 1)
Finished after 1 seconds.
Failed Command: /tmp/au_postint_mount.wlmYH3/usr/sbin/chromeos-firmwareupdate --mode=autoupdate - Exit Code 1
Firmware update failed (error code: 1).
Rolling back update due to failure installing required firmware.
Successfully updated GPT with all settings to rollback.
PostInstall Failed

[0706/164325:ERROR:postinstall_runner_action.cc(83)] Postinst command failed with code: 1
[0706/164325:INFO:prefs.cc(27)] delta-update-failures not present in /var/lib/update_engine/prefs
[0706/164325:INFO:action_processor.cc(68)] ActionProcessor::ActionComplete: PostinstallRunnerAction action failed. Aborting processing.
[0706/164325:INFO:action_processor.cc(73)] ActionProcessor::ActionComplete: finished last action of type PostinstallRunnerAction
[0706/164325:INFO:update_attempter.cc(842)] Processing Done.
[0706/164325:INFO:utils.cc(675)] Setting cgroup cpu shares to  1024
[0706/164325:INFO:update_attempter.cc(1254)] CPU shares = 1024
[0706/164325:ERROR:update_attempter.cc(1225)] Update failed.
[0706/164325:INFO:payload_state.cc(188)] Updating payload state for error code: 5 (kErrorCodePostinstallRunnerError)
[0706/164325:INFO:payload_state.cc(272)] Not incrementing URL index or failure count for this error
[0706/164325:INFO:update_attempter.cc(1229)] Reporting the error event

 

Deleted: update_engine.20160706-163838.txt 59.7 KB

update_engine.20160706-163838.txt 59.7 KB View Download