Sorry, I cannot repro this issue. Can you?

{13:04:06}[guocb@guocb:/work/chromeos/src/platform/dev (devserver-bugfix)]
$ uptime
 13:04:07 up 14 min,  1 user,  load average: 2.35, 1.80, 1.10
{13:04:07}[guocb@guocb:/work/chromeos/src/platform/dev (devserver-bugfix)]
$ cros_sdk --no-ns-pid
(cr) ((4853533...)) guocb@guocb ~/trunk/src/scripts $ 

I couldn't reproduce this either.  From the logs, it looks like the existing chroot image was successfully mounted, but then it decided it needed to create a new chroot anyway.

cros_sdk detects if the chroot is set up correctly by looking for <chroot>/etc/cros_chroot_version .  Perhaps that file was somehow deleted prior to rebooting?

 Issue 836041  has been merged into this issue.

If there isn't one already, would it make sense to add a flag or env var to cros_sdk so I could force it not to recreate the chroot? Using this I could get a copy of a chroot that triggers this behavior that we could use to troubleshoot the issue.

Alternatively I could just interrupt the download, but I am not sure if the chroot.img would be gone at that point, or I could make a hard link to chroot.img as long as cros_sdk doesn't truncate the existing inode.

I suspect you could reproduce this by removing /etc/cros_chroot_version from the chroot and then rebooting.  The trouble is that I'm not sure how it would ever get in that state by itself.  There isn't much in codesearch that touches that file; maybe a failed update_chroot?

Is there a log for the chroot upgrade, and if so where were I look for it?

we really only log to stdout/stderr

scripts generally should clobber /etc/cros_chroot_version in place (e.g. a `echo foo > /etc/cros_chroot_version`) rather than do anything more exotic that could get lost.

This problem happened to me again. One thing to note is it took a while for my machine to reboot because it was waiting for /usr/local/google to unmount.

If it happens again, could you Ctrl-C the download and then tar up the chroot.img file?  If there's something wrong with the image, maybe I can reproduce the behavior with a corrupted file.

I experienced something very similar this morning (cros_sdk rebuilt the chroot).  Additional circumstances:

- My hostname had changed.  I just ordered a new workstation, and it didn't arrive with my requested hostname.  So I submitted a ticket and corp IT tweaked the machine name while I was out on vacation.

- The default configuration of the cinnamon screensaver would not allow me to unlock my workstation.  This could be related to the hostname change while it was locked.  From the lock/unlock screen I therefore rebooted my computer entirely, while it was otherwise logged into my X session.

Console output:

jbrandmeyer@charybdis:~/chromiumos$ cros_sdk
09:43:43: NOTICE: Mounted /usr/local/google/home/jbrandmeyer/chromiumos/chroot.img on chroot
09:43:43: NOTICE: /usr/local/google/home/jbrandmeyer/chromiumos/chroot.img is using 112 GiB more than needed.  Running fstrim.
09:43:43: NOTICE: Downloading SDK tarball...
09:43:44: NOTICE: Creating chroot. This may take a few minutes...

INFO    cros_sdk:make_chroot: Unpacking stage3...
INFO    cros_sdk:make_chroot: Set timezone...
INFO    cros_sdk:make_chroot: Adding user/group...

INFO    cros_sdk:make_chroot: Setting up mounts...
INFO    cros_sdk:make_chroot: Running init_setup()...
INFO    cros_sdk:make_chroot: Setting up hosts/resolv...
INFO    cros_sdk:make_chroot: Setting up /etc/make.*...
INFO    cros_sdk:make_chroot: Running post-inst configuration hacks
>>> Regenerating /etc/ld.so.cache...
lrwxrwxrwx 1 root        root           88 May 29 09:45 /etc/make.conf -> /mnt/host/source/src/third_party/chromiumos-overlay/chromeos/config/make.conf.amd64-host
lrwxrwxrwx 1 root        root           67 May 29 09:45 /etc/portage/make.profile -> /usr/local/portage/chromiumos/profiles/default/linux/amd64/10.0/sdk

/usr/local/portage/chromiumos/profiles/default/linux/amd64/10.0:
total 24
drwxr-xr-x 3 jbrandmeyer primarygroup 4096 May 22 15:40 chromeos
-rw-r--r-- 1 jbrandmeyer primarygroup    2 May 22 15:40 eapi
-rw-r--r-- 1 jbrandmeyer primarygroup  423 May 22 15:40 make.defaults
drwxr-xr-x 2 jbrandmeyer primarygroup 4096 May 22 15:40 no-multilib
-rw-r--r-- 1 jbrandmeyer primarygroup   29 May 22 15:40 parent
drwxr-xr-x 2 jbrandmeyer primarygroup 4096 May 22 15:40 sdk
INFO    cros_sdk:make_chroot: Updating python

Performing Global Updates
(Could take a couple of minutes if you have a lot of binary packages.)


