08/02 11:08:25 INFO | autoserv:0201| Results placed in /usr/local/autotest/results/457-debug_user/group0
08/02 11:08:25 DEBUG| base_job:0349| Persistent state global_properties.tag now set to '457-debug_user/group0'
08/02 11:08:25 DEBUG| base_job:0349| Persistent state global_properties.last_boot_tag now set to None
08/02 11:08:25 INFO |server_job:0534| Processing control file
08/02 11:08:25 INFO |server_job:0117| START netperf2 netperf2 timestamp=1343905705 localtime=Aug 02 11:08:25
08/02 11:08:25 INFO | netperf2:0008| running on test-client1 and test-client2
08/02 11:08:25 INFO | netperf2:0008|
08/02 11:08:25 DEBUG|base_utils:0077| Running '/usr/local/autotest/conmux/conmux-attach test-client1 echo 2> /dev/null'
08/02 11:08:25 DEBUG| ssh_host:0114| Running (ssh) 'ls /var/log/kern.log'
08/02 11:08:25 INFO |abstract_s:0618| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client1'
08/02 11:08:25 DEBUG|base_utils:0077| Running '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client1'
08/02 11:08:25 DEBUG|base_utils:0114| [stderr] Warning: Permanently added 'test-client1,192.168.254.11' (ECDSA) to the list of known hosts.
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /var/log/kern.log
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /var/tmp'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /var/tmp/autoserv-XXXXXX'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /var/tmp/autoserv-TgXFIh
08/02 11:08:26 INFO |logfile_mo:0052| Launching followfiles on target: test-client1, /var/tmp/autoserv-TgXFIh, ['/var/log/kern.log']
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/bin/python[0-9]'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /usr/bin/python2
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /usr/bin/python2.7
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /tmp'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /tmp/autoserv-XXXXXX'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /tmp/autoserv-57JcML
08/02 11:08:26 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'rsync --version'
08/02 11:08:26 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:26 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/server/hosts/monitors root@test-client1:"/tmp/autoserv-57JcML"'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'test -f /var/log/messages'
08/02 11:08:26 DEBUG| remote:0185| Found remote path /var/log/messages
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /var/tmp/messages.autotest_start'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'cp /var/log/messages /var/tmp/messages.autotest_start'
08/02 11:08:26 DEBUG|base_utils:0077| Running '/usr/local/autotest/conmux/conmux-attach test-client2 echo 2> /dev/null'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'ls /var/log/kern.log'
08/02 11:08:26 INFO |abstract_s:0618| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client2'
08/02 11:08:26 DEBUG|base_utils:0077| Running '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client2'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /var/log/kern.log
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /var/tmp'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /var/tmp/autoserv-XXXXXX'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /var/tmp/autoserv-pk2k6t
08/02 11:08:26 INFO |logfile_mo:0052| Launching followfiles on target: test-client2, /var/tmp/autoserv-pk2k6t, ['/var/log/kern.log']
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/bin/python[0-9]'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /usr/bin/python2
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /usr/bin/python2.7
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /tmp'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /tmp/autoserv-XXXXXX'
08/02 11:08:26 DEBUG|base_utils:0114| [stdout] /tmp/autoserv-vugbtn
08/02 11:08:26 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'rsync --version'
08/02 11:08:26 INFO |abstract_s:0117| XMARKWA make_rsync_cmd
08/02 11:08:26 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/server/hosts/monitors root@test-client2:"/tmp/autoserv-vugbtn"'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'test -f /var/log/messages'
08/02 11:08:26 DEBUG| remote:0185| Found remote path /var/log/messages
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /var/tmp/messages.autotest_start'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) 'cp /var/log/messages /var/tmp/messages.autotest_start'
08/02 11:08:26 DEBUG| ssh_host:0114| Running (ssh) '/sbin/iptables -L'
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain INPUT (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG|base_utils:0114| [stdout]
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain FORWARD (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG|base_utils:0114| [stdout]
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain OUTPUT (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables-save > /tmp/iptable-rules'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P INPUT ACCEPT'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P FORWARD ACCEPT'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P OUTPUT ACCEPT'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) '/sbin/iptables -L'
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain INPUT (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG|base_utils:0114| [stdout]
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain FORWARD (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG|base_utils:0114| [stdout]
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Chain OUTPUT (policy ACCEPT)
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables-save > /tmp/iptable-rules'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P INPUT ACCEPT'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P FORWARD ACCEPT'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'iptables -P OUTPUT ACCEPT'
08/02 11:08:27 DEBUG|base_utils:0077| Running 'tools/make_clean'
08/02 11:08:27 DEBUG|base_utils:0077| Running 'tools/make_clean'
08/02 11:08:27 DEBUG|base_utils:0114| [stderr] rm: cannot remove network_EthernetStressPlug/src': Permission denied 08/02 11:08:27 DEBUG|base_utils:0114| [stderr] rm: cannot remove
tmp/.testlock': Permission denied
08/02 11:08:27 DEBUG|base_utils:0114| [stderr] rm: cannot remove network_EthernetStressPlug/src': Permission denied 08/02 11:08:27 DEBUG|base_utils:0114| [stderr] rm: cannot remove
tmp/.testlock': Permission denied
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning rmaptest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning disktest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning ipv6connect test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning signaltest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning rmaptest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning iosched_bugs test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning tracing_microbenchmark test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning disktest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning synctest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning ipv6connect test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning cyclictest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning signaltest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning rtc test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning iosched_bugs test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning aio_dio_bugs test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning tracing_microbenchmark test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning hackbench test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning synctest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning monotonic_time test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning cyclictest test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning tsc test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning rtc test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning aio_dio_bugs test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning hackbench test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning monotonic_time test dir
08/02 11:08:27 DEBUG|base_utils:0114| [stdout] Cleaning tsc test dir
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:08:27 DEBUG|abstract_s:0458| Host test-client1 is now up
08/02 11:08:27 INFO |autotest_r:0224| Installing autotest on test-client1
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'test -x /usr/local/autotest/autotest'
08/02 11:08:27 DEBUG|abstract_s:0458| Host test-client2 is now up
08/02 11:08:27 INFO |autotest_r:0224| Installing autotest on test-client2
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'test -x /usr/local/autotest/autotest'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'test -w /usr/local/autotest'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'test -w /usr/local/autotest'
08/02 11:08:27 DEBUG|autotest_r:0073| Found existing autodir at /usr/local/autotest
08/02 11:08:27 INFO |autotest_r:0229| Using installation dir /usr/local/autotest
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest'
08/02 11:08:27 DEBUG|autotest_r:0073| Found existing autodir at /usr/local/autotest
08/02 11:08:27 INFO |autotest_r:0229| Using installation dir /usr/local/autotest
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'rm -rf /usr/local/autotest/results/'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'rm -rf /usr/local/autotest/results/'
08/02 11:08:27 INFO |autotest_r:0249| Could not install autotest using the packaging system: No repos to install an autotest client from. Trying other methods
08/02 11:08:27 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:27 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:27 DEBUG|base_utils:0077| Running 'rsync -L --delete --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/client/tmp /usr/local/autotest/client/setup.py /usr/local/autotest/client/fsdev_disks.py /usr/local/autotest/client/job_unittest.py /usr/local/autotest/client/utils.pyc /usr/local/autotest/client/package.py /usr/local/autotest/client/harness_standalone.py /usr/local/autotest/client/local_host_unittest.py /usr/local/autotest/client/optparser.py /usr/local/autotest/client/os_dep.pyc /usr/local/autotest/client/kernel.pyc /usr/local/autotest/client/job.py /usr/local/autotest/client/common.pyc /usr/local/autotest/client/kernelexpand.py /usr/local/autotest/client/kernel.py /usr/local/autotest/client/setup_job.pyc /usr/local/autotest/client/autotest_local.py /usr/local/autotest/client/autotest_local.pyc /usr/local/autotest/client/fsinfo_unittest.py /usr/local/autotest/client/fsinfo.py /usr/local/autotest/client/base_utils.pyc /usr/local/autotest/client/local_host.py /usr/local/autotest/client/pyudev-0.15 /usr/local/autotest/client/client_logging_config.py /usr/local/autotest/client/job.pyc /usr/local/autotest/client/setup_modules.pyc /usr/local/autotest/client/os_dep.py /usr/local/autotest/client/kvm_control.py /usr/local/autotest/client/harness_autoserv.py /usr/local/autotest/client/setup_job_unittest.py /usr/local/autotest/client/cmdparser.py /usr/local/autotest/client/partition.py /usr/local/autotest/client/package_unittest.py /usr/local/autotest/client/autotestd_monitor /usr/local/autotest/client/base_utils.py /usr/local/autotest/client/partition.pyc /usr/local/autotest/client/harness_ABAT.py /usr/local/autotest/client/fsdev_mgr.py /usr/local/autotest/client/xen.pyc /usr/local/autotest/client/harness_unittest.py /usr/local/autotest/client/xen.py /usr/local/autotest/client/autotest_client /usr/local/autotest/client/results /usr/local/autotest/client/kernelexpand_unittest.py /usr/local/autotest/client/kernel_versions.py /usr/local/autotest/client/utils.py /usr/local/autotest/client/config.py /usr/local/autotest/client/kernel_versions_unittest.py /usr/local/autotest/client/client_logging_config.pyc /usr/local/autotest/client/base_sysinfo.pyc /usr/local/autotest/client/cmdparser.pyc /usr/local/autotest/client/test_config.py /usr/local/autotest/client/boottool.pyc /usr/local/autotest/client/partition_unittest.py /usr/local/autotest/client/local_host.pyc /usr/local/autotest/client/profiler.py /usr/local/autotest/client/net /usr/local/autotest/client/test.py /usr/local/autotest/client/parallel.pyc /usr/local/autotest/client/kernel_unittest.py /usr/local/autotest/client/harness.pyc /usr/local/autotest/client/LICENSE /usr/local/autotest/client/optparser.pyc /usr/local/autotest/client/TODO /usr/local/autotest/client/sysinfo.py /usr/local/autotest/client/harness_standalone.pyc /usr/local/autotest/client/harness_simple.py /usr/local/autotest/client/kernel_config.pyc /usr/local/autotest/client/setup_job.py /usr/local/autotest/client/virt /usr/local/autotest/client/fsdev_disks_unittest.py /usr/local/autotest/client/init.py /usr/local/autotest/client/common.py /usr/local/autotest/client/autotest /usr/local/autotest/client/harness.py /usr/local/autotest/client/cpuset.py /usr/local/autotest/client/tools /usr/local/autotest/client/init.pyc /usr/local/autotest/client/base_sysinfo.py /usr/local/autotest/client/autotestd /usr/local/autotest/client/setup_modules.py /usr/local/autotest/client/kernel_config.py /usr/local/autotest/client/package.pyc /usr/local/autotest/client/config /usr/local/autotest/client/test.pyc /usr/local/autotest/client/kernelexpand.pyc /usr/local/autotest/client/samples /usr/local/autotest/client/parallel.py /usr/local/autotest/client/autotest-local /usr/local/autotest/client/shared /usr/local/autotest/client/setup_modules_unittest.py /usr/local/autotest/client/sysinfo.pyc /usr/local/autotest/client/config.pyc /usr/local/autotest/client/deps/grubby/grubby-8.15.tar.bz2 root@test-client1:"/usr/local/autotest"'
08/02 11:08:27 INFO |autotest_r:0249| Could not install autotest using the packaging system: No repos to install an autotest client from. Trying other methods
08/02 11:08:27 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:27 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:27 DEBUG|base_utils:0077| Running 'rsync -L --delete --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/client/tmp /usr/local/autotest/client/setup.py /usr/local/autotest/client/fsdev_disks.py /usr/local/autotest/client/job_unittest.py /usr/local/autotest/client/utils.pyc /usr/local/autotest/client/package.py /usr/local/autotest/client/harness_standalone.py /usr/local/autotest/client/local_host_unittest.py /usr/local/autotest/client/optparser.py /usr/local/autotest/client/os_dep.pyc /usr/local/autotest/client/kernel.pyc /usr/local/autotest/client/job.py /usr/local/autotest/client/common.pyc /usr/local/autotest/client/kernelexpand.py /usr/local/autotest/client/kernel.py /usr/local/autotest/client/setup_job.pyc /usr/local/autotest/client/autotest_local.py /usr/local/autotest/client/autotest_local.pyc /usr/local/autotest/client/fsinfo_unittest.py /usr/local/autotest/client/fsinfo.py /usr/local/autotest/client/base_utils.pyc /usr/local/autotest/client/local_host.py /usr/local/autotest/client/pyudev-0.15 /usr/local/autotest/client/client_logging_config.py /usr/local/autotest/client/job.pyc /usr/local/autotest/client/setup_modules.pyc /usr/local/autotest/client/os_dep.py /usr/local/autotest/client/kvm_control.py /usr/local/autotest/client/harness_autoserv.py /usr/local/autotest/client/setup_job_unittest.py /usr/local/autotest/client/cmdparser.py /usr/local/autotest/client/partition.py /usr/local/autotest/client/package_unittest.py /usr/local/autotest/client/autotestd_monitor /usr/local/autotest/client/base_utils.py /usr/local/autotest/client/partition.pyc /usr/local/autotest/client/harness_ABAT.py /usr/local/autotest/client/fsdev_mgr.py /usr/local/autotest/client/xen.pyc /usr/local/autotest/client/harness_unittest.py /usr/local/autotest/client/xen.py /usr/local/autotest/client/autotest_client /usr/local/autotest/client/results /usr/local/autotest/client/kernelexpand_unittest.py /usr/local/autotest/client/kernel_versions.py /usr/local/autotest/client/utils.py /usr/local/autotest/client/config.py /usr/local/autotest/client/kernel_versions_unittest.py /usr/local/autotest/client/client_logging_config.pyc /usr/local/autotest/client/base_sysinfo.pyc /usr/local/autotest/client/cmdparser.pyc /usr/local/autotest/client/test_config.py /usr/local/autotest/client/boottool.pyc /usr/local/autotest/client/partition_unittest.py /usr/local/autotest/client/local_host.pyc /usr/local/autotest/client/profiler.py /usr/local/autotest/client/net /usr/local/autotest/client/test.py /usr/local/autotest/client/parallel.pyc /usr/local/autotest/client/kernel_unittest.py /usr/local/autotest/client/harness.pyc /usr/local/autotest/client/LICENSE /usr/local/autotest/client/optparser.pyc /usr/local/autotest/client/TODO /usr/local/autotest/client/sysinfo.py /usr/local/autotest/client/harness_standalone.pyc /usr/local/autotest/client/harness_simple.py /usr/local/autotest/client/kernel_config.pyc /usr/local/autotest/client/setup_job.py /usr/local/autotest/client/virt /usr/local/autotest/client/fsdev_disks_unittest.py /usr/local/autotest/client/init.py /usr/local/autotest/client/common.py /usr/local/autotest/client/autotest /usr/local/autotest/client/harness.py /usr/local/autotest/client/cpuset.py /usr/local/autotest/client/tools /usr/local/autotest/client/init.pyc /usr/local/autotest/client/base_sysinfo.py /usr/local/autotest/client/autotestd /usr/local/autotest/client/setup_modules.py /usr/local/autotest/client/kernel_config.py /usr/local/autotest/client/package.pyc /usr/local/autotest/client/config /usr/local/autotest/client/test.pyc /usr/local/autotest/client/kernelexpand.pyc /usr/local/autotest/client/samples /usr/local/autotest/client/parallel.py /usr/local/autotest/client/autotest-local /usr/local/autotest/client/shared /usr/local/autotest/client/setup_modules_unittest.py /usr/local/autotest/client/sysinfo.pyc /usr/local/autotest/client/config.pyc /usr/local/autotest/client/deps/grubby/grubby-8.15.tar.bz2 root@test-client2:"/usr/local/autotest"'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest/profilers'
08/02 11:08:27 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:27 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:27 DEBUG|base_utils:0077| Running 'rsync -L --delete --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/client/profilers/init.py root@test-client1:"/usr/local/autotest/profilers"'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p '/usr/local/autotest/site_tests';touch '/usr/local/autotest/site_tests'/init.py;mkdir -p '/usr/local/autotest/tests';touch '/usr/local/autotest/tests'/init.py;mkdir -p '/usr/local/autotest/deps';touch '/usr/local/autotest/deps'/init.py'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest/tmp'
08/02 11:08:27 INFO |autotest_r:0262| Installation of autotest completed
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:08:27 DEBUG|abstract_s:0458| Host test-client1 is now up
08/02 11:08:27 DEBUG|autotest_r:0065| Using existing host autodir: /usr/local/autotest
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/local/autotest/autotest > /dev/null 2>&1'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tmp'
08/02 11:08:27 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tmp: not mounted
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tests/download'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest/profilers'
08/02 11:08:27 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tests/download: not mounted
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/local/autotest/autotest > /dev/null 2>&1'
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tmp'
08/02 11:08:27 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:27 INFO |abstract_s:0117| XMARKWA make_rsync_cmd
08/02 11:08:27 DEBUG|base_utils:0077| Running 'rsync -L --delete --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/client/profilers/init.py root@test-client2:"/usr/local/autotest/profilers"'
08/02 11:08:27 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tmp: not mounted
08/02 11:08:27 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tests/download'
08/02 11:08:28 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tests/download: not mounted
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /usr/local/autotest/control.autoserv;rm -f /usr/local/autotest/tmp/control.autoserv.state;rm -f /usr/local/autotest/control;rm -f /usr/local/autotest/tmp/control.state'
08/02 11:08:28 DEBUG| base_job:0349| Persistent state client.sysinfo now set to {'test': set([sysinfo.command('dmesg -c', 'dmesg', False), sysinfo.command('df -mP', 'df', False)]), 'boot': set([sysinfo.command('gcc --version', 'gcc--version', False), sysinfo.command('ld --version', 'ld--version', False), sysinfo.logfile('/proc/partitions', 'partitions', False), sysinfo.logfile('/proc/interrupts', 'interrupts', False), sysinfo.command('hostname', 'hostname', False), sysinfo.command('mount', 'mount', False), sysinfo.logfile('/proc/cmdline', 'cmdline', True), sysinfo.logfile('/proc/version', 'version', False), sysinfo.command('lspci -vvn', 'lspci-vvn', False), sysinfo.logfile('/proc/slabinfo', 'slabinfo', False), sysinfo.logfile('/proc/modules', 'modules', False), sysinfo.logfile('/proc/mounts', 'proc_mounts', False), sysinfo.logfile('/proc/cpuinfo', 'cpuinfo', False), sysinfo.command('uptime', 'uptime', False), sysinfo.logfile('/proc/pci', 'pci', False), sysinfo.logfile('/proc/meminfo', 'meminfo', False), sysinfo.command('uname -a', 'uname', True)])}
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/tmpsjKk2S root@test-client1:"/usr/local/autotest/tmp/control.autoserv.init.state"'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p '/usr/local/autotest/site_tests';touch '/usr/local/autotest/site_tests'/init.py;mkdir -p '/usr/local/autotest/tests';touch '/usr/local/autotest/tests'/init.py;mkdir -p '/usr/local/autotest/deps';touch '/usr/local/autotest/deps'/init.py'
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /tmp/autoserv-PD15m7/tmpagBt6q root@test-client1:"/usr/local/autotest/control.autoserv"'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /usr/local/autotest/tmp'
08/02 11:08:28 INFO |autotest_r:0262| Installation of autotest completed
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:08:28 DEBUG|abstract_s:0458| Host test-client2 is now up
08/02 11:08:28 DEBUG|autotest_r:0065| Using existing host autodir: /usr/local/autotest
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/local/autotest/autotest > /dev/null 2>&1'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tmp'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; else echo 'no boot_id available'; fi'
08/02 11:08:28 DEBUG|base_utils:0114| [stdout] 07f6f514-affe-4104-a182-6c81f91e20b6
08/02 11:08:28 INFO |autotest_r:0707| Executing /usr/local/autotest/autotest /usr/local/autotest/control phase 0
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /tmp'
08/02 11:08:28 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tmp: not mounted
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tests/download'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /tmp/autoserv-XXXXXX'
08/02 11:08:28 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tests/download: not found
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'ls /usr/local/autotest/autotest > /dev/null 2>&1'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tmp'
08/02 11:08:28 DEBUG|base_utils:0114| [stdout] /tmp/autoserv-kb9Rhe
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/tmpC_WFZN root@test-client1:"/usr/local/autotest/global_config.ini"'
08/02 11:08:28 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tmp: not mounted
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'umount /usr/local/autotest/tests/download'
08/02 11:08:28 DEBUG|base_utils:0114| [stderr] umount: /usr/local/autotest/tests/download: not found
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /usr/local/autotest/control.autoserv;rm -f /usr/local/autotest/tmp/control.autoserv.state;rm -f /usr/local/autotest/control;rm -f /usr/local/autotest/tmp/control.state'
08/02 11:08:28 DEBUG| base_job:0349| Persistent state client.sysinfo now set to {'test': set([sysinfo.command('dmesg -c', 'dmesg', False), sysinfo.command('df -mP', 'df', False)]), 'boot': set([sysinfo.command('gcc --version', 'gcc--version', False), sysinfo.command('ld --version', 'ld--version', False), sysinfo.logfile('/proc/partitions', 'partitions', False), sysinfo.logfile('/proc/interrupts', 'interrupts', False), sysinfo.command('hostname', 'hostname', False), sysinfo.command('mount', 'mount', False), sysinfo.logfile('/proc/cmdline', 'cmdline', True), sysinfo.logfile('/proc/version', 'version', False), sysinfo.command('lspci -vvn', 'lspci-vvn', False), sysinfo.logfile('/proc/slabinfo', 'slabinfo', False), sysinfo.logfile('/proc/modules', 'modules', False), sysinfo.logfile('/proc/mounts', 'proc_mounts', False), sysinfo.logfile('/proc/cpuinfo', 'cpuinfo', False), sysinfo.command('uptime', 'uptime', False), sysinfo.logfile('/proc/pci', 'pci', False), sysinfo.logfile('/proc/meminfo', 'meminfo', False), sysinfo.command('uname -a', 'uname', True)])}
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/tmpXLkxvl root@test-client2:"/usr/local/autotest/tmp/control.autoserv.init.state"'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'nohup /usr/local/autotest/autotestd /tmp/autoserv-kb9Rhe -H autoserv --verbose --hostname=test-client1 --user=debug_user /usr/local/autotest/control.autoserv >/dev/null 2>/dev/null &'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) '/usr/local/autotest/autotestd_monitor /tmp/autoserv-kb9Rhe 0 0'
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /tmp/autoserv-SJhcnl/tmpg_mx8s root@test-client2:"/usr/local/autotest/control.autoserv"'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; else echo 'no boot_id available'; fi'
08/02 11:08:28 DEBUG|base_utils:0114| [stdout] ab083a36-5d60-485b-b808-8d21d54473a5
08/02 11:08:28 INFO |autotest_r:0707| Executing /usr/local/autotest/autotest /usr/local/autotest/control phase 0
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mkdir -p /tmp'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'mktemp -d /tmp/autoserv-XXXXXX'
08/02 11:08:28 DEBUG|base_utils:0114| [stdout] /tmp/autoserv-atrOMr
08/02 11:08:28 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:28 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:28 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/tmp5twp7J root@test-client2:"/usr/local/autotest/global_config.ini"'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) 'nohup /usr/local/autotest/autotestd /tmp/autoserv-atrOMr -H autoserv --verbose --hostname=test-client2 --user=debug_user /usr/local/autotest/control.autoserv >/dev/null 2>/dev/null &'
08/02 11:08:28 DEBUG| ssh_host:0114| Running (ssh) '/usr/local/autotest/autotestd_monitor /tmp/autoserv-atrOMr 0 0'
08/02 11:08:29 INFO |server_job:0117| START ---- ---- timestamp=1343905709 localtime=Aug 02 11:08:29
08/02 11:08:29 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_12n74Wharness-fifo/autoserv.fifo'
08/02 11:08:29 INFO |server_job:0117| START netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905709 localtime=Aug 02 11:08:29
08/02 11:08:29 INFO |server_job:0117| START ---- ---- timestamp=1343905709 localtime=Aug 02 11:08:29
08/02 11:08:29 INFO |autotest_r:1030| Bundling /usr/local/autotest/client/tests/netperf2 into test-netperf2.tar.bz2
08/02 11:08:29 DEBUG|base_utils:0077| Running 'tar -cf /usr/local/autotest/tmp/tmp/_autotmp_ZuY462autoserv-packager/test-netperf2.tar.bz2.tmp -C /usr/local/autotest/client/tests/netperf2 -j .'
08/02 11:08:29 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_I53cMEharness-fifo/autoserv.fifo'
08/02 11:08:29 INFO |server_job:0117| START netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905709 localtime=Aug 02 11:08:29
08/02 11:08:29 INFO |autotest_r:1030| Bundling /usr/local/autotest/client/tests/netperf2 into test-netperf2.tar.bz2
08/02 11:08:29 DEBUG|base_utils:0077| Running 'tar -cf /usr/local/autotest/tmp/tmp/_autotmp_Q1PuQ_autoserv-packager/test-netperf2.tar.bz2.tmp -C /usr/local/autotest/client/tests/netperf2 -j .'
08/02 11:08:30 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:30 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:30 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/_autotmp_ZuY462autoserv-packager/test-netperf2.tar.bz2 root@test-client1:"/usr/local/autotest/tmp/packages/test-netperf2.tar.bz2"'
08/02 11:08:30 INFO |abstract_s:0345| XMARKWA send_file
08/02 11:08:30 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:08:30 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az /usr/local/autotest/tmp/tmp/_autotmp_Q1PuQ_autoserv-packager/test-netperf2.tar.bz2 root@test-client2:"/usr/local/autotest/tmp/packages/test-netperf2.tar.bz2"'
08/02 11:08:30 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_xFyDQnharness-fifo/autoserv.fifo'
08/02 11:08:30 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_Fsz9Urharness-fifo/autoserv.fifo'
08/02 11:08:31 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_OUjZZQharness-fifo/autoserv.fifo'
08/02 11:08:31 DEBUG| ssh_host:0114| Running (ssh) 'echo B > /usr/local/autotest/tmp/tmp/_autotmp_vh0e9Pharness-fifo/autoserv.fifo'
08/02 11:09:15 INFO |server_job:0117| GOOD netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905755 localtime=Aug 02 11:09:15 completed successfully
08/02 11:09:15 INFO |server_job:0117| GOOD netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905754 localtime=Aug 02 11:09:14 completed successfully
08/02 11:09:15 INFO |server_job:0117| END GOOD netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905755 localtime=Aug 02 11:09:15
08/02 11:09:15 INFO |server_job:0117| END GOOD netperf2.TCP_STREAM netperf2.TCP_STREAM timestamp=1343905754 localtime=Aug 02 11:09:14
08/02 11:09:15 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:09:15 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:09:15 DEBUG|abstract_s:0458| Host test-client2 is now up
08/02 11:09:15 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:09:15 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:09:15 DEBUG|base_utils:0077| Running 'rsync --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client2:"/usr/local/autotest/results/default/" /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client2'
08/02 11:09:15 DEBUG|abstract_s:0458| Host test-client1 is now up
08/02 11:09:15 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:09:15 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:09:15 DEBUG|base_utils:0077| Running 'rsync --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client1:"/usr/local/autotest/results/default/" /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client1'
08/02 11:09:15 DEBUG| ssh_host:0114| Running (ssh) 'echo A > /usr/local/autotest/tmp/tmp/_autotmp_ZpyZREharness-fifo/autoserv.fifo'
08/02 11:09:15 DEBUG| ssh_host:0114| Running (ssh) 'echo A > /usr/local/autotest/tmp/tmp/_autotmp_hkbDeeharness-fifo/autoserv.fifo'
08/02 11:09:15 INFO |server_job:0117| END GOOD ---- ---- timestamp=1343905755 localtime=Aug 02 11:09:15
08/02 11:09:15 INFO |server_job:0117| END GOOD ---- ---- timestamp=1343905755 localtime=Aug 02 11:09:15
08/02 11:09:17 INFO |autotest_r:0792| Client complete
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:09:17 INFO |autotest_r:0792| Client complete
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:09:17 DEBUG|abstract_s:0458| Host test-client2 is now up
08/02 11:09:17 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:09:17 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:09:17 DEBUG|base_utils:0077| Running 'rsync --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_D0QVLBssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpTbtU2N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client2:"/usr/local/autotest/results/default/" /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client2'
08/02 11:09:17 DEBUG|abstract_s:0458| Host test-client1 is now up
08/02 11:09:17 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:09:17 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:09:17 DEBUG|base_utils:0077| Running 'rsync --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_AntoIassh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp00VI8N -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client1:"/usr/local/autotest/results/default/" /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client1'
08/02 11:09:17 DEBUG| base_job:0242| Value of client.sysinfo is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0245| Importing client.harness from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client2/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0245| Importing client.steps from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client2/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0245| Importing client._record_indent from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client2/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0242| Value of global_properties.tag is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0242| Value of global_properties.last_boot_tag is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0391| Persistent state client.* deleted
08/02 11:09:17 DEBUG| base_job:0242| Value of client.sysinfo is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0245| Importing client.harness from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client1/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0245| Importing client.steps from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client1/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0245| Importing client._record_indent from state file /usr/local/autotest/results/457-debug_user/group0/netperf2/test-client1/control.autoserv.state
08/02 11:09:17 DEBUG| base_job:0242| Value of global_properties.tag is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0242| Value of global_properties.last_boot_tag is unchanged, skipping import
08/02 11:09:17 DEBUG| base_job:0391| Persistent state client.* deleted
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) '/sbin/iptables -L'
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain INPUT (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG|base_utils:0114| [stdout]
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain FORWARD (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG|base_utils:0114| [stdout]
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain OUTPUT (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'ls "/tmp/iptable-rules" > /dev/null'
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'iptables-restore < /tmp/iptable-rules'
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) '/sbin/iptables -L'
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain INPUT (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG|base_utils:0114| [stdout]
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain FORWARD (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG|base_utils:0114| [stdout]
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] Chain OUTPUT (policy ACCEPT)
08/02 11:09:17 DEBUG|base_utils:0114| [stdout] target prot opt source destination
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'ls "/tmp/iptable-rules" > /dev/null'
08/02 11:09:17 DEBUG| ssh_host:0114| Running (ssh) 'iptables-restore < /tmp/iptable-rules'
08/02 11:21:57 INFO | autoserv:0201| Results placed in /usr/local/autotest/results/457-debug_user/group0
08/02 11:21:57 DEBUG| base_job:0349| Persistent state global_properties.tag now set to ''
08/02 11:21:57 DEBUG| base_job:0349| Persistent state global_properties.last_boot_tag now set to None
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'test -f /var/log/messages'
08/02 11:21:57 INFO |abstract_s:0618| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_RI7quYssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpX63eQ6 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client1'
08/02 11:21:57 DEBUG|base_utils:0077| Running '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_RI7quYssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpX63eQ6 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client1'
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'test -f /var/log/messages'
08/02 11:21:57 INFO |abstract_s:0618| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_dEdIc_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2XIgSf -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client2'
08/02 11:21:57 DEBUG|base_utils:0077| Running '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_dEdIc_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2XIgSf -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22 test-client2'
08/02 11:21:57 ERROR|base_utils:0114| [stderr] Warning: Permanently added 'test-client2,192.168.254.12' (ECDSA) to the list of known hosts.
08/02 11:21:57 DEBUG| remote:0185| Found remote path /var/log/messages
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /var/tmp/messages.autotest_start'
08/02 11:21:57 DEBUG| remote:0185| Found remote path /var/log/messages
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'rm -f /var/tmp/messages.autotest_start'
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'cp /var/log/messages /var/tmp/messages.autotest_start'
08/02 11:21:57 INFO |crashcolle:0021| Collecting crash information...
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'cp /var/log/messages /var/tmp/messages.autotest_start'
08/02 11:21:57 INFO |crashcolle:0052| test-client2 already up, collecting crash info
08/02 11:21:57 INFO |crashcolle:0099| Collecting /var/tmp/messages.autotest_start...
08/02 11:21:57 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'rsync --version'
08/02 11:21:57 INFO |crashcolle:0021| Collecting crash information...
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'true'
08/02 11:21:57 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:21:57 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_dEdIc_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2XIgSf -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client2:"/var/tmp/messages.autotest_start" /usr/local/autotest/results/457-debug_user/group0/crashinfo.test-client2/messages.at_start'
08/02 11:21:57 INFO |crashcolle:0052| test-client1 already up, collecting crash info
08/02 11:21:57 INFO |crashcolle:0099| Collecting /var/tmp/messages.autotest_start...
08/02 11:21:57 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:21:57 DEBUG| ssh_host:0114| Running (ssh) 'rsync --version'
08/02 11:21:57 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:21:57 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_RI7quYssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpX63eQ6 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client1:"/var/tmp/messages.autotest_start" /usr/local/autotest/results/457-debug_user/group0/crashinfo.test-client1/messages.at_start'
08/02 11:21:57 INFO |crashcolle:0099| Collecting /var/log/messages...
08/02 11:21:57 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:21:57 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:21:57 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_dEdIc_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2XIgSf -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client2:"/var/log/messages" /usr/local/autotest/results/457-debug_user/group0/crashinfo.test-client2/messages.raw'
08/02 11:21:57 INFO |crashcolle:0099| Collecting /var/log/messages...
08/02 11:21:57 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:21:57 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:21:57 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_RI7quYssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmpX63eQ6 -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client1:"/var/log/messages" /usr/local/autotest/results/457-debug_user/group0/crashinfo.test-client1/messages.raw'
08/02 11:21:57 INFO |crashcolle:0099| Collecting /var/log/monitor-ssh-reboots...
08/02 11:21:57 INFO |abstract_s:0240| XMARKWA get_file
08/02 11:21:57 INFO |abstract_s:0117| XMARKWA _make_rsync_cmd
08/02 11:21:57 DEBUG|base_utils:0077| Running 'rsync -L --timeout=1800 --rsh='/usr/bin/ssh -a -x -o ControlPath=/tmp/_autotmp_dEdIc_ssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/tmp/tmp2XIgSf -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=300 -l root -p 22' -az root@test-client2:"/var/log/monitor-ssh-reboots" /usr/local/autotest/results/457-debug_user/group0/crashinfo