Issue 634356 link

Starred by 2 users

Issue metadata

Status:	Assigned
Owner:	█ jongkyuj@google.com Last visit > 30 days ago
Cc:	█ bmahadev@chromium.org aashuto...@chromium.org █ jrbarnette@chromium.org █ tienchang@chromium.org █ krisr@chromium.org █ sbasi@chromium.org
Components:	Tests>Fails
EstimatedDays:	----
NextAction:	----
OS:	Chrome
Pri:	3
Type:	Bug
Hotlist-Google

Test "platform_InstallTestImage" fails with "Source does not look like a removable device" error with lumpy.

Project Member Reported by jongkyuj@google.com, Aug 4 2016

Issue description

Version: 8530.31.0
Device: lumpy

This is same issue as below but happens with lumpy.
Per request, creating the separate bug for this issue.

https://bugs.chromium.org/p/chromium/issues/detail?id=634032&can=2&start=0&num=100&q=owner%3Ame&colspec=ID%20Pri%20M%20Stars%20ReleaseBlock%20Component%20Status%20Owner%20Summary%20OS%20Modified&groupby=&sort=



/chromiumos/src/third_party/autotest/files$ cros_sdk
(cr) ((8628edf...)) jongkyuj@jongkyuj0 ~/trunk/src/scripts $ test_that -b lumpy --autotest_dir=../third_party/autotest/files/ chromeos1-test-station.cros platform_InstallTestImage
INFO:root:Identity added: /tmp/test_that_results_Vho4_q/testing_rsa (/tmp/test_that_results_Vho4_q/testing_rsa)
11:25:28 INFO | Began logging to /tmp/test_that_results_Vho4_q
Adding labels [u'cros-version:ad_hoc_build', u'board:lumpy'] to host chromeos1-test-station.cros
13:25:28 INFO | Fetching suite for job named platform_InstallTestImage...
13:25:30 INFO | Scheduling suite for job named platform_InstallTestImage...
13:25:30 INFO | ... scheduled 1 job(s).
13:25:30 INFO | autoserv| Results placed in /tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage
13:25:30 INFO | autoserv| Logged pid 323 to /tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage/.autoserv_execute
13:25:30 INFO | autoserv| I am PID 323
13:25:30 INFO | autoserv| Not checking if job_repo_url contains autotest packages on ['chromeos1-test-station.cros']
13:25:30 INFO | autoserv| Processing control file
13:25:31 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_5r89NPssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpKN1rC8 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
13:25:32 INFO | autoserv| Pinging servo host at chromeos1-test-station-servo.cros
13:25:34 INFO | autoserv| Pinging host chromeos1-test-station-servo.cros
13:25:34 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_wLBphessh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=180 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station-servo.cros'
13:25:35 INFO | autoserv| Host (ssh) chromeos1-test-station-servo.cros is alive
13:25:35 INFO | autoserv| Applying an update to the servo host, if necessary.
13:26:35 INFO | autoserv| Devserver call failed: "http://100.107.160.4:8082/check_health?", timeout: 60 seconds, Error: Call is timed out.
13:26:35 INFO | autoserv| Using devserver url: http://172.17.40.22:8082/update/beaglebone_servo-release/R54-8638.0.0 to trigger update on servo host chromeos1-test-station-servo.cros, from 8489.0.0 to 8638.0.0
13:26:35 INFO | autoserv| Staging artifacts on devserver http://172.17.40.22:8082: build=beaglebone_servo-release/R54-8638.0.0, artifacts=['full_payload'], files=, archive_url=gs://chromeos-image-archive/beaglebone_servo-release/R54-8638.0.0
13:26:51 INFO | autoserv| Finished staging artifacts: build=beaglebone_servo-release/R54-8638.0.0, artifacts=['full_payload'], files=, archive_url=gs://chromeos-image-archive/beaglebone_servo-release/R54-8638.0.0
13:26:51 INFO | autoserv| Triggering update via: /usr/bin/update_engine_client --check_for_update --omaha_url=http://172.17.40.22:8082/update/beaglebone_servo-release/R54-8638.0.0
13:26:51 INFO | autoserv| [stderr] [0803/170332:INFO:update_engine_client.cc(449)] Initiating update check and install.
13:26:51 INFO | autoserv| Triggered download and update passed for chromeos1-test-station-servo.cros, update engine currently in status UPDATE_STATUS_CHECKING_FOR_UPDATE
13:26:51 INFO | autoserv| servod is running, PID=26978,26994,26995
13:26:52 INFO | autoserv| Setting usb_mux_oe1 to on
13:26:53 INFO | autoserv| Setting prtctl4_pwren to off
13:26:55 INFO | autoserv| Sanity checks pass on servo host chromeos1-test-station-servo.cros
13:26:56 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_J5AIFsssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
13:26:56 INFO | autoserv| Can't find build to stage: DUT has no board attribute, cannot be repaired..
13:26:56 INFO | autoserv| Assuming this is an invocation from test_that with a pre-installed USB image
13:26:56 INFO | autoserv| START platform_InstallTestImage       platform_InstallTestImage       timestamp=1470248816    localtime=Aug 03 13:26:56
13:26:56 INFO | autoserv| Downloading image to USB, then booting from it. Usb boot timeout = 300
13:26:57 INFO | autoserv| Setting power_state to off
13:27:08 INFO | autoserv| Setting prtctl4_pwren to off
13:27:10 INFO | autoserv| Setting usb_mux_sel1 to servo_sees_usbkey
13:27:13 INFO | autoserv| Setting prtctl4_pwren to on
13:27:23 INFO | autoserv| Setting power_state to rec
13:27:35 INFO | autoserv| Setting prtctl4_pwren to off
13:27:38 INFO | autoserv| Setting usb_mux_sel1 to dut_sees_usbkey
13:27:40 INFO | autoserv| Setting prtctl4_pwren to on
13:27:40 INFO | autoserv| Resetting the TPM status
13:27:40 INFO | autoserv| chromeos-tpm-recovery is too old.
13:27:40 INFO | autoserv| Installing image through chromeos-install.
13:27:40 INFO | autoserv| [stderr] Error: Source does not look like a removable device: /dev/sda
13:27:40 INFO | autoserv| Autotest caught exception when running test:
13:27:40 INFO | autoserv| Traceback (most recent call last):
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 600, in _exec
13:27:40 INFO | autoserv| _call_test_function(self.execute, *p_args, **p_dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 810, in _call_test_function
13:27:40 INFO | autoserv| raise error.UnhandledTestFail(e)
13:27:40 INFO | autoserv| UnhandledTestFail: Unhandled AutoservRunError: command execution error
13:27:40 INFO | autoserv| * Command:
13:27:40 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_J5AIFsssh-
13:27:40 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
13:27:40 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
13:27:40 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
13:27:40 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
13:27:40 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
13:27:40 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
13:27:40 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
13:27:40 INFO | autoserv| Exit status: 1
13:27:40 INFO | autoserv| Duration: 0.201654911041
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| stderr:
13:27:40 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
13:27:40 INFO | autoserv| Traceback (most recent call last):
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
13:27:40 INFO | autoserv| return func(*args, **dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
13:27:40 INFO | autoserv| dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
13:27:40 INFO | autoserv| postprocess_profiled_run, args, dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
13:27:40 INFO | autoserv| self.run_once(*args, **dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
13:27:40 INFO | autoserv| host.servo_install(image_url=image_url)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 994, in servo_install
13:27:40 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
13:27:40 INFO | autoserv| options, stdin, args, ignore_timeout)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
13:27:40 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
13:27:40 INFO | autoserv| AutoservRunError: command execution error
13:27:40 INFO | autoserv| * Command:
13:27:40 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_J5AIFsssh-
13:27:40 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
13:27:40 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
13:27:40 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
13:27:40 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
13:27:40 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
13:27:40 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
13:27:40 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
13:27:40 INFO | autoserv| Exit status: 1
13:27:40 INFO | autoserv| Duration: 0.201654911041
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| stderr:
13:27:40 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| FAIL  platform_InstallTestImage       platform_InstallTestImage       timestamp=1470248860    localtime=Aug 03 13:27:40       Unhandled AutoservRunError: command execution error
13:27:40 INFO | autoserv| * Command:
13:27:40 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_J5AIFsssh-
13:27:40 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
13:27:40 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
13:27:40 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
13:27:40 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
13:27:40 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
13:27:40 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
13:27:40 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
13:27:40 INFO | autoserv| Exit status: 1
13:27:40 INFO | autoserv| Duration: 0.201654911041
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| stderr:
13:27:40 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
13:27:40 INFO | autoserv| Traceback (most recent call last):
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
13:27:40 INFO | autoserv| return func(*args, **dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
13:27:40 INFO | autoserv| dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
13:27:40 INFO | autoserv| postprocess_profiled_run, args, dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
13:27:40 INFO | autoserv| self.run_once(*args, **dargs)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
13:27:40 INFO | autoserv| host.servo_install(image_url=image_url)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 994, in servo_install
13:27:40 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
13:27:40 INFO | autoserv| options, stdin, args, ignore_timeout)
13:27:40 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
13:27:40 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
13:27:40 INFO | autoserv| AutoservRunError: command execution error
13:27:40 INFO | autoserv| * Command:
13:27:40 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_J5AIFsssh-
13:27:40 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
13:27:40 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
13:27:40 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
13:27:40 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
13:27:40 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
13:27:40 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
13:27:40 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
13:27:40 INFO | autoserv| Exit status: 1
13:27:40 INFO | autoserv| Duration: 0.201654911041
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| stderr:
13:27:40 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
13:27:40 INFO | autoserv| 
13:27:40 INFO | autoserv| END FAIL      platform_InstallTestImage       platform_InstallTestImage       timestamp=1470248860    localtime=Aug 03 13:27:40
13:27:40 INFO | autoserv| Finished processing control file
13:27:40 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_EmHWA1ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpE4lZif -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
13:27:42 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_RTf9nrssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
13:27:43 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage/crashinfo.chromeos1-test-station.cros
13:27:46 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=chromeos1-test-station.cros, status=Running
-------------------------------------------------------------------------------------------------------
/tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage                           [  FAILED  ]
/tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage                             FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage/platform_InstallTestImage [  FAILED  ]
/tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage/platform_InstallTestImage   FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_Vho4_q/results-1-platform_InstallTestImage/platform_InstallTestImage   08/03 13:27:40.901 ERROR|        base_utils:0278| [stderr] Error: Source does not look like a removable device: /dev/sda

Comment 1 by jrbarnette@chromium.org, Aug 4 2016

Owner: jongkyuj@google.com
Status: Assigned (was: Untriaged)

Hmmm...

The servo is configured for lumpy:
    $ servo-stat chromeos1-test-station
    chromeos1-test-station ...ABCDEFG is up BOARD=lumpy CHROMEOS_RELEASE_VERSION=8638.0.0

But the DUT is running a samus-cheets build:
    $ ssh chromeos1-test-station cat /etc/lsb-release | grep RELEASE_BOARD
    CHROMEOS_RELEASE_BOARD=samus-cheets

... and crossystem agrees that it's samus hardware:
    $ ssh chromeos1-test-station crossystem | grep ro_fwid
    ro_fwid                = Google_Samus.6300.174.0        # Read-only firmware ID
    $ ssh chromeos1-test-station crossystem | grep hwid
    hwid                   = SAMUS CHEETS 5429              # Hardware ID

You'll need to fix the configuration problems, and then retry
to see if the problem persists.

Comment 2 by jongkyuj@google.com, Aug 4 2016

Reran the test using 8530.43.0 build with lumpy.

jongkyuj@jongkyuj0 ~/trunk/src/scripts $ test_that -b lumpy --autotest_dir=../third_party/autotest/files/ chromeos1-test-station.cros platform_InstallTestImage
INFO:root:Identity added: /tmp/test_that_results_WTBwJv/testing_rsa (/tmp/test_that_results_WTBwJv/testing_rsa)
09:44:31 INFO | Began logging to /tmp/test_that_results_WTBwJv
Adding labels [u'cros-version:ad_hoc_build', u'board:lumpy'] to host chromeos1-test-station.cros
11:44:32 INFO | Fetching suite for job named platform_InstallTestImage...
11:44:34 INFO | Scheduling suite for job named platform_InstallTestImage...
11:44:34 INFO | ... scheduled 1 job(s).
11:44:34 INFO | autoserv| Results placed in /tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage
11:44:34 INFO | autoserv| Logged pid 442 to /tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage/.autoserv_execute
11:44:34 INFO | autoserv| I am PID 442
11:44:34 INFO | autoserv| Not checking if job_repo_url contains autotest packages on ['chromeos1-test-station.cros']
11:44:34 INFO | autoserv| Processing control file
11:44:34 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_MnAYy_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpOlHfdR -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:44:36 INFO | autoserv| Pinging servo host at chromeos1-test-station-servo.cros
11:44:38 INFO | autoserv| Pinging host chromeos1-test-station-servo.cros
11:44:38 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_n0JD5cssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=180 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station-servo.cros'
11:44:39 INFO | autoserv| Host (ssh) chromeos1-test-station-servo.cros is alive
11:44:39 INFO | autoserv| Applying an update to the servo host, if necessary.
11:45:39 INFO | autoserv| Devserver call failed: "http://100.107.160.4:8082/check_health?", timeout: 60 seconds, Error: Call is timed out.
11:45:39 INFO | autoserv| servo host chromeos1-test-station-servo.cros does not require an update.
11:45:40 INFO | autoserv| servod is running, PID=460,479,480
11:45:40 INFO | autoserv| Setting usb_mux_oe1 to on
11:45:40 INFO | autoserv| Setting prtctl4_pwren to off
11:45:43 INFO | autoserv| Sanity checks pass on servo host chromeos1-test-station-servo.cros
11:45:43 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_IQ1EWVssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:45:44 INFO | autoserv| Can't find build to stage: DUT has no board attribute, cannot be repaired..
11:45:44 INFO | autoserv| Assuming this is an invocation from test_that with a pre-installed USB image
11:45:44 INFO | autoserv| START platform_InstallTestImage       platform_InstallTestImage       timestamp=1470329144    localtime=Aug 04 11:45:44
11:45:44 INFO | autoserv| Downloading image to USB, then booting from it. Usb boot timeout = 300
11:45:44 INFO | autoserv| Setting power_state to off
11:45:56 INFO | autoserv| Setting prtctl4_pwren to off
11:45:58 INFO | autoserv| Setting usb_mux_sel1 to servo_sees_usbkey
11:46:00 INFO | autoserv| Setting prtctl4_pwren to on
11:46:10 INFO | autoserv| Setting power_state to rec
11:46:23 INFO | autoserv| Setting prtctl4_pwren to off
11:46:25 INFO | autoserv| Setting usb_mux_sel1 to dut_sees_usbkey
11:46:27 INFO | autoserv| Setting prtctl4_pwren to on
11:46:28 INFO | autoserv| Resetting the TPM status
11:46:28 INFO | autoserv| chromeos-tpm-recovery is too old.
11:46:28 INFO | autoserv| Installing image through chromeos-install.
11:46:28 INFO | autoserv| [stderr] Error: Source does not look like a removable device: /dev/sda
11:46:28 INFO | autoserv| Autotest caught exception when running test:
11:46:28 INFO | autoserv| Traceback (most recent call last):
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 600, in _exec
11:46:28 INFO | autoserv| _call_test_function(self.execute, *p_args, **p_dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 810, in _call_test_function
11:46:28 INFO | autoserv| raise error.UnhandledTestFail(e)
11:46:28 INFO | autoserv| UnhandledTestFail: Unhandled AutoservRunError: command execution error
11:46:28 INFO | autoserv| * Command:
11:46:28 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_IQ1EWVssh-
11:46:28 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:46:28 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:46:28 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:46:28 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:46:28 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:46:28 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:46:28 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:46:28 INFO | autoserv| Exit status: 1
11:46:28 INFO | autoserv| Duration: 0.159061908722
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| stderr:
11:46:28 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:46:28 INFO | autoserv| Traceback (most recent call last):
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
11:46:28 INFO | autoserv| return func(*args, **dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
11:46:28 INFO | autoserv| dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
11:46:28 INFO | autoserv| postprocess_profiled_run, args, dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
11:46:28 INFO | autoserv| self.run_once(*args, **dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
11:46:28 INFO | autoserv| host.servo_install(image_url=image_url)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 994, in servo_install
11:46:28 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
11:46:28 INFO | autoserv| options, stdin, args, ignore_timeout)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
11:46:28 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
11:46:28 INFO | autoserv| AutoservRunError: command execution error
11:46:28 INFO | autoserv| * Command:
11:46:28 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_IQ1EWVssh-
11:46:28 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:46:28 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:46:28 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:46:28 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:46:28 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:46:28 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:46:28 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:46:28 INFO | autoserv| Exit status: 1
11:46:28 INFO | autoserv| Duration: 0.159061908722
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| stderr:
11:46:28 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| FAIL  platform_InstallTestImage       platform_InstallTestImage       timestamp=1470329188    localtime=Aug 04 11:46:28       Unhandled AutoservRunError: command execution error
11:46:28 INFO | autoserv| * Command:
11:46:28 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_IQ1EWVssh-
11:46:28 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:46:28 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:46:28 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:46:28 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:46:28 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:46:28 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:46:28 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:46:28 INFO | autoserv| Exit status: 1
11:46:28 INFO | autoserv| Duration: 0.159061908722
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| stderr:
11:46:28 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:46:28 INFO | autoserv| Traceback (most recent call last):
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
11:46:28 INFO | autoserv| return func(*args, **dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
11:46:28 INFO | autoserv| dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
11:46:28 INFO | autoserv| postprocess_profiled_run, args, dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
11:46:28 INFO | autoserv| self.run_once(*args, **dargs)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
11:46:28 INFO | autoserv| host.servo_install(image_url=image_url)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 994, in servo_install
11:46:28 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
11:46:28 INFO | autoserv| options, stdin, args, ignore_timeout)
11:46:28 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
11:46:28 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
11:46:28 INFO | autoserv| AutoservRunError: command execution error
11:46:28 INFO | autoserv| * Command:
11:46:28 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_IQ1EWVssh-
11:46:28 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:46:28 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:46:28 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:46:28 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:46:28 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:46:28 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:46:28 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:46:28 INFO | autoserv| Exit status: 1
11:46:28 INFO | autoserv| Duration: 0.159061908722
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| stderr:
11:46:28 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:46:28 INFO | autoserv| 
11:46:28 INFO | autoserv| END FAIL      platform_InstallTestImage       platform_InstallTestImage       timestamp=1470329188    localtime=Aug 04 11:46:28
11:46:28 INFO | autoserv| Finished processing control file
11:46:28 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_dQCITessh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpikEbjX -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:46:30 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_USnr2pssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:46:30 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage/crashinfo.chromeos1-test-station.cros
11:46:33 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=chromeos1-test-station.cros, status=Running
-------------------------------------------------------------------------------------------------------
/tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage                           [  FAILED  ]
/tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage                             FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage/platform_InstallTestImage [  FAILED  ]
/tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage/platform_InstallTestImage   FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_WTBwJv/results-1-platform_InstallTestImage/platform_InstallTestImage   08/04 11:46:28.260 ERROR|        base_utils:0278| [stderr] Error: Source does not look like a removable device: /dev/sda

Comment 3 by jrbarnette@chromium.org, Aug 4 2016

    $ servo-stat chromeos1-test-station
    chromeos1-test-station ...ABDEFG pwr_button is 'pwr_button:press' BOARD=lumpy CHROMEOS_RELEASE_VERSION=8638.0.0

The complaint about pwr_button commonly happens when the
ribbon cable isn't properly connected.  It can also indicate
other kinds of hardware problems, all of which boil down to
"a bad wire, or a bad connection".  The problem can't be fixed
with software.

Project Member

Comment 4 by sheriffbot@chromium.org, Aug 5 2016

Labels: Hotlist-Google

Comment 5 by jongkyuj@google.com, Aug 8 2016

Reran the test using new servo

jongkyuj@jongkyuj0 ~/trunk/src/scripts $ test_that -b lumpy --autotest_dir=../third_party/autotest/files/ chromeos1-test-station.cros platform_InstallTestImage
INFO:root:Identity added: /tmp/test_that_results_4SHPd2/testing_rsa (/tmp/test_that_results_4SHPd2/testing_rsa)
09:54:27 INFO | Began logging to /tmp/test_that_results_4SHPd2
Adding labels [u'cros-version:ad_hoc_build', u'board:lumpy'] to host chromeos1-test-station.cros
11:54:27 INFO | Fetching suite for job named platform_InstallTestImage...
11:54:29 INFO | Scheduling suite for job named platform_InstallTestImage...
11:54:29 INFO | ... scheduled 1 job(s).
11:54:30 INFO | autoserv| Results placed in /tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage
11:54:30 INFO | autoserv| Logged pid 1237 to /tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage/.autoserv_execute
11:54:30 INFO | autoserv| I am PID 1237
11:54:30 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_dmC0TAssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp9vViwP -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:54:32 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_VEM8Kbssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:54:33 INFO | autoserv| get_network_stats: at-start RXbytes 27336788 TXbytes 11727446
11:54:33 INFO | autoserv| Not checking if job_repo_url contains autotest packages on ['chromeos1-test-station.cros']
11:54:33 INFO | autoserv| Processing control file
11:54:33 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_fI3iesssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpuuLzjC -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:54:34 INFO | autoserv| Pinging host chromeos1-test-station-servo.cros
11:54:34 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_6mRc5sssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=180 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station-servo.cros'
11:54:35 INFO | autoserv| Host (ssh) chromeos1-test-station-servo.cros is alive
11:54:35 INFO | autoserv| Applying an update to the servo host, if necessary.
11:55:35 INFO | autoserv| Devserver call failed: "http://100.107.160.4:8082/check_health?", timeout: 60 seconds, Error: Call is timed out.
11:55:35 INFO | autoserv| [stderr] [0808/165523:INFO:update_engine_client.cc(469)] Querying Update Engine status...
11:55:35 INFO | autoserv| Using devserver url: http://172.17.40.22:8082/update/beaglebone_servo-release/R54-8638.0.0 to trigger update on servo host chromeos1-test-station-servo.cros, from 8647.0.0 to 8638.0.0
11:55:35 INFO | autoserv| Staging artifacts on devserver http://172.17.40.22:8082: build=beaglebone_servo-release/R54-8638.0.0, artifacts=['full_payload'], files=, archive_url=gs://chromeos-image-archive/beaglebone_servo-release/R54-8638.0.0
11:55:35 INFO | autoserv| Finished staging artifacts: build=beaglebone_servo-release/R54-8638.0.0, artifacts=['full_payload'], files=, archive_url=gs://chromeos-image-archive/beaglebone_servo-release/R54-8638.0.0
11:55:35 INFO | autoserv| Triggering update via: /usr/bin/update_engine_client --check_for_update --omaha_url=http://172.17.40.22:8082/update/beaglebone_servo-release/R54-8638.0.0
11:55:36 INFO | autoserv| [stderr] [0808/165524:INFO:update_engine_client.cc(449)] Initiating update check and install.
11:55:36 INFO | autoserv| [stderr] [0808/165524:INFO:update_engine_client.cc(469)] Querying Update Engine status...
11:55:36 INFO | autoserv| Triggered download and update passed for chromeos1-test-station-servo.cros, update engine currently in status UPDATE_STATUS_UPDATE_AVAILABLE
11:55:36 INFO | autoserv| servod is running, PID=454,469,470
11:55:37 INFO | autoserv| Setting usb_mux_oe1 to on
11:55:37 INFO | autoserv| Setting prtctl4_pwren to off
11:55:40 INFO | autoserv| Sanity checks pass on servo host chromeos1-test-station-servo.cros
11:55:40 INFO | autoserv| Can't find build to stage: DUT has no board attribute, cannot be repaired..
11:55:40 INFO | autoserv| Assuming this is an invocation from test_that with a pre-installed USB image
11:55:40 INFO | autoserv| START platform_InstallTestImage       platform_InstallTestImage       timestamp=1470675340    localtime=Aug 08 11:55:40
11:55:40 INFO | autoserv| Downloading image to USB, then booting from it. Usb boot timeout = 300
11:55:40 INFO | autoserv| Setting power_state to off
11:55:52 INFO | autoserv| Setting prtctl4_pwren to off
11:55:54 INFO | autoserv| Setting usb_mux_sel1 to servo_sees_usbkey
11:55:56 INFO | autoserv| Setting prtctl4_pwren to on
11:56:07 INFO | autoserv| Setting power_state to rec
11:56:20 INFO | autoserv| Setting prtctl4_pwren to off
11:56:22 INFO | autoserv| Setting usb_mux_sel1 to dut_sees_usbkey
11:56:24 INFO | autoserv| Setting prtctl4_pwren to on
11:56:25 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_l6dPoSssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:56:25 INFO | autoserv| Resetting the TPM status
11:56:25 INFO | autoserv| chromeos-tpm-recovery is too old.
11:56:25 INFO | autoserv| Installing image through chromeos-install.
11:56:25 INFO | autoserv| [stderr] Error: Source does not look like a removable device: /dev/sda
11:56:25 INFO | autoserv| Autotest caught exception when running test:
11:56:25 INFO | autoserv| Traceback (most recent call last):
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 600, in _exec
11:56:25 INFO | autoserv| _call_test_function(self.execute, *p_args, **p_dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 810, in _call_test_function
11:56:25 INFO | autoserv| raise error.UnhandledTestFail(e)
11:56:25 INFO | autoserv| UnhandledTestFail: Unhandled AutoservRunError: command execution error
11:56:25 INFO | autoserv| * Command:
11:56:25 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_l6dPoSssh-
11:56:25 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:56:25 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:56:25 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:56:25 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:56:25 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:56:25 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:56:25 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:56:25 INFO | autoserv| Exit status: 1
11:56:25 INFO | autoserv| Duration: 0.116894960403
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| stderr:
11:56:25 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:56:25 INFO | autoserv| Traceback (most recent call last):
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
11:56:25 INFO | autoserv| return func(*args, **dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
11:56:25 INFO | autoserv| dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
11:56:25 INFO | autoserv| postprocess_profiled_run, args, dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
11:56:25 INFO | autoserv| self.run_once(*args, **dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
11:56:25 INFO | autoserv| host.servo_install(image_url=image_url)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 1090, in servo_install
11:56:25 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
11:56:25 INFO | autoserv| options, stdin, args, ignore_timeout)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
11:56:25 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
11:56:25 INFO | autoserv| AutoservRunError: command execution error
11:56:25 INFO | autoserv| * Command:
11:56:25 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_l6dPoSssh-
11:56:25 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:56:25 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:56:25 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:56:25 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:56:25 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:56:25 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:56:25 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:56:25 INFO | autoserv| Exit status: 1
11:56:25 INFO | autoserv| Duration: 0.116894960403
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| stderr:
11:56:25 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| FAIL  platform_InstallTestImage       platform_InstallTestImage       timestamp=1470675385    localtime=Aug 08 11:56:25       Unhandled AutoservRunError: command execution error
11:56:25 INFO | autoserv| * Command:
11:56:25 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_l6dPoSssh-
11:56:25 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:56:25 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:56:25 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:56:25 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:56:25 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:56:25 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:56:25 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:56:25 INFO | autoserv| Exit status: 1
11:56:25 INFO | autoserv| Duration: 0.116894960403
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| stderr:
11:56:25 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:56:25 INFO | autoserv| Traceback (most recent call last):
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
11:56:25 INFO | autoserv| return func(*args, **dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
11:56:25 INFO | autoserv| dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
11:56:25 INFO | autoserv| postprocess_profiled_run, args, dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
11:56:25 INFO | autoserv| self.run_once(*args, **dargs)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
11:56:25 INFO | autoserv| host.servo_install(image_url=image_url)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 1090, in servo_install
11:56:25 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
11:56:25 INFO | autoserv| options, stdin, args, ignore_timeout)
11:56:25 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
11:56:25 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
11:56:25 INFO | autoserv| AutoservRunError: command execution error
11:56:25 INFO | autoserv| * Command:
11:56:25 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_l6dPoSssh-
11:56:25 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
11:56:25 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
11:56:25 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
11:56:25 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
11:56:25 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
11:56:25 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
11:56:25 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
11:56:25 INFO | autoserv| Exit status: 1
11:56:25 INFO | autoserv| Duration: 0.116894960403
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| stderr:
11:56:25 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
11:56:25 INFO | autoserv| 
11:56:25 INFO | autoserv| END FAIL      platform_InstallTestImage       platform_InstallTestImage       timestamp=1470675385    localtime=Aug 08 11:56:25
11:56:25 INFO | autoserv| Finished processing control file
11:56:25 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_5rkgFTssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpJFHFzz -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:56:27 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_6MWey5ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:56:27 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage/crashinfo.chromeos1-test-station.cros
11:56:27 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_lTzls1ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpIz6d31 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
11:56:29 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_lcgXYTssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
11:56:30 INFO | autoserv| get_network_stats: at-end RXbytes 27497269 TXbytes 11757604
11:56:35 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=chromeos1-test-station.cros, status=Running
-------------------------------------------------------------------------------------------------------
/tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage                           [  FAILED  ]
/tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage                             FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage/platform_InstallTestImage [  FAILED  ]
/tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage/platform_InstallTestImage   FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_4SHPd2/results-1-platform_InstallTestImage/platform_InstallTestImage   08/08 11:56:25.669 ERROR|        base_utils:0278| [stderr] Error: Source does not look like a removable device: /dev/sda
-------------------------------------------------------------------------------------------------------
Total PASS: 0/2 (0%)

Comment 6 by jrbarnette@chromium.org, Aug 8 2016

Please run servo-stat against the host, and report what it says.

Comment 7 by jongkyuj@google.com, Aug 11 2016

Ran from conductive test bed.

jongkyuj@jongkyuj0 ~/trunk/src/scripts $ test_that -b lumpy --autotest_dir=../third_party/autotest/files/ chromeos1-test-station.cros network_WiFi_VerifyRouter
INFO:root:Identity added: /tmp/test_that_results_OAPuAa/testing_rsa (/tmp/test_that_results_OAPuAa/testing_rsa)
10:28:44 INFO | Began logging to /tmp/test_that_results_OAPuAa
Adding labels [u'cros-version:ad_hoc_build', u'board:lumpy'] to host chromeos1-test-station.cros
12:28:45 INFO | Fetching suite for job named network_WiFi_VerifyRouter...
12:28:47 INFO | Scheduling suite for job named network_WiFi_VerifyRouter...
12:28:47 INFO | ... scheduled 1 job(s).
12:28:48 INFO | autoserv| Results placed in /tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter
12:28:48 INFO | autoserv| Logged pid 1867 to /tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/.autoserv_execute
12:28:48 INFO | autoserv| I am PID 1867
12:28:48 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_E5kyFxssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpjnk8jL -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:28:49 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp__LCFYCssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:28:50 INFO | autoserv| get_network_stats: at-start RXbytes 38351763 TXbytes 320734610
12:28:50 INFO | autoserv| Not checking if job_repo_url contains autotest packages on ['chromeos1-test-station.cros']
12:28:50 INFO | autoserv| Processing control file
12:28:50 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_mIGygissh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp_tnncw -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:28:52 INFO | autoserv| START network_WiFi_VerifyRouter       network_WiFi_VerifyRouter       timestamp=1470936532    localtime=Aug 11 12:28:52
12:28:52 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_KjfGcsssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpKapog0 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:28:54 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_vKtSXnssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:28:54 INFO | autoserv| Installing autotest on chromeos1-test-station.cros
12:28:54 INFO | autoserv| Using installation dir /tmp/sysinfo/autoserv-tTpNxC
12:28:55 INFO | autoserv| Could not install autotest using the packaging system: No repos to install an autotest client from. Trying other methods
12:28:56 INFO | autoserv| Installation of autotest completed from /mnt/host/source/src/third_party/autotest/files/client/
12:28:56 INFO | autoserv| Installing updated global_config.ini.
12:28:56 INFO | autoserv| Executing /tmp/sysinfo/autoserv-tTpNxC/bin/autotest /tmp/sysinfo/autoserv-tTpNxC/control phase 0
12:28:57 INFO | autoserv| Entered autotestd_monitor.
12:28:57 INFO | autoserv| Finished launching tail subprocesses.
12:28:57 INFO | autoserv| Finished waiting on autotestd to start.
12:28:58 INFO | autoserv| START ----    ----    timestamp=1470936538    localtime=Aug 11 12:28:58
12:28:59 INFO | autoserv| GOOD  ----    sysinfo.before  timestamp=1470936538    localtime=Aug 11 12:28:58
12:28:59 INFO | autoserv| END GOOD      ----    ----    timestamp=1470936538    localtime=Aug 11 12:28:58
12:28:59 INFO | autoserv| Got lock of exit_code_file.
12:28:59 INFO | autoserv| Released lock of exit_code_file and closed it.
12:29:01 INFO | autoserv| Killing child processes.
12:29:01 INFO | autoserv| Client complete
12:29:37 INFO | autoserv| Installing Pyshark
12:29:37 INFO | autoserv| Server-side packaging is not enabled. Install package ['tshark', 'python-dev', 'libxml2-dev', 'libxslt-dev', 'zlib1g-dev'] is skipped.
12:29:37 INFO | autoserv| SERVER: http://cautotest/afe/server/noauth/rpc/
12:29:37 INFO | autoserv| HEADERS: {'AUTHORIZATION': 'jongkyuj'}
12:29:37 INFO | autoserv| DEBUG: get_labels {'name__startswith': 'variant:', 'host__hostname__in': ['chromeos1-test-station.cros']}
12:29:37 INFO | autoserv| Running wifi test with commandline arguments: {}
12:29:38 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_mLQwlxssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:29:38 INFO | autoserv| Installing autotest on chromeos1-test-station.cros
12:29:38 INFO | autoserv| Using installation dir /usr/local/autotest
12:29:39 INFO | autoserv| Could not install autotest using the packaging system: No repos to install an autotest client from. Trying other methods
12:29:39 INFO | autoserv| Installation of autotest completed from /mnt/host/source/src/third_party/autotest/files/client/
12:29:39 INFO | autoserv| Installing updated global_config.ini.
12:29:39 INFO | autoserv| Waiting 60 seconds for XMLRPC server to start.
12:29:39 INFO | autoserv| <class 'socket.error'>([Errno 111] Connection refused)
12:29:39 INFO | autoserv| Retrying in 0.804748 seconds...
12:29:40 INFO | autoserv| XMLRPC server started successfully.
12:29:41 INFO | autoserv| client system capabilities: set(['tdls', 'ibss_supported', '5ghz'])
12:29:41 INFO | autoserv| Connecting to router at chromeos1-test-station-router.cros
12:29:42 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_GAMm9Qssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpZeNlcE -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station-router.cros'
12:29:44 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_N0M4Irssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station-router.cros'
12:29:48 INFO | autoserv| Ping command returned no output; stderr was ping: unknown host chromeos1-test-station-pcap.cros
12:29:48 INFO | autoserv| .
12:29:48 INFO | autoserv| Ping command returned no output; stderr was ping: unknown host chromeos1-test-station-attenuator.cros
12:29:48 INFO | autoserv| .
12:29:48 INFO | autoserv| ======= WiFi autotest setup complete. Starting test... =======
12:29:49 INFO | autoserv| Executing /tmp/sysinfo/autoserv-tTpNxC/bin/autotest /tmp/sysinfo/autoserv-tTpNxC/control phase 0
12:29:49 INFO | autoserv| Entered autotestd_monitor.
12:29:49 INFO | autoserv| Finished launching tail subprocesses.
12:29:49 INFO | autoserv| Finished waiting on autotestd to start.
12:29:51 INFO | autoserv| START ----    ----    timestamp=1470936591    localtime=Aug 11 12:29:51
12:29:51 INFO | autoserv| GOOD  ----    sysinfo.iteration.before        timestamp=1470936591    localtime=Aug 11 12:29:51
12:29:51 INFO | autoserv| END GOOD      ----    ----    timestamp=1470936591    localtime=Aug 11 12:29:51
12:29:52 INFO | autoserv| Got lock of exit_code_file.
12:29:52 INFO | autoserv| Released lock of exit_code_file and closed it.
12:29:54 INFO | autoserv| Killing child processes.
12:29:54 INFO | autoserv| Client complete
12:29:54 INFO | autoserv| router system capabilities: set(['vht', 'send_management_frame', '5ghz', 'multi_ap_same_band', 'multi_ap'])
12:30:00 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:00 INFO | autoserv| Starting hostapd on managed0(phy0) channel=6...
12:30:00 INFO | autoserv| Waiting for hostapd to startup.
12:30:02 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:02 INFO | autoserv| Starting up local server...
12:30:02 INFO | autoserv| AP configured.
12:30:02 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:03 INFO | autoserv| Starting hostapd on managed1(phy0) channel=6...
12:30:03 INFO | autoserv| Waiting for hostapd to startup.
12:30:03 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:03 INFO | autoserv| Starting up local server...
12:30:03 INFO | autoserv| AP configured.
12:30:03 INFO | autoserv| Connecting to AP with settings bitmap=3, ap_instance=0, channel=6.
12:30:14 INFO | autoserv| Finished connection attempt to VerifyRouter_a_bmaju_ch6 with times: discovery=3.98, association=3.61, configuration=3.01.
12:30:14 INFO | autoserv| Signal level for AP 0 with bitmap 3 is -21
12:30:14 INFO | autoserv| Connecting to AP with settings bitmap=3, ap_instance=1, channel=6.
12:30:18 INFO | autoserv| Finished connection attempt to VerifyRouter_b_4x1bq_ch6 with times: discovery=0.00, association=0.20, configuration=3.61.
12:30:18 INFO | autoserv| Signal level for AP 1 with bitmap 3 is -23
12:30:27 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:28 INFO | autoserv| Starting hostapd on managed0(phy0) channel=6...
12:30:28 INFO | autoserv| Waiting for hostapd to startup.
12:30:29 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:29 INFO | autoserv| Starting up local server...
12:30:30 INFO | autoserv| AP configured.
12:30:30 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:30 INFO | autoserv| Starting hostapd on managed1(phy0) channel=6...
12:30:30 INFO | autoserv| Waiting for hostapd to startup.
12:30:31 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:31 INFO | autoserv| Starting up local server...
12:30:31 INFO | autoserv| AP configured.
12:30:31 INFO | autoserv| Connecting to AP with settings bitmap=1, ap_instance=0, channel=6.
12:30:40 INFO | autoserv| Finished connection attempt to VerifyRouter_c_asnx4_ch6 with times: discovery=4.93, association=0.41, configuration=3.61.
12:30:40 INFO | autoserv| Signal level for AP 0 with bitmap 1 is -32
12:30:40 INFO | autoserv| Connecting to AP with settings bitmap=1, ap_instance=1, channel=6.
12:30:41 INFO | autoserv| Finished connection attempt to VerifyRouter_d_bgi8a_ch6 with times: discovery=0.00, association=0.21, configuration=0.41.
12:30:41 INFO | autoserv| Signal level for AP 1 with bitmap 1 is -27
12:30:50 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:51 INFO | autoserv| Starting hostapd on managed0(phy0) channel=6...
12:30:51 INFO | autoserv| Waiting for hostapd to startup.
12:30:52 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:52 INFO | autoserv| Starting up local server...
12:30:53 INFO | autoserv| AP configured.
12:30:53 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:30:53 INFO | autoserv| Starting hostapd on managed1(phy0) channel=6...
12:30:53 INFO | autoserv| Waiting for hostapd to startup.
12:30:53 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:30:53 INFO | autoserv| Starting up local server...
12:30:54 INFO | autoserv| AP configured.
12:30:54 INFO | autoserv| Connecting to AP with settings bitmap=2, ap_instance=0, channel=6.
12:31:03 INFO | autoserv| Finished connection attempt to VerifyRouter_e_au5xs_ch6 with times: discovery=5.25, association=0.21, configuration=3.41.
12:31:03 INFO | autoserv| Signal level for AP 0 with bitmap 2 is -16
12:31:03 INFO | autoserv| Connecting to AP with settings bitmap=2, ap_instance=1, channel=6.
12:31:03 INFO | autoserv| Finished connection attempt to VerifyRouter_f_y2pu8_ch6 with times: discovery=0.00, association=0.22, configuration=0.02.
12:31:03 INFO | autoserv| Signal level for AP 1 with bitmap 2 is -15
12:31:13 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:13 INFO | autoserv| Starting hostapd on managed2(phy1) channel=149...
12:31:13 INFO | autoserv| Waiting for hostapd to startup.
12:31:15 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:15 INFO | autoserv| Starting up local server...
12:31:15 INFO | autoserv| AP configured.
12:31:15 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:15 INFO | autoserv| Starting hostapd on managed3(phy1) channel=149...
12:31:15 INFO | autoserv| Waiting for hostapd to startup.
12:31:16 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:16 INFO | autoserv| Starting up local server...
12:31:16 INFO | autoserv| AP configured.
12:31:16 INFO | autoserv| Connecting to AP with settings bitmap=3, ap_instance=0, channel=149.
12:31:21 INFO | autoserv| Finished connection attempt to VerifyRouter_g_ae57d_ch149 with times: discovery=1.54, association=0.41, configuration=3.01.
12:31:21 INFO | autoserv| Signal level for AP 0 with bitmap 3 is -22
12:31:21 INFO | autoserv| Connecting to AP with settings bitmap=3, ap_instance=1, channel=149.
12:31:22 INFO | autoserv| Finished connection attempt to VerifyRouter_h_u4vuu_ch149 with times: discovery=0.00, association=0.40, configuration=0.61.
12:31:22 INFO | autoserv| Signal level for AP 1 with bitmap 3 is -23
12:31:32 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:32 INFO | autoserv| Starting hostapd on managed3(phy1) channel=149...
12:31:32 INFO | autoserv| Waiting for hostapd to startup.
12:31:34 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:34 INFO | autoserv| Starting up local server...
12:31:34 INFO | autoserv| AP configured.
12:31:34 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:35 INFO | autoserv| Starting hostapd on managed2(phy1) channel=149...
12:31:35 INFO | autoserv| Waiting for hostapd to startup.
12:31:35 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:35 INFO | autoserv| Starting up local server...
12:31:35 INFO | autoserv| AP configured.
12:31:35 INFO | autoserv| Connecting to AP with settings bitmap=1, ap_instance=0, channel=149.
12:31:40 INFO | autoserv| Finished connection attempt to VerifyRouter_i_kw8z6_ch149 with times: discovery=1.25, association=0.41, configuration=2.81.
12:31:40 INFO | autoserv| Signal level for AP 0 with bitmap 1 is -52
12:31:40 INFO | autoserv| Connecting to AP with settings bitmap=1, ap_instance=1, channel=149.
12:31:41 INFO | autoserv| Finished connection attempt to VerifyRouter_j_cphi8_ch149 with times: discovery=0.00, association=0.41, configuration=0.61.
12:31:41 INFO | autoserv| Signal level for AP 1 with bitmap 1 is -52
12:31:51 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:51 INFO | autoserv| Starting hostapd on managed3(phy1) channel=149...
12:31:51 INFO | autoserv| Waiting for hostapd to startup.
12:31:53 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:53 INFO | autoserv| Starting up local server...
12:31:53 INFO | autoserv| AP configured.
12:31:53 INFO | autoserv| Not enforcing pure N mode because Snow does not seem to support it...
12:31:54 INFO | autoserv| Starting hostapd on managed2(phy1) channel=149...
12:31:54 INFO | autoserv| Waiting for hostapd to startup.
12:31:54 INFO | autoserv| Beacon footer file does not exist.  Ignoring.
12:31:54 INFO | autoserv| Starting up local server...
12:31:54 INFO | autoserv| AP configured.
12:31:54 INFO | autoserv| Connecting to AP with settings bitmap=2, ap_instance=0, channel=149.
12:31:59 INFO | autoserv| Finished connection attempt to VerifyRouter_k_35dmu_ch149 with times: discovery=1.25, association=0.41, configuration=2.81.
12:31:59 INFO | autoserv| Signal level for AP 0 with bitmap 2 is -19
12:31:59 INFO | autoserv| Connecting to AP with settings bitmap=2, ap_instance=1, channel=149.
12:32:00 INFO | autoserv| Finished connection attempt to VerifyRouter_l_coi3v_ch149 with times: discovery=0.00, association=0.41, configuration=0.41.
12:32:00 INFO | autoserv| Signal level for AP 1 with bitmap 2 is -19
12:32:01 INFO | autoserv| Executing /tmp/sysinfo/autoserv-tTpNxC/bin/autotest /tmp/sysinfo/autoserv-tTpNxC/control phase 0
12:32:01 INFO | autoserv| Entered autotestd_monitor.
12:32:01 INFO | autoserv| Finished launching tail subprocesses.
12:32:01 INFO | autoserv| Finished waiting on autotestd to start.
12:32:03 INFO | autoserv| START ----    ----    timestamp=1470936723    localtime=Aug 11 12:32:03
12:32:03 INFO | autoserv| GOOD  ----    sysinfo.iteration.after timestamp=1470936723    localtime=Aug 11 12:32:03
12:32:03 INFO | autoserv| END GOOD      ----    ----    timestamp=1470936723    localtime=Aug 11 12:32:03
12:32:03 INFO | autoserv| Got lock of exit_code_file.
12:32:03 INFO | autoserv| Released lock of exit_code_file and closed it.
12:32:05 INFO | autoserv| Killing child processes.
12:32:06 INFO | autoserv| Client complete
12:32:10 INFO | autoserv| ======= WiFi autotest complete. Cleaning up... =======
12:32:13 INFO | autoserv| Waiting for RPC server "shill_xmlrpc_server" shutdown
12:32:13 INFO | autoserv| Shut down RPC server.
12:32:18 INFO | autoserv| Executing /tmp/sysinfo/autoserv-tTpNxC/bin/autotest /tmp/sysinfo/autoserv-tTpNxC/control phase 0
12:32:18 INFO | autoserv| Entered autotestd_monitor.
12:32:18 INFO | autoserv| Finished launching tail subprocesses.
12:32:18 INFO | autoserv| Finished waiting on autotestd to start.
12:32:20 INFO | autoserv| START ----    ----    timestamp=1470936740    localtime=Aug 11 12:32:20
12:32:21 INFO | autoserv| GOOD  ----    sysinfo.after   timestamp=1470936741    localtime=Aug 11 12:32:21
12:32:21 INFO | autoserv| END GOOD      ----    ----    timestamp=1470936741    localtime=Aug 11 12:32:21
12:32:22 INFO | autoserv| Got lock of exit_code_file.
12:32:22 INFO | autoserv| Released lock of exit_code_file and closed it.
12:32:24 INFO | autoserv| Killing child processes.
12:32:24 INFO | autoserv| Client complete
12:33:04 INFO | autoserv| GOOD  network_WiFi_VerifyRouter       network_WiFi_VerifyRouter       timestamp=1470936784    localtime=Aug 11 12:33:04       completed successfully
12:33:04 INFO | autoserv| END GOOD      network_WiFi_VerifyRouter       network_WiFi_VerifyRouter       timestamp=1470936784    localtime=Aug 11 12:33:04
12:33:04 INFO | autoserv| Finished processing control file
12:33:04 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_TVUtSKssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpeLG_kq -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:33:06 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_7MTQHVssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:33:06 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/crashinfo.chromeos1-test-station.cros
12:33:06 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_PuaLyxssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpc4rgVE -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:33:08 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_SFKeU3ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:33:09 INFO | autoserv| get_network_stats: at-end RXbytes 57168608 TXbytes 482652558
12:33:12 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=chromeos1-test-station.cros, status=Running
-------------------------------------------------------------------------------------------------------
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter                           [  PASSED  ]
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter [  PASSED  ]
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_1_ch_149                                                               -52
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_1_ch_6                                                                 -32
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_2_ch_149                                                               -19
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_2_ch_6                                                                 -16
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_3_ch_149                                                               -22
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_0_bm_3_ch_6                                                                 -21
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_1_ch_149                                                               -52
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_1_ch_6                                                                 -27
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_2_ch_149                                                               -19
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_2_ch_6                                                                 -15
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_3_ch_149                                                               -23
/tmp/test_that_results_OAPuAa/results-1-network_WiFi_VerifyRouter/network_WiFi_VerifyRouter   signal_for_ap_1_bm_3_ch_6                                                                 -23
-------------------------------------------------------------------------------------------------------
Total PASS: 2/2 (100%)

12:33:12 INFO | Finished running tests. Results can be found in /tmp/test_that_results_OAPuAa or /tmp/test_that_latest
(cr) ((763040e...)) jongkyuj@jongkyuj0 ~/trunk/src/scripts $ test_that -b lumpy --autotest_dir=../third_party/autotest/files/ chromeos1-test-station.cros platform_InstallTestImage
INFO:root:Identity added: /tmp/test_that_results_BCl4sy/testing_rsa (/tmp/test_that_results_BCl4sy/testing_rsa)
10:37:02 INFO | Began logging to /tmp/test_that_results_BCl4sy
Adding labels [u'cros-version:ad_hoc_build', u'board:lumpy'] to host chromeos1-test-station.cros
12:37:02 INFO | Fetching suite for job named platform_InstallTestImage...
12:37:04 INFO | Scheduling suite for job named platform_InstallTestImage...
12:37:04 INFO | ... scheduled 1 job(s).
12:37:04 INFO | autoserv| Results placed in /tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage
12:37:04 INFO | autoserv| Logged pid 2617 to /tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage/.autoserv_execute
12:37:04 INFO | autoserv| I am PID 2617
12:37:04 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_fI9mAossh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2Oa4wc -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:37:06 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_f2VSqBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:37:07 INFO | autoserv| get_network_stats: at-start RXbytes 58130103 TXbytes 482708270
12:37:07 INFO | autoserv| Not checking if job_repo_url contains autotest packages on ['chromeos1-test-station.cros']
12:37:07 INFO | autoserv| Processing control file
12:37:07 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_5OZZHJssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpDWoFmp -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:37:09 INFO | autoserv| Pinging host chromeos1-test-station-servo.cros
12:37:09 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_rIjxvQssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=180 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station-servo.cros'
12:37:09 INFO | autoserv| Host (ssh) chromeos1-test-station-servo.cros is alive
12:37:09 INFO | autoserv| Applying an update to the servo host, if necessary.
12:38:09 INFO | autoserv| Devserver call failed: "http://100.107.160.4:8082/check_health?", timeout: 60 seconds, Error: Call is timed out.
12:38:10 INFO | autoserv| [stderr] [0811/155218:INFO:update_engine_client.cc(469)] Querying Update Engine status...
12:38:10 INFO | autoserv| servo host chromeos1-test-station-servo.cros does not require an update.
12:38:10 INFO | autoserv| servod is running, PID=11200,11219,11220
12:38:10 INFO | autoserv| Setting usb_mux_oe1 to on
12:38:11 INFO | autoserv| Setting prtctl4_pwren to off
12:38:13 INFO | autoserv| Sanity checks pass on servo host chromeos1-test-station-servo.cros
12:38:14 INFO | autoserv| Can't find build to stage: DUT has no board attribute, cannot be repaired..
12:38:14 INFO | autoserv| Assuming this is an invocation from test_that with a pre-installed USB image
12:38:14 INFO | autoserv| START platform_InstallTestImage       platform_InstallTestImage       timestamp=1470937094    localtime=Aug 11 12:38:14
12:38:14 INFO | autoserv| Downloading image to USB, then booting from it. Usb boot timeout = 300
12:38:14 INFO | autoserv| Setting power_state to off
12:38:26 INFO | autoserv| Setting prtctl4_pwren to off
12:38:28 INFO | autoserv| Setting usb_mux_sel1 to servo_sees_usbkey
12:38:30 INFO | autoserv| Setting prtctl4_pwren to on
12:38:41 INFO | autoserv| Setting power_state to rec
12:38:53 INFO | autoserv| Setting prtctl4_pwren to off
12:38:55 INFO | autoserv| Setting usb_mux_sel1 to dut_sees_usbkey
12:38:57 INFO | autoserv| Setting prtctl4_pwren to on
12:38:58 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_tnw3jcssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:38:58 INFO | autoserv| Resetting the TPM status
12:38:58 INFO | autoserv| chromeos-tpm-recovery is too old.
12:38:58 INFO | autoserv| Installing image through chromeos-install.
12:38:58 INFO | autoserv| [stderr] Error: Source does not look like a removable device: /dev/sda
12:38:58 INFO | autoserv| Autotest caught exception when running test:
12:38:58 INFO | autoserv| Traceback (most recent call last):
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 600, in _exec
12:38:58 INFO | autoserv| _call_test_function(self.execute, *p_args, **p_dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 810, in _call_test_function
12:38:58 INFO | autoserv| raise error.UnhandledTestFail(e)
12:38:58 INFO | autoserv| UnhandledTestFail: Unhandled AutoservRunError: command execution error
12:38:58 INFO | autoserv| * Command:
12:38:58 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_tnw3jcssh-
12:38:58 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
12:38:58 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
12:38:58 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
12:38:58 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
12:38:58 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
12:38:58 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
12:38:58 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
12:38:58 INFO | autoserv| Exit status: 1
12:38:58 INFO | autoserv| Duration: 0.140867948532
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| stderr:
12:38:58 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
12:38:58 INFO | autoserv| Traceback (most recent call last):
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
12:38:58 INFO | autoserv| return func(*args, **dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
12:38:58 INFO | autoserv| dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
12:38:58 INFO | autoserv| postprocess_profiled_run, args, dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
12:38:58 INFO | autoserv| self.run_once(*args, **dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
12:38:58 INFO | autoserv| host.servo_install(image_url=image_url)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 1090, in servo_install
12:38:58 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
12:38:58 INFO | autoserv| options, stdin, args, ignore_timeout)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
12:38:58 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
12:38:58 INFO | autoserv| AutoservRunError: command execution error
12:38:58 INFO | autoserv| * Command:
12:38:58 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_tnw3jcssh-
12:38:58 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
12:38:58 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
12:38:58 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
12:38:58 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
12:38:58 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
12:38:58 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
12:38:58 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
12:38:58 INFO | autoserv| Exit status: 1
12:38:58 INFO | autoserv| Duration: 0.140867948532
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| stderr:
12:38:58 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| FAIL  platform_InstallTestImage       platform_InstallTestImage       timestamp=1470937138    localtime=Aug 11 12:38:58       Unhandled AutoservRunError: command execution error
12:38:58 INFO | autoserv| * Command:
12:38:58 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_tnw3jcssh-
12:38:58 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
12:38:58 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
12:38:58 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
12:38:58 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
12:38:58 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
12:38:58 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
12:38:58 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
12:38:58 INFO | autoserv| Exit status: 1
12:38:58 INFO | autoserv| Duration: 0.140867948532
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| stderr:
12:38:58 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
12:38:58 INFO | autoserv| Traceback (most recent call last):
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 804, in _call_test_function
12:38:58 INFO | autoserv| return func(*args, **dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 461, in execute
12:38:58 INFO | autoserv| dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry
12:38:58 INFO | autoserv| postprocess_profiled_run, args, dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 376, in _call_run_once
12:38:58 INFO | autoserv| self.run_once(*args, **dargs)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/platform_InstallTestImage/platform_InstallTestImage.py", line 18, in run_once
12:38:58 INFO | autoserv| host.servo_install(image_url=image_url)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/cros_host.py", line 1090, in servo_install
12:38:58 INFO | autoserv| self.run('chromeos-install --yes', timeout=install_timeout)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 190, in run
12:38:58 INFO | autoserv| options, stdin, args, ignore_timeout)
12:38:58 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/hosts/ssh_host.py", line 157, in _run
12:38:58 INFO | autoserv| raise error.AutoservRunError("command execution error", result)
12:38:58 INFO | autoserv| AutoservRunError: command execution error
12:38:58 INFO | autoserv| * Command:
12:38:58 INFO | autoserv| /usr/bin/ssh -a -x    -o ControlPath=/tmp/_autotmp_tnw3jcssh-
12:38:58 INFO | autoserv| master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null
12:38:58 INFO | autoserv| -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o
12:38:58 INFO | autoserv| ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22
12:38:58 INFO | autoserv| chromeos1-test-station.cros "export LIBC_FATAL_STDERR_=1; if type
12:38:58 INFO | autoserv| \"logger\" > /dev/null 2>&1; then logger -tag \"autotest\"
12:38:58 INFO | autoserv| \"server[stack::_call_run_once|run_once|servo_install] -> ssh_run
12:38:58 INFO | autoserv| (chromeos-install --yes)\";fi; chromeos-install --yes"
12:38:58 INFO | autoserv| Exit status: 1
12:38:58 INFO | autoserv| Duration: 0.140867948532
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| stderr:
12:38:58 INFO | autoserv| Error: Source does not look like a removable device: /dev/sda
12:38:58 INFO | autoserv| 
12:38:58 INFO | autoserv| END FAIL      platform_InstallTestImage       platform_InstallTestImage       timestamp=1470937138    localtime=Aug 11 12:38:58
12:38:58 INFO | autoserv| Finished processing control file
12:38:58 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_NssdTussh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp0pip_7 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:39:00 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_lqCYgSssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:39:01 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage/crashinfo.chromeos1-test-station.cros
12:39:01 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_98omi4ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpuYOcok -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 chromeos1-test-station.cros'
12:39:03 INFO | autoserv| Starting master ssh connection '/usr/bin/ssh -a -x   -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_aFsxb8ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 chromeos1-test-station.cros'
12:39:03 INFO | autoserv| get_network_stats: at-end RXbytes 58291019 TXbytes 482741893
12:39:08 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=chromeos1-test-station.cros, status=Running
-------------------------------------------------------------------------------------------------------
/tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage                           [  FAILED  ]
/tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage                             FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage/platform_InstallTestImage [  FAILED  ]
/tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage/platform_InstallTestImage   FAIL: Unhandled AutoservRunError: command execution error
/tmp/test_that_results_BCl4sy/results-1-platform_InstallTestImage/platform_InstallTestImage   08/11 12:38:58.901 ERROR|        base_utils:0278| [stderr] Error: Source does not look like a removable device: /dev/sda
-------------------------------------------------------------------------------------------------------
Total PASS: 0/2 (0%)

Comment 8 by zalcorn@chromium.org, Apr 6 2018

Components: Tests>Fails

►

Issue 634356 link

Issue metadata

Test "platform_InstallTestImage" fails with "Source does not look like a removable device" error with lumpy.

Issue description

Comment 1 by jrbarnette@chromium.org, Aug 4 2016

Comment 1 Deleted

Comment 2 by jongkyuj@google.com, Aug 4 2016

Comment 2 Deleted

Comment 3 by jrbarnette@chromium.org, Aug 4 2016

Comment 3 Deleted

Comment 4 by sheriffbot@chromium.org, Aug 5 2016

Comment 4 Deleted

Comment 5 by jongkyuj@google.com, Aug 8 2016

Comment 5 Deleted

Comment 6 by jrbarnette@chromium.org, Aug 8 2016

Comment 6 Deleted

Comment 7 by jongkyuj@google.com, Aug 11 2016

Comment 7 Deleted

Comment 8 by zalcorn@chromium.org, Apr 6 2018

Comment 8 Deleted

Sign in to add a comment