>>> Jobs: 0 of 0 complete                           Load avg: 3.63, 2.49, 1.71
Switching to python2.7
INFO    cros_sdk:make_chroot: Updating portage
>>> Jobs: 0 of 0 complete                           Load avg: 3.50, 2.48, 1.71
INFO    cros_sdk:make_chroot: Updating ncurses
>>> Jobs: 0 of 0 complete                           Load avg: 3.38, 2.47, 1.71
INFO    cros_sdk:make_chroot: Updating preinstalled build tools
09:45:56: INFO: RunCommand: /usr/local/google/home/jbrandmeyer/chromiumos/.cache/common/gsutil_4.30.tar.gz/gsutil/gsutil -o 'Boto:num_retries=10' cp -v -- gs://chromeos-prebuilt/host/amd64/tatl/paladin-R68-10686.0.0-rc2/packages/Packages /tmp/tmpLCUmOP.tmp
09:45:59: INFO: RunCommand: /usr/local/google/home/jbrandmeyer/chromiumos/.cache/common/gsutil_4.30.tar.gz/gsutil/gsutil -o 'Boto:num_retries=10' cp -v -- gs://chromeos-prebuilt/host/amd64/amd64-generic/paladin-R68-10686.0.0-rc2/packages/Packages /tmp/tmpxfpzMK.tmp
09:46:00: INFO: RunCommand: /usr/local/google/home/jbrandmeyer/chromiumos/.cache/common/gsutil_4.30.tar.gz/gsutil/gsutil -o 'Boto:num_retries=10' cp -v -- gs://chromeos-prebuilt/host/amd64/moblab-generic-vm/paladin-R68-10686.0.0-rc2/packages/Packages /tmp/tmp04YRJ_.tmp
09:46:02: INFO: RunCommand: /usr/local/google/home/jbrandmeyer/chromiumos/.cache/common/gsutil_4.30.tar.gz/gsutil/gsutil -o 'Boto:num_retries=10' cp -v -- gs://chromeos-prebuilt/host/amd64/arm-generic/paladin-R68-10686.0.0-rc2/packages/Packages /tmp/tmp_UQcvw.tmp
09:46:03: INFO: RunCommand: /usr/local/google/home/jbrandmeyer/chromiumos/.cache/common/gsutil_4.30.tar.gz/gsutil/gsutil -o 'Boto:num_retries=10' cp -v -- gs://chromeos-prebuilt/host/amd64/amd64-host/chroot-2018.05.21.101803/packages/Packages /tmp/tmpumeTOl.tmp
Starting fast-emerge.
 Building package sys-apps/sandbox >=sys-devel/patch-2.7 sys-devel/automake sys-devel/bison on root
Calculating deps...
Deps calculated in 0m1.9s

Total: 0 packages, Size of downloads: 0 KiB
Done
INFO    cros_sdk:make_chroot: Updating host toolchain
09:46:09: INFO: Determining required toolchain updates...
09:46:09: INFO: Nothing to update!
09:46:09: INFO: Nothing to clean!
INFO    cros_sdk:make_chroot: Running emerge curl sudo gentoolkit ...

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.
Starting fast-emerge.
 Building package pbzip2 dev-libs/openssl net-misc/curl sudo app-portage/gentoolkit on root
Calculating deps...
Deps calculated in 0m1.8s

Total: 0 packages, Size of downloads: 0 KiB
Done
INFO    cros_sdk:make_chroot: Updating Perl modules
INFO    : Chroot is already initialized to 155
INFO    : Elapsed time (run_chroot_version_hooks): 0m0s
INFO    : Updating chroot
INFO    : Clearing shadow utils lockfiles under /
INFO    : Bootstrapping depot_tools
INFO    : Updating the SDK

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.
Starting fast-emerge.
 Building package virtual/target-sdk world on root
Calculating deps...
Deps calculated in 0m19.3s
[ebuild     U  ] dev-util/crosutils-0.0.1-r2127::chromiumos [0.0.1-r2126::chromiumos] USE="cros_host -profiling" 0 KiB
[ebuild     U  ] x11-libs/gdk-pixbuf-2.22.1-r5:2::portage-stable [2.22.1-r4:2::portage-stable] USE="X -debug -doc -introspection -jpeg -jpeg2k -svg {-test} -tiff" 0 KiB
[ebuild  N     ] dev-embedded/meta-embedded-toolkit-2.8.1-r2::chromeos  0 KiB
[ebuild     U  ] app-misc/screen-4.6.1::portage-stable [4.0.3-r6::portage-stable] USE="-debug -multiuser -nethack (-pam) -selinux" 0 KiB
[ebuild     U  ] sys-apps/coreboot-utils-0.0.1-r3374::chromiumos [0.0.1-r3372::chromiumos] USE="cros_host pci -mma -profiling -static" 0 KiB
[ebuild     U  ] chromeos-base/system_api-0.0.1-r3330::chromiumos [0.0.1-r3328::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/libbrillo-0.0.1-r1322:0/0.0.1.0::chromiumos [0.0.1-r1321:0/0.0.1.0::chromiumos] USE="cros_host dbus -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/chromeos-dbus-bindings-0.0.1-r2362::chromiumos [0.0.1-r2361::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/power_manager-client-0.0.1-r1537::chromiumos [0.0.1-r1536::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/shill-client-0.0.1-r1432::chromiumos [0.0.1-r1431::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/debugd-client-0.0.1-r1518::chromiumos [0.0.1-r1516::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/session_manager-client-0.0.1-r1533::chromiumos [0.0.1-r1531::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/update_engine-client-0.0.1-r1355::chromiumos [0.0.1-r1354::chromiumos] USE="cros_host -asan -cros-debug -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/metrics-0.0.1-r2811::chromiumos [0.0.1-r2810::chromiumos] USE="cros_host passive_metrics -asan -cros-debug -fuzzer -metrics_uploader -profiling -systemd {-test}" 0 KiB
[ebuild     U  ] chromeos-base/ec-utils-0.0.1-r4771::chromiumos [0.0.1-r4766::chromiumos] USE="cros_host -cr50_onboard -cr50_utils -profiling -static -unibuild -updater_utils" EC_FIRMWARE="-atlas -bds -chell -chell_pd -coral -cr50 -cyan -dingdong -elm -eve -eve_fp -extra_atlas -extra_bds -extra_chell -extra_chell_pd -extra_coral -extra_cr50 -extra_cyan -extra_dingdong -extra_elm -extra_eve -extra_eve_fp -extra_fizz -extra_glados -extra_glados_pd -extra_glkrvp -extra_gru -extra_grunt -extra_hadoken -extra_hammer -extra_hoho -extra_host -extra_jerry -extra_kahlee -extra_kevin -extra_llama -extra_lux -extra_meowth -extra_meowth_fp -extra_minimuffin -extra_nami -extra_nautilus -extra_nefario -extra_nocturne -extra_nocturne_fp -extra_oak -extra_oak_pd -extra_octopus -extra_plankton -extra_poppy -extra_reef -extra_rowan -extra_ryu -extra_ryu_p4p5 -extra_ryu_sh -extra_ryu_sh_loader -extra_samus -extra_samus_pd -extra_scarlet -extra_soraka -extra_staff -extra_strago -extra_twinkie -extra_wand -extra_whiskers -extra_zinger -extra_zoombini -fizz -glados -glados_pd -glkrvp -gru -grunt -hadoken -hammer -hoho -host -jerry -kahlee -kevin -llama -lux -meowth -meowth_fp -minimuffin -nami -nautilus -nefario -nocturne -nocturne_fp -oak -oak_pd -octopus -plankton -poppy -reef -rowan -ryu -ryu_p4p5 -ryu_sh -ryu_sh_loader -samus -samus_pd -scarlet -soraka -staff -strago -twinkie -wand -whiskers -zinger -zoombini" 0 KiB
[ebuild     U  ] chromeos-base/ec-devutils-0.0.2-r2844::chromiumos [0.0.2-r2839::chromiumos] USE="cros_host -profiling" PYTHON_TARGETS="python2_7" 0 KiB
[ebuild     U  ] chromeos-base/chromeos-common-script-0.0.1-r4::chromiumos [0.0.1-r3::chromiumos] USE="cros_host -asan -cros-debug -direncryption -fuzzer -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/chromeos-storage-info-0.0.1-r4::chromiumos [0.0.1-r3::chromiumos] USE="cros_host mmc sata -asan -cros-debug -fuzzer -nvme -profiling {-test}" 0 KiB
[ebuild     U  ] chromeos-base/chromeos-installer-0.0.3-r3136::chromiumos [0.0.3-r3135::chromiumos] USE="cros_host -asan -cros-debug -cros_embedded -fuzzer -mtd -pam -profiling -systemd {-test}" 0 KiB
[ebuild     U  ] chromeos-base/update_engine-0.0.3-r3194::chromiumos [0.0.3-r3193::chromiumos] USE="cros_host dbus delta_generator -asan -cros-debug -cros_p2p -fuzzer -hwid_override -mtd -power_management -profiling -systemd {-test}" 0 KiB
[ebuild  N     ] virtual/libelf-3:0/1::portage-stable  ABI_X86="(64) -32 (-x32)" 0 KiB
[ebuild  N     ] chromeos-base/cr50-utils-0.0.1-r4::chromeos  USE="cros_host -profiling" 0 KiB
[ebuild  N     ] chromeos-base/monotype-fonts-20170519-r1::chromeos  USE="-X" 0 KiB
[ebuild   R    ] chromeos-base/chromeos-fonts-0.0.1-r29::chromiumos  USE="cros_host internal*" 0 KiB
[ebuild     U  ] chromeos-base/tast-cmd-0.0.1-r106::chromiumos [0.0.1-r104::chromiumos] USE="cros_host -profiling" 0 KiB
[ebuild     U  ] chromeos-base/tast-remote-tests-cros-0.0.1-r58::chromiumos [0.0.1-r56::chromiumos] USE="cros_host -profiling" 0 KiB
[ebuild  N     ] virtual/target-chrome-os-sdk-1.5-r3::chromeos  0 KiB
[ebuild     U  ] virtual/target-sdk-1.5::chromeos [1-r1::chromiumos] 0 KiB

Total: 28 packages (22 upgrades, 5 new, 1 reinstall), Size of downloads: 0 KiB
Pending 0/28, Fetching 28/28, [Time 09:46:53 | Elapsed 0m20.1s | Load 3.29 2.6 1.8]
Fetched chromeos-base/cr50-utils-0.0.1-r4 in 0.06s
Fetched chromeos-base/ec-devutils-0.0.2-r2844 in 0.08s
Fetched virtual/target-chrome-os-sdk-1.5-r3 in 0.09s
Fetched virtual/libelf-3 in 0.09s
Fetched chromeos-base/system_api-0.0.1-r3330 in 0.09s
Fetched chromeos-base/tast-cmd-0.0.1-r106 in 0.09s
Started virtual/libelf-3 (logged in /tmp/libelf-3-W0L7Fc)
Fetched chromeos-base/chromeos-fonts-0.0.1-r29 in 0.10s
Fetched chromeos-base/chromeos-installer-0.0.3-r3136 in 0.10s
Fetched virtual/target-sdk-1.5 in 0.10s
Started chromeos-base/tast-cmd-0.0.1-r106 (logged in /tmp/tast-cmd-0.0.1-r106-ah8agc)
Started chromeos-base/system_api-0.0.1-r3330 (logged in /tmp/system_api-0.0.1-r3330-5J6a4Q)
Fetched chromeos-base/chromeos-storage-info-0.0.1-r4 in 0.11s
Fetched chromeos-base/power_manager-client-0.0.1-r1537 in 0.11s
Fetched chromeos-base/tast-remote-tests-cros-0.0.1-r58 in 0.11s
Fetched chromeos-base/chromeos-dbus-bindings-0.0.1-r2362 in 0.12s
Fetched chromeos-base/chromeos-common-script-0.0.1-r4 in 0.12s
Fetched chromeos-base/update_engine-client-0.0.1-r1355 in 0.12s
Started chromeos-base/tast-remote-tests-cros-0.0.1-r58 (logged in /tmp/tast-remote-tests-cros-0.0.1-r58-qSFdvm)
Fetched sys-apps/coreboot-utils-0.0.1-r3374 in 0.13s
Fetched chromeos-base/libbrillo-0.0.1-r1322 in 0.13s
Fetched chromeos-base/update_engine-0.0.3-r3194 in 0.13s
Started chromeos-base/chromeos-common-script-0.0.1-r4 (logged in /tmp/chromeos-common-script-0.0.1-r4-Chg3Wb)
Fetched chromeos-base/debugd-client-0.0.1-r1518 in 0.13s
Fetched dev-util/crosutils-0.0.1-r2127 in 0.13s
Fetched chromeos-base/metrics-0.0.1-r2811 in 0.13s
Fetched chromeos-base/shill-client-0.0.1-r1432 in 0.13s
Fetched chromeos-base/ec-utils-0.0.1-r4771 in 0.13s
Started sys-apps/coreboot-utils-0.0.1-r3374 (logged in /tmp/coreboot-utils-0.0.1-r3374-B1s2LG)
Started chromeos-base/ec-utils-0.0.1-r4771 (logged in /tmp/ec-utils-0.0.1-r4771-xbwPKj)
Fetched chromeos-base/session_manager-client-0.0.1-r1533 in 0.14s
Started dev-util/crosutils-0.0.1-r2127 (logged in /tmp/crosutils-0.0.1-r2127-hzgI_t)
Fetched x11-libs/gdk-pixbuf-2.22.1-r5 in 0.16s
Started x11-libs/gdk-pixbuf-2.22.1-r5 (logged in /tmp/gdk-pixbuf-2.22.1-r5-no59PY)
Fetched app-misc/screen-4.6.1 in 0.20s
Fetched chromeos-base/monotype-fonts-20170519-r1 in 0.20s
Started app-misc/screen-4.6.1 (logged in /tmp/screen-4.6.1-cImaLc)
Started chromeos-base/monotype-fonts-20170519-r1 (logged in /tmp/monotype-fonts-20170519-r1-VFFIIz)
Fetched dev-embedded/meta-embedded-toolkit-2.8.1-r2 in 1.15s
Started dev-embedded/meta-embedded-toolkit-2.8.1-r2 (logged in /tmp/meta-embedded-toolkit-2.8.1-r2-XYqtcO)
Completed virtual/libelf-3 (in 0m8.5s)
Pending 15/28, Building 12/12, [Time 09:47:02 | Elapsed 0m28.7s | Load 4.23 2.81 1.88]
Started chromeos-base/cr50-utils-0.0.1-r4 (logged in /tmp/cr50-utils-0.0.1-r4-u7iojj)
Completed dev-util/crosutils-0.0.1-r2127 (in 0m11.0s)
Pending 15/28, Building 11/11, [Time 09:47:05 | Elapsed 0m31.3s | Load 5.81 3.16 2.0]
Completed chromeos-base/chromeos-common-script-0.0.1-r4 (in 0m13.4s)
Pending 14/28, Building 11/11, [Time 09:47:07 | Elapsed 0m33.7s | Load 5.81 3.16 2.0]
Started chromeos-base/chromeos-storage-info-0.0.1-r4 (logged in /tmp/chromeos-storage-info-0.0.1-r4-7nfTIF)
Completed chromeos-base/tast-cmd-0.0.1-r106 (in 0m14.6s)
Pending 14/28, Building 10/10, [Time 09:47:08 | Elapsed 0m34.9s | Load 6.71 3.39 2.08]
Completed chromeos-base/tast-remote-tests-cros-0.0.1-r58 (in 0m14.6s)
Pending 14/28, Building 9/9, [Time 09:47:08 | Elapsed 0m34.9s | Load 6.71 3.39 2.08]
Completed chromeos-base/ec-utils-0.0.1-r4771 (in 0m15.9s)
Pending 13/28, Building 9/9, [Time 09:47:09 | Elapsed 0m36.1s | Load 6.71 3.39 2.08]
Started chromeos-base/ec-devutils-0.0.2-r2844 (logged in /tmp/ec-devutils-0.0.2-r2844-QrJeJS)
Completed dev-embedded/meta-embedded-toolkit-2.8.1-r2 (in 0m15.1s)
Pending 13/28, Building 8/8, [Time 09:47:10 | Elapsed 0m36.4s | Load 6.71 3.39 2.08]
Completed sys-apps/coreboot-utils-0.0.1-r3374 (in 0m16.5s)
Pending 13/28, Building 7/7, [Time 09:47:10 | Elapsed 0m36.8s | Load 6.71 3.39 2.08]
Completed chromeos-base/system_api-0.0.1-r3330 (in 0m20.9s)
Pending 12/28, Building 7/7, [Time 09:47:14 | Elapsed 0m41.2s | Load 7.21 3.55 2.14]
Started chromeos-base/libbrillo-0.0.1-r1322 (logged in /tmp/libbrillo-0.0.1-r1322-X69lQ6)
Completed chromeos-base/chromeos-storage-info-0.0.1-r4 (in 0m10.1s)
Pending 12/28, Building 6/6, [Time 09:47:17 | Elapsed 0m43.7s | Load 7.21 3.55 2.14]
Completed chromeos-base/cr50-utils-0.0.1-r4 (in 0m15.7s)
Pending 12/28, Building 5/5, [Time 09:47:18 | Elapsed 0m44.4s | Load 7.36 3.64 2.17]
Completed app-misc/screen-4.6.1 (in 0m30.3s)
Pending 12/28, Building 4/4, [Time 09:47:24 | Elapsed 0m50.6s | Load 8.45 3.93 2.27]
Completed chromeos-base/ec-devutils-0.0.2-r2844 (in 0m19.2s)
Pending 12/28, Building 3/3, [Time 09:47:29 | Elapsed 0m55.3s | Load 8.98 4.11 2.34]
Completed x11-libs/gdk-pixbuf-2.22.1-r5 (in 0m51.0s)
Pending 12/28, Building 2/2, [Time 09:47:45 | Elapsed 1m11.3s | Load 8.29 4.2 2.4]
Completed chromeos-base/libbrillo-0.0.1-r1322 (in 0m30.1s)
Pending 10/28, Building 3/3, [Time 09:47:45 | Elapsed 1m11.3s | Load 8.29 4.2 2.4]
Started chromeos-base/chromeos-dbus-bindings-0.0.1-r2362 (logged in /tmp/chromeos-dbus-bindings-0.0.1-r2362-cBqkwd)
Started chromeos-base/chromeos-installer-0.0.3-r3136 (logged in /tmp/chromeos-installer-0.0.3-r3136-3xY0iQ)
Completed chromeos-base/monotype-fonts-20170519-r1 (in 0m57.1s)
Pending 9/28, Building 3/3, [Time 09:47:51 | Elapsed 1m17.4s | Load 7.7 4.15 2.39]
Started chromeos-base/chromeos-fonts-0.0.1-r29 (logged in /tmp/chromeos-fonts-0.0.1-r29-7n1xjn)
Completed chromeos-base/chromeos-installer-0.0.3-r3136 (in 0m12.7s)
Pending 9/28, Building 2/2, [Time 09:47:57 | Elapsed 1m24.0s | Load 7.73 4.21 2.42]
Completed chromeos-base/chromeos-fonts-0.0.1-r29 (in 0m6.9s)
Pending 9/28, Building 1/1, [Time 09:47:58 | Elapsed 1m24.3s | Load 7.73 4.21 2.42]
Completed chromeos-base/chromeos-dbus-bindings-0.0.1-r2362 (in 0m13.6s)
Pending 4/28, Building 5/5, [Time 09:47:58 | Elapsed 1m24.9s | Load 7.27 4.18 2.42]
Started chromeos-base/update_engine-client-0.0.1-r1355 (logged in /tmp/update_engine-client-0.0.1-r1355-MoExub)
Started chromeos-base/shill-client-0.0.1-r1432 (logged in /tmp/shill-client-0.0.1-r1432-EozwQl)
Started chromeos-base/power_manager-client-0.0.1-r1537 (logged in /tmp/power_manager-client-0.0.1-r1537-3sjhhK)
Started chromeos-base/session_manager-client-0.0.1-r1533 (logged in /tmp/session_manager-client-0.0.1-r1533-qm7z1a)
Started chromeos-base/debugd-client-0.0.1-r1518 (logged in /tmp/debugd-client-0.0.1-r1518-V01w1A)
Completed chromeos-base/session_manager-client-0.0.1-r1533 (in 0m11.1s)
Pending 3/28, Building 5/5, [Time 09:48:09 | Elapsed 1m36.1s | Load 7.15 4.25 2.47]
Started chromeos-base/metrics-0.0.1-r2811 (logged in /tmp/metrics-0.0.1-r2811-usqEvO)
Completed chromeos-base/power_manager-client-0.0.1-r1537 (in 0m11.5s)
Pending 3/28, Building 4/4, [Time 09:48:10 | Elapsed 1m36.4s | Load 7.15 4.25 2.47]
Completed chromeos-base/debugd-client-0.0.1-r1518 (in 0m11.5s)
Pending 3/28, Building 3/3, [Time 09:48:10 | Elapsed 1m36.5s | Load 7.15 4.25 2.47]
Completed chromeos-base/update_engine-client-0.0.1-r1355 (in 0m11.8s)
Pending 3/28, Building 2/2, [Time 09:48:10 | Elapsed 1m36.8s | Load 7.15 4.25 2.47]
Completed chromeos-base/shill-client-0.0.1-r1432 (in 0m11.8s)
Pending 3/28, Building 1/1, [Time 09:48:10 | Elapsed 1m36.8s | Load 7.15 4.25 2.47]
Completed chromeos-base/metrics-0.0.1-r2811 (in 0m17.6s)
Pending 2/28, Building 1/1, [Time 09:48:27 | Elapsed 1m53.7s | Load 6.23 4.19 2.47]
Started chromeos-base/update_engine-0.0.3-r3194 (logged in /tmp/update_engine-0.0.3-r3194-gHg2E_)
Completed chromeos-base/update_engine-0.0.3-r3194 (in 0m23.0s)
Pending 1/28, Building 1/1, [Time 09:48:50 | Elapsed 2m16.7s | Load 6.68 4.46 2.61]
Started virtual/target-chrome-os-sdk-1.5-r3 (logged in /tmp/target-chrome-os-sdk-1.5-r3-9c6K2u)
Completed virtual/target-chrome-os-sdk-1.5-r3 (in 0m6.6s)
Pending 0/28, Building 1/1, [Time 09:48:57 | Elapsed 2m23.3s | Load 6.47 4.46 2.62]
Started virtual/target-sdk-1.5 (logged in /tmp/target-sdk-1.5-808uMh)
Completed virtual/target-sdk-1.5 (in 0m7.7s)
Pending 0/28, [Time 09:49:04 | Elapsed 2m31.0s | Load 6.02 4.42 2.63]
Merge complete
Done

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.

Local copy of remote index is up-to-date and will be used.
Starting fast-emerge.
 Building package dev-util/cargo dev-lang/rust virtual/target-sdk-post-cross on root
Calculating deps...
Deps calculated in 0m3.8s
[binary  N     ] net-libs/libssh2-1.8.0::portage-stable  USE="zlib -gcrypt -libressl -static-libs {-test}" ABI_X86="(64) -32 (-x32)" 274 KiB
[binary  N     ] dev-lang/rust-1.26.0-r2:stable/1.26::chromiumos  167289 KiB
[binary  N     ] dev-util/cargo-0.26.0::portage-stable  USE="-debug -doc -libressl" 2807 KiB
[binary  N     ] virtual/target-chromium-os-sdk-post-cross-1-r1::chromiumos  9 KiB
[binary  N     ] virtual/target-sdk-post-cross-1-r1::chromiumos  9 KiB

Total: 5 packages (5 new, 5 binaries), Size of downloads: 170387 KiB
Pending 0/5, Fetching 5/5, [Time 09:49:11 | Elapsed 0m4.6s | Load 5.86 4.42 2.63]
Fetched virtual/target-chromium-os-sdk-post-cross-1-r1 in 1.58s
Fetched virtual/target-sdk-post-cross-1-r1 in 1.63s
Fetched net-libs/libssh2-1.8.0 in 1.64s
Started net-libs/libssh2-1.8.0 (logged in /tmp/libssh2-1.8.0-wn0kWe)
Fetched dev-util/cargo-0.26.0 in 1.70s
Completed net-libs/libssh2-1.8.0 (in 0m2.4s)
Pending 3/5, Fetching 1/1, [Time 09:49:15 | Elapsed 0m8.7s | Load 5.95 4.46 2.66]
Fetched dev-lang/rust-1.26.0-r2 in 7.64s
Started dev-lang/rust-1.26.0-r2 (logged in /tmp/rust-1.26.0-r2-vh_ehj)
Completed dev-lang/rust-1.26.0-r2 (in 0m5.1s)
Pending 2/5, Building 1/1, [Time 09:49:24 | Elapsed 0m17.4s | Load 5.56 4.43 2.67]
Started dev-util/cargo-0.26.0 (logged in /tmp/cargo-0.26.0-G6NN0R)
Completed dev-util/cargo-0.26.0 (in 0m2.8s)
Pending 1/5, Building 1/1, [Time 09:49:26 | Elapsed 0m20.2s | Load 5.56 4.43 2.67]
Started virtual/target-chromium-os-sdk-post-cross-1-r1 (logged in /tmp/target-chromium-os-sdk-post-cross-1-r1-buk5lV)
Completed virtual/target-chromium-os-sdk-post-cross-1-r1 (in 0m2.3s)
Pending 0/5, Building 1/1, [Time 09:49:29 | Elapsed 0m22.5s | Load 5.44 4.42 2.67]
Started virtual/target-sdk-post-cross-1-r1 (logged in /tmp/target-sdk-post-cross-1-r1-xVlOZM)
Completed virtual/target-sdk-post-cross-1-r1 (in 0m2.3s)
Pending 0/5, [Time 09:49:31 | Elapsed 0m24.7s | Load 5.44 4.42 2.67]
Merge complete
Done
Scanning Configuration files...
Exiting: Nothing left to do; exiting. :)
INFO    : Running 'eclean -d packages' to clean up stale binpkgs
 * Building file list for packages cleaning...
 * Your packages directory was already clean.
INFO    : Elapsed time (update_chroot): 3m16s
Now using icedtea-bin-8 as your generation-2 system JVM
INFO    cros_sdk:make_chroot: Elapsed time (make_chroot.sh): 5m49s

cros_sdk:make_chroot: All set up.  To enter the chroot, run:
$ cros_sdk --enter 

CAUTION: Do *NOT* rm -rf the chroot directory; if there are stale bind
mounts you may end up deleting your source tree too.  To unmount and
delete the chroot cleanly, use:
$ cros_sdk --delete 

(cr) ((16441e1...)) jbrandmeyer@charybdis ~/trunk/src/scripts $

The following revision refers to this bug:
  https://chromium.googlesource.com/chromiumos/chromite/+/7b44bef640065d5614c38d6b1cd91731547c26ca

commit 7b44bef640065d5614c38d6b1cd91731547c26ca
Author: Benjamin Gordon <bmgordon@chromium.org>
Date: Sat Jun 09 09:19:24 2018

cros_sdk: Simplify create/download logic

Right now options.create can get toggled on and off in a couple of
places, and one of the places also sets options.download through some
tricky logic.  This is really hard to reason about and might be one
source of the bug where a chroot is recreated even when it already
exists.  Simplify this by breaking out a couple of the steps and
ensuring that flags are only ever turned on (not turned back to False).

This leads to a scenario where if --create is explicitly requested and
the chroot already exists, make_chroot.sh will be called again.  To
avoid that, also re-check if the chroot is set up before calling
CreateChroot().

BUG= chromium:834412 
TEST=Ran cros_sdk with various flag combinations

Change-Id: Ie8ebb3ee9daef440b2b52ec11d7310566f6a80e9
Reviewed-on: https://chromium-review.googlesource.com/1093024
Commit-Ready: Benjamin Gordon <bmgordon@chromium.org>
Tested-by: Benjamin Gordon <bmgordon@chromium.org>
Reviewed-by: Mike Frysinger <vapier@chromium.org>

[modify] https://crrev.com/7b44bef640065d5614c38d6b1cd91731547c26ca/lib/cros_sdk_lib.py
[modify] https://crrev.com/7b44bef640065d5614c38d6b1cd91731547c26ca/lib/cros_sdk_lib_unittest.py
[modify] https://crrev.com/7b44bef640065d5614c38d6b1cd91731547c26ca/scripts/cros_sdk.py

I wasn't ever able to reproduce this locally, but this is likely fixed by the extra check added in crrev.com/c/1093024.  Feel free to reopen if it happens again.

I can confirm the chroot is no longer recreated. I did see this warning:

09:08:52: WARNING: could not read .../chroot/etc/cros_chroot_version

I am wondering what the root cause is, but I am not sure it is worth the effort to split off a separate bug. That file is present in the chroot after cros_sdk finishes.

That warning is from a library checking for the chroot version to see if it needs to mount the chroot.  I'll send out a CL to downgrade it to a debug message so that people won't see if after every reboot.

The following revision refers to this bug:
  https://chromium.googlesource.com/chromiumos/chromite/+/d10d32bf1073df6f6fdf67a27b3f7421f3a8db7f

commit d10d32bf1073df6f6fdf67a27b3f7421f3a8db7f
Author: Benjamin Gordon <bmgordon@chromium.org>
Date: Wed Jun 13 19:59:23 2018

cros_sdk_lib: Reduce version warning to debug

GetChrootVersion prints a warning when /etc/cros_chroot_version can't be
read from the chroot.  It was originally only used on builders when the
chroot was mounted, so this was a worthwhile warning.  Now that cros_sdk
(indirectly) calls it to check if the chroot is ready, this warning is
showing up way too often.  Downgrade it to a debug message so that
normal cros_sdk calls don't display it.

BUG= chromium:834412 
TEST=cros_sdk without chroot mounted

Change-Id: Ic222c4d87749081b1c60d7740aa09ab66421de29
Reviewed-on: https://chromium-review.googlesource.com/1095678
Commit-Ready: Benjamin Gordon <bmgordon@chromium.org>
Tested-by: Benjamin Gordon <bmgordon@chromium.org>
Reviewed-by: Mike Frysinger <vapier@chromium.org>

[modify] https://crrev.com/d10d32bf1073df6f6fdf67a27b3f7421f3a8db7f/lib/cros_sdk_lib.py

The following revision refers to this bug:
  https://chromium.googlesource.com/chromium/src.git/+/6fdada6c28d349e63ed16c3de4a4ea06c6823e4e

commit 6fdada6c28d349e63ed16c3de4a4ea06c6823e4e
Author: Chromite Chromium Autoroll <chromite-chromium-autoroll@skia-buildbots.google.com.iam.gserviceaccount.com>
Date: Thu Jun 14 05:35:04 2018

Roll src/third_party/chromite 402795d084bf..17b4574e14a3 (8 commits)

https://chromium.googlesource.com/chromiumos/chromite.git/+log/402795d084bf..17b4574e14a3


git log 402795d084bf..17b4574e14a3 --date=short --no-merges --format='%ad %ae %s'
2018-06-14 chrome-bot@chromium.org Update config settings by config-updater.
2018-06-14 dgarrett@google.com master-full: Add better master/slave support for FULL_TYPE.
2018-06-14 bpastene@chromium.org cros_run_vm_test: Fix --host-cmd and --autotest cmd args.
2018-06-14 lannm@chromium.org Make FindOverlays output have deterministic ordering
2018-06-14 lannm@chromium.org Fix cros_mark_as_stable for CLs that create overlays
2018-06-13 ihf@chromium.org chromeos_config: make betty-arcnext important.
2018-06-13 bmgordon@chromium.org cros_sdk_lib: Reduce version warning to debug
2018-06-13 bmgordon@chromium.org cros_sdk: Add a --unmount action


Created with:
  gclient setdep -r src/third_party/chromite@17b4574e14a3

The AutoRoll server is located here: https://chromite-chromium-roll.skia.org

Documentation for the AutoRoller is here:
https://skia.googlesource.com/buildbot/+/master/autoroll/README.md

If the roll is causing failures, please contact the current sheriff, who should
be CC'd on the roll, and stop the roller if necessary.



BUG= chromium:851183 ,chromium:None,chromium:851059,chromium:851059,chromium:834412,chromium:None
TBR=chrome-os-gardeners@chromium.org

Change-Id: I247744af7bf25889f89c57eee10cf8568af22542
Reviewed-on: https://chromium-review.googlesource.com/1100319
Reviewed-by: Chromite Chromium Autoroll <chromite-chromium-autoroll@skia-buildbots.google.com.iam.gserviceaccount.com>
Commit-Queue: Chromite Chromium Autoroll <chromite-chromium-autoroll@skia-buildbots.google.com.iam.gserviceaccount.com>
Cr-Commit-Position: refs/heads/master@{#567155}
[modify] https://crrev.com/6fdada6c28d349e63ed16c3de4a4ea06c6823e4e/DEPS