builder: mozilla-central_win7_ix_test-dromaeojs-e10s slave: t-w732-ix-015 starttime: 1479830176.67 results: success (0) buildid: 20161122062250 builduid: 3978839679f2437eae557fd251efb6ae revision: c176703ff213c6660818a3701d7c4cf026064329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:16.665738) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:16.666252) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:16.666537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:16.790112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 07:56:16.790487) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --07:56:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 8.65 MB/s 07:56:17 (8.65 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.986000 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 07:56:17.861193) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-11-22 07:56:17.861519) ========= 'rm' '-rf' 'scripts' 'properties' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=6.601000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 6 secs) (at 2016-11-22 07:56:24.472725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 07:56:24.473109) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev c176703ff213c6660818a3701d7c4cf026064329 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c176703ff213c6660818a3701d7c4cf026064329 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-22 07:56:24,901 truncating revision to first 12 chars 2016-11-22 07:56:24,901 Setting DEBUG logging. 2016-11-22 07:56:24,901 attempt 1/10 2016-11-22 07:56:24,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c176703ff213?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 07:56:25,418 unpacking tar archive at: mozilla-central-c176703ff213/testing/mozharness/ 2016-11-22 07:56:25,602 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.329000 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 07:56:25.890116) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:25.890444) ========= script_repo_revision: c176703ff213c6660818a3701d7c4cf026064329 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:25.890860) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:25.891124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 07:56:25.901128) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 4 secs) (at 2016-11-22 07:56:25.901494) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 07:56:26 INFO - MultiFileLogger online at 20161122 07:56:26 in C:\slave\test 07:56:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 07:56:26 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 07:56:26 INFO - {'append_to_log': False, 07:56:26 INFO - 'base_work_dir': 'C:\\slave\\test', 07:56:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:56:26 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 07:56:26 INFO - 'branch': 'Firefox-Non-PGO', 07:56:26 INFO - 'buildbot_json_path': 'buildprops.json', 07:56:26 INFO - 'config_files': ('talos/windows_config.py',), 07:56:26 INFO - 'default_actions': ('clobber', 07:56:26 INFO - 'read-buildbot-config', 07:56:26 INFO - 'download-and-extract', 07:56:26 INFO - 'populate-webroot', 07:56:26 INFO - 'create-virtualenv', 07:56:26 INFO - 'install', 07:56:26 INFO - 'run-tests'), 07:56:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:56:26 INFO - 'download_minidump_stackwalk': True, 07:56:26 INFO - 'download_symbols': 'ondemand', 07:56:26 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 07:56:26 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 07:56:26 INFO - 'hg': 'c:/mozilla-build/hg/hg', 07:56:26 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 07:56:26 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 07:56:26 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 07:56:26 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 07:56:26 INFO - 'C:/mozilla-build/tooltool.py'), 07:56:26 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 07:56:26 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 07:56:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:56:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:56:26 INFO - 'installer_path': 'installer.exe', 07:56:26 INFO - 'log_level': 'info', 07:56:26 INFO - 'log_name': 'talos', 07:56:26 INFO - 'log_to_console': True, 07:56:26 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 07:56:26 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 07:56:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 07:56:26 INFO - 'opt_config_files': (), 07:56:26 INFO - 'pip_index': False, 07:56:26 INFO - 'sps_profile': False, 07:56:26 INFO - 'sps_profile_interval': 0, 07:56:26 INFO - 'suite': 'dromaeojs-e10s', 07:56:26 INFO - 'system_bits': '32', 07:56:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:56:26 INFO - 'title': 'T-W732-IX-015', 07:56:26 INFO - 'use_talos_json': True, 07:56:26 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 07:56:26 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 07:56:26 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 07:56:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:56:26 INFO - 'work_dir': 'build'} 07:56:26 INFO - [mozharness: 2016-11-22 15:56:26.372000Z] Running clobber step. 07:56:26 INFO - Running pre-action listener: _resource_record_pre_action 07:56:26 INFO - Running main action method: clobber 07:56:26 INFO - rmtree: C:\slave\test\build 07:56:26 INFO - Using _rmtree_windows ... 07:56:26 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 07:57:51 INFO - Running post-action listener: _resource_record_post_action 07:57:51 INFO - [mozharness: 2016-11-22 15:57:51.066000Z] Finished clobber step (success) 07:57:51 INFO - [mozharness: 2016-11-22 15:57:51.066000Z] Running read-buildbot-config step. 07:57:51 INFO - Running pre-action listener: _resource_record_pre_action 07:57:51 INFO - Running main action method: read_buildbot_config 07:57:51 INFO - Using buildbot properties: 07:57:51 INFO - { 07:57:51 INFO - "project": "", 07:57:51 INFO - "product": "firefox", 07:57:51 INFO - "script_repo_revision": "production", 07:57:51 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs-e10s", 07:57:51 INFO - "repository": "", 07:57:51 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs-e10s", 07:57:51 INFO - "buildid": "20161122062250", 07:57:51 INFO - "pgo_build": "False", 07:57:51 INFO - "basedir": "C:\\slave\\test", 07:57:51 INFO - "buildnumber": 108, 07:57:51 INFO - "slavename": "t-w732-ix-015", 07:57:51 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 07:57:51 INFO - "platform": "win7_ix", 07:57:51 INFO - "scheduler": "tests-mozilla-central-win32-talos", 07:57:51 INFO - "branch": "mozilla-central", 07:57:51 INFO - "repo_path": "mozilla-central", 07:57:51 INFO - "revision": "c176703ff213c6660818a3701d7c4cf026064329", 07:57:51 INFO - "stage_platform": "win32", 07:57:51 INFO - "builduid": "3978839679f2437eae557fd251efb6ae", 07:57:51 INFO - "slavebuilddir": "test" 07:57:51 INFO - } 07:57:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 07:57:51 INFO - Running post-action listener: _resource_record_post_action 07:57:51 INFO - [mozharness: 2016-11-22 15:57:51.070000Z] Finished read-buildbot-config step (success) 07:57:51 INFO - [mozharness: 2016-11-22 15:57:51.070000Z] Running download-and-extract step. 07:57:51 INFO - Running pre-action listener: _resource_record_pre_action 07:57:51 INFO - Running main action method: download_and_extract 07:57:51 INFO - mkdir: C:\slave\test\build\tests 07:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:51 INFO - trying https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 07:57:51 INFO - Downloading https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 07:57:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 07:57:51 INFO - Downloaded 1274 bytes. 07:57:51 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 07:57:51 INFO - Using the following test package requirements: 07:57:51 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 07:57:51 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 07:57:51 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'], 07:57:51 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'jsshell-win32.zip'], 07:57:51 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 07:57:51 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 07:57:51 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 07:57:51 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 07:57:51 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 07:57:51 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 07:57:51 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 07:57:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 07:57:51 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 07:57:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:57:51 INFO - Fetch https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 07:57:52 INFO - Http code: 200 07:57:52 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 07:57:52 INFO - x-amz-cf-id: dmbEfAPBT91yodWNBvi_7Vk6Me8LZoCfNkeG9DXIvnqkrDVugacWaA== 07:57:52 INFO - x-amz-version-id: JT4Xl09aXJEF64dYff2kh22VIk3N7XcN 07:57:53 INFO - Expected file size: 16994628 07:57:53 INFO - Obtained file size: 16994628 07:59:20 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 07:59:20 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 07:59:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:59:20 INFO - Fetch https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 07:59:20 INFO - Http code: 200 07:59:20 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 07:59:20 INFO - x-amz-cf-id: cI9msQwbTOvfVVppzdRtm4eAkA8hjszMpRK-C3j7MYJtfrolYyhQkA== 07:59:20 INFO - x-amz-version-id: JT4Xl09aXJEF64dYff2kh22VIk3N7XcN 07:59:21 INFO - Expected file size: 16994628 07:59:21 INFO - Obtained file size: 16994628 07:59:44 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 07:59:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 07:59:44 INFO - Fetch https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 07:59:44 INFO - Http code: 200 07:59:44 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 07:59:44 INFO - x-amz-cf-id: HqM99-d-enxrB_SNDP2SZ9S4iMSageTVo82-eXTiomnBQjqvW3Es_w== 07:59:44 INFO - x-amz-version-id: Ad4wBemNrFXbF2RvdjlPZqavUPxPkavW 07:59:45 INFO - Expected file size: 14409392 07:59:45 INFO - Obtained file size: 14409392 07:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:56 INFO - trying https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 07:59:56 INFO - Downloading https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 07:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 07:59:59 INFO - Downloaded 53230757 bytes. 07:59:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 07:59:59 INFO - mkdir: C:\slave\test\properties 07:59:59 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 07:59:59 INFO - Writing to file C:\slave\test\properties\build_url 07:59:59 INFO - Contents: 07:59:59 INFO - build_url:https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 07:59:59 INFO - Running post-action listener: _resource_record_post_action 07:59:59 INFO - Running post-action listener: set_extra_try_arguments 07:59:59 INFO - [mozharness: 2016-11-22 15:59:59.809000Z] Finished download-and-extract step (success) 07:59:59 INFO - [mozharness: 2016-11-22 15:59:59.809000Z] Running populate-webroot step. 07:59:59 INFO - Running pre-action listener: _resource_record_pre_action 07:59:59 INFO - Running main action method: populate_webroot 07:59:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 07:59:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 07:59:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 07:59:59 INFO - u'--tppagecycles', 07:59:59 INFO - u'7'], 07:59:59 INFO - u'tests': [u'tsvgm']}}, 07:59:59 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 07:59:59 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 07:59:59 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 07:59:59 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 07:59:59 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:59:59 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:59:59 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'damp', u'tps']}, 07:59:59 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'tests': [u'damp', u'tps']}, 07:59:59 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'dromaeo_dom']}, 07:59:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 07:59:59 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'basic_compositor_video']}, 07:59:59 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 07:59:59 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'other-e10s': {u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'a11yr', 07:59:59 INFO - u'ts_paint', 07:59:59 INFO - u'tpaint', 07:59:59 INFO - u'sessionrestore', 07:59:59 INFO - u'sessionrestore_no_auto_restore', 07:59:59 INFO - u'tabpaint']}, 07:59:59 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'tsvgx', 07:59:59 INFO - u'tsvgr_opacity', 07:59:59 INFO - u'tart', 07:59:59 INFO - u'tscrollx', 07:59:59 INFO - u'cart']}, 07:59:59 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 07:59:59 INFO - u'tsvgr_opacity', 07:59:59 INFO - u'tart', 07:59:59 INFO - u'tscrollx', 07:59:59 INFO - u'cart']}, 07:59:59 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'talos_options': [u'--disable-e10s'], 07:59:59 INFO - u'tests': [u'tp5o']}, 07:59:59 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'tests': [u'tp5o']}, 07:59:59 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'talos_options': [u'--disable-e10s', 07:59:59 INFO - u'--xperf_path', 07:59:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:59 INFO - u'tests': [u'tp5n']}, 07:59:59 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 07:59:59 INFO - u'talos_options': [u'--xperf_path', 07:59:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:59:59 INFO - u'tests': [u'tp5n']}}, 07:59:59 INFO - u'talos.zip': {u'path': u'', 07:59:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:59:59 INFO - Running post-action listener: _resource_record_post_action 07:59:59 INFO - [mozharness: 2016-11-22 15:59:59.903000Z] Finished populate-webroot step (success) 07:59:59 INFO - [mozharness: 2016-11-22 15:59:59.903000Z] Running create-virtualenv step. 07:59:59 INFO - Running pre-action listener: _resource_record_pre_action 07:59:59 INFO - Running main action method: create_virtualenv 07:59:59 INFO - Creating virtualenv C:\slave\test\build/venv 07:59:59 INFO - mkdir: C:\slave\test\build\venv\Scripts 08:00:00 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 08:00:00 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 08:00:00 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 08:00:00 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 08:00:17 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 08:00:22 INFO - Installing distribute......................................................................................................................................................................................done. 08:00:24 INFO - Return code: 0 08:00:24 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 08:00:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 08:00:24 INFO - Reading from file tmpfile_stdout 08:00:24 INFO - Output received: 08:00:24 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 08:00:24 INFO - Using _rmtree_windows ... 08:00:24 INFO - Using _rmtree_windows ... 08:00:24 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 08:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 08:00:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:00:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:24 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:24 INFO - 'DCLOCATION': 'SCL3', 08:00:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:24 INFO - 'HOMEDRIVE': 'C:', 08:00:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:24 INFO - 'KTS_VERSION': '1.19c', 08:00:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:24 INFO - 'MOZ_NO_REMOTE': '1', 08:00:24 INFO - 'NO_EM_RESTART': '1', 08:00:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:24 INFO - 'OS': 'Windows_NT', 08:00:24 INFO - 'OURDRIVE': 'C:', 08:00:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:24 INFO - 'PROCESSOR_LEVEL': '6', 08:00:24 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:24 INFO - 'PROMPT': '$P$G', 08:00:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:24 INFO - 'PWD': 'C:\\slave\\test', 08:00:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:24 INFO - 'SYSTEMDRIVE': 'C:', 08:00:24 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:24 INFO - 'TEST1': 'testie', 08:00:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:24 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:24 INFO - 'USERNAME': 'cltbld', 08:00:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:24 INFO - 'WINDIR': 'C:\\windows', 08:00:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:25 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:00:25 INFO - Downloading/unpacking pip>=1.5 08:00:31 INFO - Running setup.py egg_info for package pip 08:00:31 INFO - warning: no files found matching 'pip\cacert.pem' 08:00:31 INFO - warning: no files found matching '*.html' under directory 'docs' 08:00:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:00:31 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:00:31 INFO - Installing collected packages: pip 08:00:31 INFO - Found existing installation: pip 0.8.2 08:00:31 INFO - Uninstalling pip: 08:00:31 INFO - Successfully uninstalled pip 08:00:31 INFO - Running setup.py install for pip 08:00:31 INFO - warning: no files found matching 'pip\cacert.pem' 08:00:31 INFO - warning: no files found matching '*.html' under directory 'docs' 08:00:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:00:31 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:00:31 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 08:00:31 INFO - Successfully installed pip 08:00:31 INFO - Cleaning up... 08:00:31 INFO - Return code: 0 08:00:31 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 08:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:31 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 08:00:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:00:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:31 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:31 INFO - 'DCLOCATION': 'SCL3', 08:00:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:31 INFO - 'HOMEDRIVE': 'C:', 08:00:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:31 INFO - 'KTS_VERSION': '1.19c', 08:00:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:31 INFO - 'MOZ_NO_REMOTE': '1', 08:00:31 INFO - 'NO_EM_RESTART': '1', 08:00:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:31 INFO - 'OS': 'Windows_NT', 08:00:31 INFO - 'OURDRIVE': 'C:', 08:00:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:31 INFO - 'PROCESSOR_LEVEL': '6', 08:00:31 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:31 INFO - 'PROMPT': '$P$G', 08:00:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:31 INFO - 'PWD': 'C:\\slave\\test', 08:00:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:31 INFO - 'SYSTEMDRIVE': 'C:', 08:00:31 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:31 INFO - 'TEST1': 'testie', 08:00:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:31 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:31 INFO - 'USERNAME': 'cltbld', 08:00:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:31 INFO - 'WINDIR': 'C:\\windows', 08:00:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:33 INFO - Downloading/unpacking psutil>=3.1.1 08:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:34 INFO - Installing collected packages: psutil 08:00:34 INFO - Successfully installed psutil 08:00:34 INFO - Cleaning up... 08:00:34 INFO - Return code: 0 08:00:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 08:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 08:00:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:00:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:34 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:34 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:34 INFO - 'DCLOCATION': 'SCL3', 08:00:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:34 INFO - 'HOMEDRIVE': 'C:', 08:00:34 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:34 INFO - 'KTS_VERSION': '1.19c', 08:00:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:34 INFO - 'MOZ_NO_REMOTE': '1', 08:00:34 INFO - 'NO_EM_RESTART': '1', 08:00:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:34 INFO - 'OS': 'Windows_NT', 08:00:34 INFO - 'OURDRIVE': 'C:', 08:00:34 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:34 INFO - 'PROCESSOR_LEVEL': '6', 08:00:34 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:34 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:34 INFO - 'PROMPT': '$P$G', 08:00:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:34 INFO - 'PWD': 'C:\\slave\\test', 08:00:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:34 INFO - 'SYSTEMDRIVE': 'C:', 08:00:34 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:34 INFO - 'TEST1': 'testie', 08:00:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:34 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:34 INFO - 'USERNAME': 'cltbld', 08:00:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:34 INFO - 'WINDIR': 'C:\\windows', 08:00:34 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 08:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 08:00:40 INFO - Installing collected packages: mozsystemmonitor 08:00:40 INFO - Running setup.py install for mozsystemmonitor 08:00:41 INFO - Successfully installed mozsystemmonitor 08:00:41 INFO - Cleaning up... 08:00:42 INFO - Return code: 0 08:00:42 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 08:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:42 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 08:00:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:00:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:42 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:42 INFO - 'DCLOCATION': 'SCL3', 08:00:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:42 INFO - 'HOMEDRIVE': 'C:', 08:00:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:42 INFO - 'KTS_VERSION': '1.19c', 08:00:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:42 INFO - 'MOZ_NO_REMOTE': '1', 08:00:42 INFO - 'NO_EM_RESTART': '1', 08:00:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:42 INFO - 'OS': 'Windows_NT', 08:00:42 INFO - 'OURDRIVE': 'C:', 08:00:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:42 INFO - 'PROCESSOR_LEVEL': '6', 08:00:42 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:42 INFO - 'PROMPT': '$P$G', 08:00:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:42 INFO - 'PWD': 'C:\\slave\\test', 08:00:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:42 INFO - 'SYSTEMDRIVE': 'C:', 08:00:42 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:42 INFO - 'TEST1': 'testie', 08:00:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:42 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:42 INFO - 'USERNAME': 'cltbld', 08:00:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:42 INFO - 'WINDIR': 'C:\\windows', 08:00:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:42 INFO - Downloading/unpacking jsonschema==2.5.1 08:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:44 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 08:00:44 INFO - warning: no files found matching '*.txt' 08:00:44 INFO - no previously-included directories found matching 'build' 08:00:44 INFO - no previously-included directories found matching 'dist' 08:00:44 INFO - no previously-included directories found matching '.git*' 08:00:44 INFO - Installing collected packages: jsonschema, functools32 08:00:44 INFO - Running setup.py install for functools32 08:00:44 INFO - warning: no files found matching '*.txt' 08:00:44 INFO - no previously-included directories found matching 'build' 08:00:44 INFO - no previously-included directories found matching 'dist' 08:00:44 INFO - no previously-included directories found matching '.git*' 08:00:44 INFO - Successfully installed jsonschema functools32 08:00:44 INFO - Cleaning up... 08:00:45 INFO - Return code: 0 08:00:45 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 08:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:45 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 08:00:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:00:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:45 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:45 INFO - 'DCLOCATION': 'SCL3', 08:00:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:45 INFO - 'HOMEDRIVE': 'C:', 08:00:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:45 INFO - 'KTS_VERSION': '1.19c', 08:00:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:45 INFO - 'MOZ_NO_REMOTE': '1', 08:00:45 INFO - 'NO_EM_RESTART': '1', 08:00:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:45 INFO - 'OS': 'Windows_NT', 08:00:45 INFO - 'OURDRIVE': 'C:', 08:00:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:45 INFO - 'PROCESSOR_LEVEL': '6', 08:00:45 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:45 INFO - 'PROMPT': '$P$G', 08:00:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:45 INFO - 'PWD': 'C:\\slave\\test', 08:00:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:45 INFO - 'SYSTEMDRIVE': 'C:', 08:00:45 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:45 INFO - 'TEST1': 'testie', 08:00:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:45 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:45 INFO - 'USERNAME': 'cltbld', 08:00:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:45 INFO - 'WINDIR': 'C:\\windows', 08:00:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 08:00:45 INFO - Cleaning up... 08:00:45 INFO - Return code: 0 08:00:45 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 08:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:45 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 08:00:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:00:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:45 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:45 INFO - 'DCLOCATION': 'SCL3', 08:00:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:45 INFO - 'HOMEDRIVE': 'C:', 08:00:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:45 INFO - 'KTS_VERSION': '1.19c', 08:00:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:45 INFO - 'MOZ_NO_REMOTE': '1', 08:00:45 INFO - 'NO_EM_RESTART': '1', 08:00:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:45 INFO - 'OS': 'Windows_NT', 08:00:45 INFO - 'OURDRIVE': 'C:', 08:00:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:45 INFO - 'PROCESSOR_LEVEL': '6', 08:00:45 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:45 INFO - 'PROMPT': '$P$G', 08:00:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:45 INFO - 'PWD': 'C:\\slave\\test', 08:00:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:45 INFO - 'SYSTEMDRIVE': 'C:', 08:00:45 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:45 INFO - 'TEST1': 'testie', 08:00:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:45 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:45 INFO - 'USERNAME': 'cltbld', 08:00:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:45 INFO - 'WINDIR': 'C:\\windows', 08:00:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 08:00:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:47 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 08:00:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:47 INFO - Downloading docopt-0.6.1.tar.gz 08:00:47 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 08:00:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:47 INFO - Running setup.py install for blobuploader 08:00:47 INFO - Running setup.py install for requests 08:00:48 INFO - Running setup.py install for docopt 08:00:48 INFO - Successfully installed blobuploader requests docopt 08:00:48 INFO - Cleaning up... 08:00:49 INFO - Return code: 0 08:00:49 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:00:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 08:00:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:00:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:00:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:00:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:00:49 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:00:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:00:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:00:49 INFO - 'DCLOCATION': 'SCL3', 08:00:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:00:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:00:49 INFO - 'HOMEDRIVE': 'C:', 08:00:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:00:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:00:49 INFO - 'KTS_VERSION': '1.19c', 08:00:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:00:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:00:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:00:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:49 INFO - 'MOZ_NO_REMOTE': '1', 08:00:49 INFO - 'NO_EM_RESTART': '1', 08:00:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:00:49 INFO - 'OS': 'Windows_NT', 08:00:49 INFO - 'OURDRIVE': 'C:', 08:00:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:00:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:00:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:00:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:00:49 INFO - 'PROCESSOR_LEVEL': '6', 08:00:49 INFO - 'PROCESSOR_REVISION': '1e05', 08:00:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:00:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:00:49 INFO - 'PROMPT': '$P$G', 08:00:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:00:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:00:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:00:49 INFO - 'PWD': 'C:\\slave\\test', 08:00:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:00:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:00:49 INFO - 'SYSTEMDRIVE': 'C:', 08:00:49 INFO - 'SYSTEMROOT': 'C:\\windows', 08:00:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:49 INFO - 'TEST1': 'testie', 08:00:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:00:49 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:00:49 INFO - 'USERNAME': 'cltbld', 08:00:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:00:49 INFO - 'WINDIR': 'C:\\windows', 08:00:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:00:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eybec6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yd2uts-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huqjnz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-upcqbf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-abfrlb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tydils-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:00:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:00:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jztpvy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cynvqs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z3pvka-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rlyv7s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qjn39m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j8uvlw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0i3lpx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:00:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:00:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lg2wzk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:00:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:00:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1_jxjh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:00:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:00:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uvno9_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:00:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:00:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qrcdps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:00:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:52 INFO - Running setup.py install for manifestparser 08:00:52 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 08:00:52 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 08:00:52 INFO - Running setup.py install for mozcrash 08:00:53 INFO - Running setup.py install for mozdebug 08:00:53 INFO - Running setup.py install for mozdevice 08:00:53 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 08:00:53 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 08:00:53 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 08:00:53 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 08:00:54 INFO - Running setup.py install for mozfile 08:00:54 INFO - Running setup.py install for mozhttpd 08:00:54 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 08:00:54 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 08:00:55 INFO - Running setup.py install for mozinfo 08:00:55 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 08:00:55 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Running setup.py install for mozInstall 08:00:56 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 08:00:56 INFO - Running setup.py install for mozleak 08:00:56 INFO - Running setup.py install for mozlog 08:00:57 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 08:00:57 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 08:00:58 INFO - Running setup.py install for moznetwork 08:00:58 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 08:00:58 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 08:00:58 INFO - Running setup.py install for mozprocess 08:00:59 INFO - Running setup.py install for mozprofile 08:00:59 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Running setup.py install for mozrunner 08:00:59 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 08:00:59 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 08:01:00 INFO - Running setup.py install for mozscreenshot 08:01:00 INFO - Running setup.py install for moztest 08:01:01 INFO - Running setup.py install for mozversion 08:01:01 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 08:01:01 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 08:01:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:01:01 INFO - Cleaning up... 08:01:01 INFO - Return code: 0 08:01:01 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:01 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:01:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 08:01:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:01:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:01:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:01:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:01:01 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:01:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:01:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:01:01 INFO - 'DCLOCATION': 'SCL3', 08:01:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:01:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:01:01 INFO - 'HOMEDRIVE': 'C:', 08:01:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:01:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:01:01 INFO - 'KTS_VERSION': '1.19c', 08:01:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:01:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:01:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:01:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:01 INFO - 'MOZ_NO_REMOTE': '1', 08:01:01 INFO - 'NO_EM_RESTART': '1', 08:01:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:01:01 INFO - 'OS': 'Windows_NT', 08:01:01 INFO - 'OURDRIVE': 'C:', 08:01:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:01:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:01:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:01:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:01:01 INFO - 'PROCESSOR_LEVEL': '6', 08:01:01 INFO - 'PROCESSOR_REVISION': '1e05', 08:01:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:01:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:01:01 INFO - 'PROMPT': '$P$G', 08:01:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:01:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:01:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:01:01 INFO - 'PWD': 'C:\\slave\\test', 08:01:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:01:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:01:01 INFO - 'SYSTEMDRIVE': 'C:', 08:01:01 INFO - 'SYSTEMROOT': 'C:\\windows', 08:01:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:01 INFO - 'TEST1': 'testie', 08:01:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:01 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:01:01 INFO - 'USERNAME': 'cltbld', 08:01:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:01:01 INFO - 'WINDIR': 'C:\\windows', 08:01:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:01:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eliccx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e_q2jz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dzgw2j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jah0fs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iw0o5i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cnzln1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 08:01:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:01:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j17gsy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aqoioe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qtpwtu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y6llff-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zuzygh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xeaq85-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ji5qwx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 08:01:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:01:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n72ox3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 08:01:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:01:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ql6sps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 08:01:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:01:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a7vkgm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 08:01:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:01:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dl_ld0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:01:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 08:01:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:05 INFO - Downloading blessings-1.6.tar.gz 08:01:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 08:01:06 INFO - Installing collected packages: blessings 08:01:06 INFO - Running setup.py install for blessings 08:01:06 INFO - Successfully installed blessings 08:01:06 INFO - Cleaning up... 08:01:08 INFO - Return code: 0 08:01:08 INFO - Done creating virtualenv C:\slave\test\build/venv. 08:01:08 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:01:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:01:09 INFO - Reading from file tmpfile_stdout 08:01:09 INFO - Using _rmtree_windows ... 08:01:09 INFO - Using _rmtree_windows ... 08:01:09 INFO - Current package versions: 08:01:09 INFO - blessings == 1.6 08:01:09 INFO - blobuploader == 1.2.4 08:01:09 INFO - docopt == 0.6.1 08:01:09 INFO - functools32 == 3.2.3-2 08:01:09 INFO - jsonschema == 2.5.1 08:01:09 INFO - manifestparser == 1.1 08:01:09 INFO - mozInstall == 1.12 08:01:09 INFO - mozcrash == 1.0 08:01:09 INFO - mozdebug == 0.1 08:01:09 INFO - mozdevice == 0.49 08:01:09 INFO - mozfile == 1.2 08:01:09 INFO - mozhttpd == 0.7 08:01:09 INFO - mozinfo == 0.9 08:01:09 INFO - mozleak == 0.1 08:01:09 INFO - mozlog == 3.4 08:01:09 INFO - moznetwork == 0.27 08:01:09 INFO - mozprocess == 0.23 08:01:09 INFO - mozprofile == 0.28 08:01:09 INFO - mozrunner == 6.13 08:01:09 INFO - mozscreenshot == 0.1 08:01:09 INFO - mozsystemmonitor == 0.3 08:01:09 INFO - moztest == 0.7 08:01:09 INFO - mozversion == 1.4 08:01:09 INFO - psutil == 3.1.1 08:01:09 INFO - requests == 1.2.3 08:01:09 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:01:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 08:01:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:01:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:01:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:01:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:01:09 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:01:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:01:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:01:09 INFO - 'DCLOCATION': 'SCL3', 08:01:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:01:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:01:09 INFO - 'HOMEDRIVE': 'C:', 08:01:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:01:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:01:09 INFO - 'KTS_VERSION': '1.19c', 08:01:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:01:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:01:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:01:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:09 INFO - 'MOZ_NO_REMOTE': '1', 08:01:09 INFO - 'NO_EM_RESTART': '1', 08:01:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:01:09 INFO - 'OS': 'Windows_NT', 08:01:09 INFO - 'OURDRIVE': 'C:', 08:01:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:01:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:01:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:01:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:01:09 INFO - 'PROCESSOR_LEVEL': '6', 08:01:09 INFO - 'PROCESSOR_REVISION': '1e05', 08:01:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:01:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:01:09 INFO - 'PROMPT': '$P$G', 08:01:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:01:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:01:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:01:09 INFO - 'PWD': 'C:\\slave\\test', 08:01:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:01:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:01:09 INFO - 'SYSTEMDRIVE': 'C:', 08:01:09 INFO - 'SYSTEMROOT': 'C:\\windows', 08:01:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:09 INFO - 'TEST1': 'testie', 08:01:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:09 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:01:09 INFO - 'USERNAME': 'cltbld', 08:01:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:01:09 INFO - 'WINDIR': 'C:\\windows', 08:01:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:01:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 08:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 08:01:09 INFO - Cleaning up... 08:01:09 INFO - Return code: 0 08:01:09 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 08:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01920640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01EEA020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-015', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 08:01:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 08:01:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:01:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:01:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:01:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:01:09 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:01:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:01:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:01:09 INFO - 'DCLOCATION': 'SCL3', 08:01:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:01:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:01:09 INFO - 'HOMEDRIVE': 'C:', 08:01:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:01:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:01:09 INFO - 'KTS_VERSION': '1.19c', 08:01:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:01:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:01:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:01:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:09 INFO - 'MOZ_NO_REMOTE': '1', 08:01:09 INFO - 'NO_EM_RESTART': '1', 08:01:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:01:09 INFO - 'OS': 'Windows_NT', 08:01:09 INFO - 'OURDRIVE': 'C:', 08:01:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:01:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:01:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:01:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:01:09 INFO - 'PROCESSOR_LEVEL': '6', 08:01:09 INFO - 'PROCESSOR_REVISION': '1e05', 08:01:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:01:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:01:09 INFO - 'PROMPT': '$P$G', 08:01:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:01:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:01:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:01:09 INFO - 'PWD': 'C:\\slave\\test', 08:01:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:01:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:01:09 INFO - 'SYSTEMDRIVE': 'C:', 08:01:09 INFO - 'SYSTEMROOT': 'C:\\windows', 08:01:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:09 INFO - 'TEST1': 'testie', 08:01:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:09 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:01:09 INFO - 'USERNAME': 'cltbld', 08:01:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:01:09 INFO - 'WINDIR': 'C:\\windows', 08:01:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:01:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 08:01:10 INFO - Cleaning up... 08:01:10 INFO - Return code: 0 08:01:10 INFO - Running post-action listener: _resource_record_post_action 08:01:10 INFO - Running post-action listener: _start_resource_monitoring 08:01:10 INFO - Starting resource monitoring. 08:01:10 INFO - [mozharness: 2016-11-22 16:01:10.279000Z] Finished create-virtualenv step (success) 08:01:10 INFO - [mozharness: 2016-11-22 16:01:10.279000Z] Running install step. 08:01:10 INFO - Running pre-action listener: _resource_record_pre_action 08:01:10 INFO - Running main action method: install 08:01:10 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:01:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:01:10 INFO - Reading from file tmpfile_stdout 08:01:10 INFO - Using _rmtree_windows ... 08:01:10 INFO - Using _rmtree_windows ... 08:01:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:01:10 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 08:01:10 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 08:01:10 INFO - Reading from file tmpfile_stdout 08:01:10 INFO - Output received: 08:01:10 INFO - Usage: mozinstall-script.py [options] installer 08:01:10 INFO - Options: 08:01:10 INFO - -h, --help show this help message and exit 08:01:10 INFO - -d DEST, --destination=DEST 08:01:10 INFO - Directory to install application into. [default: 08:01:10 INFO - "C:\slave\test"] 08:01:10 INFO - --app=APP Application being installed. [default: firefox] 08:01:10 INFO - Using _rmtree_windows ... 08:01:10 INFO - Using _rmtree_windows ... 08:01:10 INFO - mkdir: C:\slave\test\build\application 08:01:10 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 08:01:10 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 08:01:18 INFO - Reading from file tmpfile_stdout 08:01:18 INFO - Output received: 08:01:18 INFO - C:\slave\test\build\application\firefox\firefox.exe 08:01:18 INFO - Using _rmtree_windows ... 08:01:18 INFO - Using _rmtree_windows ... 08:01:18 INFO - Running post-action listener: _resource_record_post_action 08:01:18 INFO - [mozharness: 2016-11-22 16:01:18.288000Z] Finished install step (success) 08:01:18 INFO - [mozharness: 2016-11-22 16:01:18.288000Z] Running run-tests step. 08:01:18 INFO - Running pre-action listener: _resource_record_pre_action 08:01:18 INFO - Running main action method: run_tests 08:01:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 08:01:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 08:01:18 INFO - Python 2.7.3 08:01:18 INFO - Return code: 0 08:01:18 INFO - grabbing minidump binary from tooltool 08:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:01:18 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E7E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0140A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01ECB5C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 08:01:18 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 08:01:18 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 08:01:18 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 08:01:18 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 08:01:19 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpcpejp8 08:01:19 INFO - INFO - File integrity verified, renaming tmpcpejp8 to win32-minidump_stackwalk.exe 08:01:19 INFO - Return code: 0 08:01:19 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 08:01:19 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 08:01:19 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 08:01:19 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 08:01:19 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 08:01:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build 08:01:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log 08:01:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:01:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:01:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:01:19 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 08:01:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:01:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:01:19 INFO - 'DCLOCATION': 'SCL3', 08:01:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:01:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:01:19 INFO - 'HOMEDRIVE': 'C:', 08:01:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:01:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:01:19 INFO - 'KTS_VERSION': '1.19c', 08:01:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:01:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 08:01:19 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:01:19 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 08:01:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:01:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:19 INFO - 'MOZ_NO_REMOTE': '1', 08:01:19 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:01:19 INFO - 'NO_EM_RESTART': '1', 08:01:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:01:19 INFO - 'OS': 'Windows_NT', 08:01:19 INFO - 'OURDRIVE': 'C:', 08:01:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:01:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:01:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:01:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:01:19 INFO - 'PROCESSOR_LEVEL': '6', 08:01:19 INFO - 'PROCESSOR_REVISION': '1e05', 08:01:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:01:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:01:19 INFO - 'PROMPT': '$P$G', 08:01:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:01:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:01:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:01:19 INFO - 'PWD': 'C:\\slave\\test', 08:01:19 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 08:01:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:01:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:01:19 INFO - 'SYSTEMDRIVE': 'C:', 08:01:19 INFO - 'SYSTEMROOT': 'C:\\windows', 08:01:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:19 INFO - 'TEST1': 'testie', 08:01:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:01:19 INFO - 'USERDOMAIN': 'T-W732-IX-015', 08:01:19 INFO - 'USERNAME': 'cltbld', 08:01:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:01:19 INFO - 'WINDIR': 'C:\\windows', 08:01:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:01:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:01:19 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600 08:01:20 INFO - mozversion application_buildid: 20161122062250 08:01:20 INFO - mozversion application_changeset: c176703ff213c6660818a3701d7c4cf026064329 08:01:20 INFO - mozversion application_display_name: Nightly 08:01:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:20 INFO - mozversion application_name: Firefox 08:01:20 INFO - mozversion application_remotingname: firefox 08:01:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:01:20 INFO - mozversion application_vendor: Mozilla 08:01:20 INFO - mozversion application_version: 53.0a1 08:01:20 INFO - mozversion platform_buildid: 20161122062250 08:01:20 INFO - mozversion platform_changeset: c176703ff213c6660818a3701d7c4cf026064329 08:01:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:01:20 INFO - mozversion platform_version: 53.0a1 08:01:20 INFO - using testdate: 1479830480 08:01:20 INFO - actual date: 1479830480 08:01:20 INFO - starting webserver on 'localhost:49895' 08:01:20 INFO - SUITE-START | Running 2 tests 08:01:20 INFO - TEST-START | dromaeo_css 08:01:20 INFO - operating with platform_type : w7_ 08:01:20 INFO - Initialising browser for dromaeo_css test... 08:01:20 INFO - TEST-INFO | started process 876 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplbzsdf\profile http://localhost:49895/getInfo.html) 08:01:33 INFO - PROCESS | 876 | __metrics Screen width/height:1600/1200 08:01:33 INFO - PROCESS | 876 | colorDepth:24 08:01:33 INFO - PROCESS | 876 | Browser inner width/height: 1010/674 08:01:33 INFO - PROCESS | 876 | __metrics 08:01:34 ERROR - PROCESS | 876 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 08:01:34 INFO - PROCESS | 876 | [Child 4088] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w32-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 08:01:34 INFO - PROCESS | 876 | [Child 4088] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w32-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 08:01:35 INFO - TEST-INFO | 876: exit 0 08:01:35 INFO - Browser initialized. 08:01:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:01:35 INFO - TEST-INFO | started process 1784 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplbzsdf\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:36 INFO - PROCESS | 1784 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 08:01:47 INFO - PROCESS | 1784 | RSS: Main: 145788928 08:01:47 INFO - PROCESS | 1784 | 08:02:36 INFO - PROCESS | 1784 | 1479830556859 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:04:50 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49895/tests/dromaeo/cssquery-ext.html) 08:04:51 INFO - PROCESS | 1784 | RSS: Main: 122310656 08:04:51 INFO - PROCESS | 1784 | 08:07:54 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-ext.html (next: http://localhost:49895/tests/dromaeo/cssquery-jquery.html) 08:07:54 INFO - PROCESS | 1784 | RSS: Main: 91787264 08:07:54 INFO - PROCESS | 1784 | 08:10:56 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49895/tests/dromaeo/cssquery-mootools.html) 08:10:57 INFO - PROCESS | 1784 | RSS: Main: 83963904 08:10:57 INFO - PROCESS | 1784 | 08:13:59 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49895/tests/dromaeo/cssquery-prototype.html) 08:14:00 INFO - PROCESS | 1784 | RSS: Main: 81600512 08:14:00 INFO - PROCESS | 1784 | 08:17:02 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49895/tests/dromaeo/cssquery-yui.html) 08:17:03 INFO - PROCESS | 1784 | RSS: Main: 86740992 08:17:03 INFO - PROCESS | 1784 | 08:20:05 INFO - PROCESS | 1784 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-yui.html (next: http://localhost:49895/tests/dromaeo/cssquery-dojo.html) 08:20:06 INFO - PROCESS | 1784 | RSS: Main: 78606336 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | __start_tp_report 08:20:06 INFO - PROCESS | 1784 | _x_x_mozilla_page_load 08:20:06 INFO - PROCESS | 1784 | _x_x_mozilla_page_load_details 08:20:06 INFO - PROCESS | 1784 | |i|pagename|runs| 08:20:06 INFO - PROCESS | 1784 | |0;dojo.html;2415.2150551070904;2415.9758402415982;2456.6929133858266;2470.1107601263543;2471.802255819535;4002.779847108408;4105.671546276299;4144.917101657965;4195.286801243788;4211.957880421195;3994.181192855465;4040.9797951010205;4183.2888408970475;4195.2029114468305;4276.893077673052;3618.5115009473648;3626.9455958160647;3632.981835090821;3644.5262115925025;3692.298463291966;3257.7719559630837;3683;3687.1703866629928;3691.4647376130447;3695.113172838513;3505.4917037029486;3565.607783143852;3582.283543291326;3636.218213084173;3656.8902932912056;3524.6122926478065;3607.9897628664016;3610.0433385152955;3616.819159042064;3619.981900090509;3589.210373717778;3634.909127271839;3643.3806252937065;3648.0515066082744;3699.2601479704167;4031.717779755418;4048.9797551012057;4127.071408932996;4185.414042034117;4188.099558594917;4298.269294219959;4326.524082350938;4344.45694288214;4362.47650281965;4394.472663280395;4379.890502737457;4441.067375851075;4450.198964186445;4470.7988140533425;4471.888202794924;3925.2345792570745;4078.0416602098485;4081.469408976814;4161.583841615875;4177.895552611209;4272.722273052242;4328.11273688893;4411.382406463098;4418.845340413134;4477.731336119843;4242.321228603409;4260.041490664637;4273.594008569181;4285.928517870532;4334.9783251083545;4244.278472659656;4391.231534481453;4397.670174736908;4412.823487060482;4413.16149931512;4152.065785198306;4166.104287578185;4174.3947127666925;4286.978565107154;4293.377460268306;4262.680298977589;4296.2266791977745;4336.609705126553;4341.110072435144;4341.175176716477;3042.832644204546;3045.2691354074864;3072.446959547303;3083.105899289181;3108.5958825353014;2741.4928238275984;2746.40952195279;2775.3200465885584;2778.0415756563953;2796.9300767480568;4027.610474386332;4046.8805916735996;4108.77401742901;4109.486314210723;4150.460440142762;4239.491261048633;4322.286822674287;4372.147431250875;4402.229609818269;4419.491758447755;3941.8226179821977;3965.603439656069;4022.1352409232154;4143.357779544175;4177.916441671149;3706.629337066272;3711.684506816897;3750.6061863504483;3769.189624230749;3805.353089974711;4427.778611069498;4438.689291749675;4510.594046535827;4546.204414227497;4547.63618910493;4268.59448352406;4359;4387.473503179639;4438.778061096997;4443.3334999749;4360.912781744284;4366.25773618492;4474.507804141607;4525.683202175816;4588.357629931746;4255.1915136124035;4285.828566857415;4316.762578058237;4371.388005679144;4381.912361752683;4211.4525111735275;4239.915201696011;4366.323219900921;4433.179861725591;4439.445069366329;4232.32282834745;4249.426327445755;4305.806238719202;4313.719608225455;4315;4148.336266197514;4185.83256669742;4195.328747400401;4218.957810421979;4269.594388533084;3876.4185372194397;4026.456428382131;4113.547509773863;4173.9582604173565;4212.136512015091;4164.4586203793315;4235.491740990978;4255.957440425556;4301.290287102593;4306.2248795217165;4076.1236334188293;4163.3963075353295;4244.3209086546;4245.066085461193;4250.213710463574 08:20:06 INFO - PROCESS | 1784 | |1;ext.html;538.6660270632209;550.8788066625347;561.5255109432529;570.3013808085096;570.6490508337371;32175.839120804394;35918.82040589797;37087;37108.99028631134;37130;33976;34276.48585271223;34390.140246493786;34825.47761783569;34877;6036.577439579242;7052.153741551021;7061.788146355618;7078.964605176992;7095.43236541078;6871.312868713114;7009.264027277139;7026.824329391755;7032.613206273653;7054.329838665319;6953.721851125949;7083.114610673666;7103.786886393391;7105.715771369165;7124.287571242886;6853.588784672911;7000.92999070013;7027.367536921676;7072.434205263567;7082.327178917994;6966.512344135913;7025.718971241145;7070.257622949568;7079.964600177018;7103.644817759143;32549.6745032549;32577.511337329957;32801.17997050069;32841.17897052569;32978.34043319144;2565.9871700641565;2655.6282120503147;2658.840469571832;2678.08944958715;2727.7408646178583;24841.378965525826;25033.87483062591;25299.241022769347;25353.61969570458;25878.22365329043;2959.113961293151;3104.798188117765;3108.1763332716846;3168.8732450702173;3205.5832741743657;198502;200670;201040;202180;202516;43716.562834371885;43860.78069609631;43946.560534394885;44015.77992110019;44186;25173.748262517507;27378;27487.17538473849;43755;47130.82172945635;9845.163161131384;9898.307118501636;9910.801783964425;9923.553440095213;9932.503374831229;7246.565206087652;7353.705851765976;7404.703811847466;7420.5547667140145;7432.219616940251;14777.335019924129;14960.326785294688;15260.923695381452;15274.236288185546;15339.156346400832;5903.35063143054;5937;5937.970310148422;6018.097285407136;6077.726502307407;718.8634159509677;813.9865866995628;841.5035129273758;861.0227391910101;942.4580866002076;22504.88747556252;24233.66714830666;24400.14599489045;24414.145504907243;24568;789.7907054630527;800.4436916343086;801.2588355770889;857.121450513229;943.2548286853446;12299.877001230052;12502.624921252376;12613.117081804368;12625.494980200689;12683.175593586388;282703;289540;292260;292720;294683;40867.59132408697;41063.794681026404;41353.172936541705;41431.171376572296;41632;1026.7997740440542;1033.9327943683636;1040.99479502602;1051.8369652703843;1063.2663462211049;22604.547909042056;22674.65988010148;23191.88404057969;23490.530189396457;23509.294721158392;35176.296474069866;35470.82264588764;35726.821365894044;35955;35984.10039749027;279498;281339.7799033057;281352;281383;282310;19998.400047998584;20585.89707051455;20726.792732072485;20807.375778726662;20827;34995.47506787349;36886.28262619298;37796.732196068326;38190.23619527649;38209.2358152841;30763.538546921365;30796.846015769777;30970;31023.689763102982;32495.18762030968;30215.697843022168;30852.22869428282;31184.688153119085;32276.76942379678;32310.515342269413;24908.252752417455;25799.226023219333;29612.851935740182;30377.392452151274;30534.694653054074;27276.77254523591;27353;29917.252068698457;30251;30397.240068997566;21800.56398871982;23264;23391.76608233964;23695.28914132579;23906.880465597562 08:20:06 INFO - PROCESS | 1784 | |2;jquery.html;298.7789036113277;301.5401512693143;314.1141979617479;314.5077953003549;321.92273854274964;11338.943305283463;11415.31508109514;14775.556733298004;15910.840891591095;16000.919995400021;11131.554737810498;15142.394304227846;15508.844911550881;15570.844291557081;15592.06447613144;4623.42207224097;4780.689255198401;4837.0567739290855;4841.055994081155;4863.902721945559;4622.560856718606;4654.534546545352;4672.415948006499;4704.106219818241;4724.763761811912;4754.690945088558;5054.393472783253;5055.595552355802;5079.314292570492;5085.593152547787;4994.475580064077;5007.349044624176;5013.248012798073;5017.247412888078;5025.321581586476;4167.062410957541;4420.07178492517;4439.089986552749;4933.1120398328285;4951.3810773653295;10551.208659350503;10695.732606684818;10714.196435267386;10757.139428845752;10774.622888198875;1572.913489758063;1582.5647946814604;1606.4216881922498;1606.9357225710958;1607.7025750236244;7491.925080749177;7918.287354138098;7924.247196516321;8063.596820159028;8142.389320800904;1431.5919962810653;1462.7147706197295;1485.814273215848;1521.8782497400182;1563.257452709965;59400.70299648474;63065.36934630641;64402.35597644011;65082.34917650858;65597.67201163963;8045;8125.268725814705;8446.366522510714;8500.532470714046;8521.872171917426;11007.834882476769;11064.391458469861;11095.83356249657;11103.500342484607;11127.833082503768;5479.53423958968;5524.171374293888;5646.124850648156;5680.8863822723315;5686.118651609007;5191.195364718475;5194.870128246748;5268.92096618551;5310.495502927216;5351.973240133774;3487.302539492112;3562.287542491512;3593.3531964246686;3644.1618427761764;3695.0762309422644;1481.2445652716976;1541.9229038548028;1542.5757916573032;1547.8452154784432;1563.7654351847314;308.16640986132455;316.4209496621224;716.7921302822123;732.1689881983987;782.7808213700173;8575.442596231225;8675.73972780805;8907.064758200426;15206.771898421532;16239.51281461558;729.4601994524014;730.0545793197822;757.6893473675876;771.6758961236294;771.7568965775763;8358.373122015752;8667.089955554702;8751.737447876574;8876.8668469973;8934.374593778373;282776;284047;286029;287066;287670;53592;72165.27834721586;72800.90798638025;73263.63368183232;73344.63327683328;761.5811303782897;779.0690125300212;787.4487858499074;789.6051974012994;791.5962858941981;13733.176009439465;14008.789868151782;14223.857761422254;14227.857721422653;14555.27223638835;36426;36704.08239794026;36776;37206.627933720316;37404.43893341548;48445.75777121092;49516;50014.74992625014;51232;52000.43976692359;6036.607620504653;6095.1771510845465;6113.969430152821;6187.102870083722;6202.255729312511;9216.769580760534;9270.490123043297;9764.951175244078;9799.804003920024;9805.509724513888;7987.800304992422;8164.5509496975865;8217.506949583045;8421.747347579583;8535.189157030072;7811.179826118403;8010.277790275119;8262.710805121791;8309.252167304972;8639.6976105836;7806.180351063056;8009.879851802344;8300.501969881827;8611.569421529024;8754.124587541195;6250.156228909221;6348.301686814354;6348.5238607105575;6451.580647258101;6507.0564768107415;8097.878531822145;8273.710420135267;8366.581670916552;8464.280536154496;8552.700655477029 08:20:06 INFO - PROCESS | 1784 | |3;mootools.html;1159.4202898550725;1165.2542372881358;1185.3480585677876;1351.9053666243356;1400.4958215042589;4697.953020469794;5028.346314979057;5513.696746678931;5922.289325280967;5926.970365148173;5634.943650563493;5780.277465316835;5857.707114644261;5937.554683398741;5965.43328383804;1706.4539347408804;1735.1237624999421;1744.3022790883686;1745.903975281359;1746.554628569713;1691.7377806440022;1732.705440075184;1743.328818404917;1744.6161844394212;1763.347561402283;1696.9830301697011;1710.7690461787686;1713.074939532651;1715.6997525433062;1719.6732620802009;1602.8317026712143;1629.9266533006044;1642.983570164295;1666.6000159961643;1666.6250093728813;1673.205227516932;1683.2088918208422;1693.9068351240676;1693.9322427102902;1700.1074435921116;5565.499105080522;5593.3847276799515;5655.406182350876;5706.9714651426475;5727.4845263926445;3328.4508056170703;3442.4836274559016;3443.104792753877;3448.5689288838894;3450.0857272822473;1972.6843705007273;2033.4916270932267;2218.179273668753;2241.69737085493;2335.474518233384;708.8121647763347;733.0214164090892;733.0763238319702;743.9181690014094;766.835130446951;7112.110986126734;7336.669849856769;7370.410367170613;7372.631368431557;7401.592912389817;3149.1812128846664;3249.2364294391273;3284.917877053093;3291;3306.322203948185;6509.153810004669;6559.409653131241;6567.310432404624;6594.142761441078;6598.406143447113;3412.6587341265677;3446.913827154341;3541.8937431877084;3552.200754830194;3572.1426857554;3151.5115157150676;3195.344954284366;3196.4246435641344;3196.872125115016;3201.327721178532;2718.266068161625;2750.40866213761;2785.791065670083;2805.060304797875;2818.3236023354657;936.7587945971615;951.0346997797242;955.1833182628752;955.2644463763003;961.2214106573698;621.1304174156127;663.2969052804043;705.1643802094534;711.9822004449827;722.1334398721555;3676.7426280160666;3689.5019172411917;3770.0951771574623;3814.5803961564206;3923.9019024524046;712.9572225666477;726.106888527104;729.4128226777495;752.6387334079564;774.9070111586645;2592.974070259273;2593.961090583605;2594.1050337633487;2609.582466805302;2656.4421471491205;6207.379262073847;6691.631960242136;6762.763303284361;6894.793156205322;6981.720731170798;6412.647304398303;6605.669716514155;6633.966830165915;6634.900476492857;6643.568168069157;5422.240886275925;5555.2222688822785;5567.331920169525;5577.191307260506;5578.107502799614;3859.6719278860487;3914.569397366344;3926.6269704378037;3968.7023473239046;3980.7810570417746;6198.194234749454;6304.842378940563;6395.808125756234;6494.220693516808;6540.476761859135;6226.688665566794;6443;6454.774082907075;6461.838454038687;6522.445592124723;4871.34236878017;4904.681195722266;4917.262410638433;5030.673006254582;5035.219540971156;5331.4401987790725;5364.892702146013;5475.589330800126;5512.035393806148;5514.228008078792;4985;5102.974485127551;5137.075326441276;5164.302819119371;5166.870828229325;4840.959193773356;4921.655484116078;4949.183384741477;4965.975170124127;4978.178600530872;4494.640428765756;4520.819167233274;4522.29904364824;4570.81716731327;4575.382323386433;3392.5589673342306;3402.285520040819;3402.6937575618313;3417.8974630761118;3426.417509023422;3416.641252668434;3428.2286485540553;3487.89536313911;3514.8594056238485;3516.9648303516637 08:20:06 INFO - PROCESS | 1784 | |4;prototype.html;236.7100302129891;236.72658079917701;242.8470063859768;244.78214389193622;251.00851636037643;4409.823607055714;4504;4512.864614061579;4559.794809233585;4566.84016059438;4412.735235885841;4500.4824445188815;4503.459584849815;4516.18708632446;4528.343390208418;3311.966880331202;3319.7344212463067;3334.6665333466703;3338.5993680758343;3346.916327091818;3316.535685004101;3316.535685004101;3328.4175269327893;3337.566116404864;3337.9652307784663;3271.6891895270032;3284.983575082121;3287.6712328767053;3310.950335744954;3316.1709572606846;3196.137042998389;3214.260720034382;3286.981035878885;3304.4052070627276;3306.619738730041;2912.22825951121;2917.095700332882;2917.993292314148;2923.824570525754;2924.3712601790503;4343.761093139876;4404.2512772828695;4405.845795397177;4406.5593440656;4415.977920110379;3870.806459677033;3917.3928041153667;3923.4114882767526;3924.1759230561615;3927.725059245854;4670.346151538787;4670.649701272384;4675.696079754805;4680.531946805327;4689.179393606105;3171.7462602991704;3219.8229097399635;3229.838508074587;3248.2366643838677;3276.541284220211;4844.515548445127;4846.61227101831;4847;4855.660103792736;4870.391201099863;3328.700416962485;3332.4001679697403;3338.5659864217496;3349.581302337208;3354.2955979244143;3372.9494057589154;3383.796972181677;3384.695377415995;3395.4567269237295;3397.7281817454545;3912.5109361329833;3913.6477717005605;3918.275119102975;3919.1769728356794;3919.510061242345;4407.603315701538;4671.15919134559;4703.365045718852;4705.482396936334;4709.222978208626;1086.2559146984183;1110.5335758981248;1112.1825458288151;1114.414932160606;1115.3475216997986;720.0423436829005;736.8710475666737;744.3524134003452;745.3776096958992;747.8093086262968;4430.357598148251;4559.589636932692;4571.291449825216;4586.816527338936;4586.88532786683;4872.439669437989;4883.462819089968;4895.31465594817;4912.582430493377;4914.508549145128;4557.361969324297;4569.13186494565;4570.349197465529;4574.199515084847;4575.519570445055;2448.461338505526;2522.823402361854;2552.5660637691635;2553.8084643651428;2566.93582660435;4917.87705307363;4921.409430868319;4928.753562321869;4935.555799978019;4936.333594964705;4842.878928026757;4884.804607815648;4886.828960986349;4892.486288939681;4902.313676085351;4909.582685471775;4952.801887924515;4953.628477864175;4953.925691114636;4969.776360063805;4543.545645435563;4726.220173671306;4762.404699412574;4784.593309568726;4794.880127996828;4688.203005489143;4701.459332176843;4703.788329525111;4703.858884233478;4715.457722361973;4899.240617704261;4915.21356582945;4930.285108659153;4934.309196712535;4937.185364414831;3175.4760464523097;3180.538821870862;3185.9840700797276;3194.1695159258297;3215.8070515769127;4575.99328147807;4772.752250497129;4774.379330686988;4782.641301902302;4793.32893394918;4573.268277075652;4584.426946631671;4588.632909367176;4598.678092533491;4605.815767369401;4588.1511920293515;4598.896264896334;4600.723956562483;4623.791929363119;4624.606908412688;4503.275518311374;4508.661850361302;4514.661400394918;4519.59323660859;4530.252508336088;4490.550944905614;4647.349371088118;4647.55848194421;4653.092646933816;4656.952185758313;4553.11214313219;4555.9772201138785;4596.172688916027;4689.437267527918;4712.599429048432 08:20:06 INFO - PROCESS | 1784 | |5;yui.html;534.5216031651672;535.3976776126857;537.7902617978991;570.5606682854203;632.8892443822333;11468;12299.38503074845;12341.321227332493;12371.443285052172;12405.44175512103;10710.089642380388;11185.85661847328;11312;11420.657380278604;11462.828057579121;615.5752530753776;628.7736414890659;631.1195881744964;633.9017452294879;634.4670476799488;496.2704823908866;496.7044608457962;628.5003422279299;630.9022101574263;630.977915772948;641.2657507154305;654.9312322206148;655.596807963104;660.1022609251414;660.247318057415;633.4267487923437;644.7839973608847;645.7804346522181;646.8673921846034;647.9902801457982;474.90501899620216;482.10569393774745;482.59944246275506;483.25578608942186;483.47059969333526;11930.10524210679;11933.582324618597;11934.582289619822;11952.521899124025;11957.402129893557;11307.378094204816;11363.545458181663;11401.600943966921;11497.252678575866;11676.532938682443;8745.956270218672;9019.819603607957;9029.819403611957;9241.214496767783;9446.480443575601;2780.819246748955;2787.8048536602446;2898.811577247472;2916.300087978891;2962.86667099981;48544.75727621375;49089.263661045115;49382.7530862347;49868.75065624685;50265;8182.509049456932;8604.397692161614;8708.129187081367;9160.129787670161;9211.677591284273;15919.761203581955;16009.839901601068;16059.678806424039;16576.088315142784;16592.83407165937;5777.971110144506;5887.499562537147;5953.880922381528;5986.2517185351835;6011.549133814894;2964.3626620276314;3116.1897906544464;3129.123845323313;3139.3093519426147;3232.2565809863863;2393.5332610140854;2525.9012329636666;2527.8230523863153;2534.176392672389;2534.505771374564;409.5065446137397;413.23138961531527;416.7332906939561;417.2864401872771;420.4114240063935;411.95468498465146;482.6331987689311;482.66937148053694;484.10923900024125;523.4007061914164;6741.460683145435;6808.62552559614;6920.411764999931;6927.307269273032;7208.24313447091;412.702853945159;441.9513853476115;467.8269040454985;471.6604045087532;476.92130798418566;3276.115448828803;3276.950845737316;3360;3370.3090866372336;3370.443876760307;61323;66608.6669566649;67986.320136798;69040;69505.65247173731;60785.696071519356;60886.695566521885;61889.690551546955;63166.68416657979;64059;56227.71886140543;56241.43758562444;56583.71708141433;57048.42951570514;57766.8446631065;6462.515311351762;6479;6532.902006469812;6564.6389448580785;6599.769008084694;33482;39214.41178382384;39273.214535708554;39794.60205397909;40161.19677606373;41039.1792164161;41726.37410438906;42656.14687706291;47118.76440617775;47347.76326118347;8606.44058136219;8689.782755431164;8917.777055573662;8918.777030574287;9068.863967040368;9231.815363692824;9326;9330.906690933003;9827.31208815376;10036.297459177787;9320.487373194539;9384.29617778683;9477.668281610111;9508.619655213713;9516.143547080796;9012;9130.95434522823;9148.862767058366;9695.70912872615;9702.70891873245;9026.18764311215;9065.592048357708;9067.229285510542;9182.724518264462;9234.399764015317;8038.075621303625;8052.758417247492;8110.594470276581;8183.631736571988;8332.833343333221;8389.874151887849;8401.95799021001;8520.659173632985;8715.30277577805;8767.824643507221 08:20:06 INFO - PROCESS | 1784 | __end_tp_report 08:20:06 INFO - PROCESS | 1784 | __start_cc_report 08:20:06 INFO - PROCESS | 1784 | _x_x_mozilla_cycle_collect,304 08:20:06 INFO - PROCESS | 1784 | __end_cc_report 08:20:06 INFO - PROCESS | 1784 | __startTimestamp1479831606062__endTimestamp 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | ------- Summary: start ------- 08:20:06 INFO - PROCESS | 1784 | Number of tests: 6 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#0] dojo.html Cycles:165 Average:4013.10 Median:4188.10 stddev:488.15 (11.7%) stddev-sans-first:473.28 08:20:06 INFO - PROCESS | 1784 | Values: 2415.2 2416.0 2456.7 2470.1 2471.8 4002.8 4105.7 4144.9 4195.3 4212.0 3994.2 4041.0 4183.3 4195.2 4276.9 3618.5 3626.9 3633.0 3644.5 3692.3 3257.8 3683.0 3687.2 3691.5 3695.1 3505.5 3565.6 3582.3 3636.2 3656.9 3524.6 3608.0 3610.0 3616.8 3620.0 3589.2 3634.9 3643.4 3648.1 3699.3 4031.7 4049.0 4127.1 4185.4 4188.1 4298.3 4326.5 4344.5 4362.5 4394.5 4379.9 4441.1 4450.2 4470.8 4471.9 3925.2 4078.0 4081.5 4161.6 4177.9 4272.7 4328.1 4411.4 4418.8 4477.7 4242.3 4260.0 4273.6 4285.9 4335.0 4244.3 4391.2 4397.7 4412.8 4413.2 4152.1 4166.1 4174.4 4287.0 4293.4 4262.7 4296.2 4336.6 4341.1 4341.2 3042.8 3045.3 3072.4 3083.1 3108.6 2741.5 2746.4 2775.3 2778.0 2796.9 4027.6 4046.9 4108.8 4109.5 4150.5 4239.5 4322.3 4372.1 4402.2 4419.5 3941.8 3965.6 4022.1 4143.4 4177.9 3706.6 3711.7 3750.6 3769.2 3805.4 4427.8 4438.7 4510.6 4546.2 4547.6 4268.6 4359.0 4387.5 4438.8 4443.3 4360.9 4366.3 4474.5 4525.7 4588.4 4255.2 4285.8 4316.8 4371.4 4381.9 4211.5 4239.9 4366.3 4433.2 4439.4 4232.3 4249.4 4305.8 4313.7 4315.0 4148.3 4185.8 4195.3 4219.0 4269.6 3876.4 4026.5 4113.5 4174.0 4212.1 4164.5 4235.5 4256.0 4301.3 4306.2 4076.1 4163.4 4244.3 4245.1 4250.2 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#1] ext.html Cycles:180 Average:38859.34 Median:32689.35 stddev:68441.19 (209.4%) stddev-sans-first:68572.71 08:20:06 INFO - PROCESS | 1784 | Values: 538.7 550.9 561.5 570.3 570.6 32175.8 35918.8 37087.0 37109.0 37130.0 33976.0 34276.5 34390.1 34825.5 34877.0 6036.6 7052.2 7061.8 7079.0 7095.4 6871.3 7009.3 7026.8 7032.6 7054.3 6953.7 7083.1 7103.8 7105.7 7124.3 6853.6 7000.9 7027.4 7072.4 7082.3 6966.5 7025.7 7070.3 7080.0 7103.6 32549.7 32577.5 32801.2 32841.2 32978.3 2566.0 2655.6 2658.8 2678.1 2727.7 24841.4 25033.9 25299.2 25353.6 25878.2 2959.1 3104.8 3108.2 3168.9 3205.6 198502.0 200670.0 201040.0 202180.0 202516.0 43716.6 43860.8 43946.6 44015.8 44186.0 25173.7 27378.0 27487.2 43755.0 47130.8 9845.2 9898.3 9910.8 9923.6 9932.5 7246.6 7353.7 7404.7 7420.6 7432.2 14777.3 14960.3 15260.9 15274.2 15339.2 5903.4 5937.0 5938.0 6018.1 6077.7 718.9 814.0 841.5 861.0 942.5 22504.9 24233.7 24400.1 24414.1 24568.0 789.8 800.4 801.3 857.1 943.3 12299.9 12502.6 12613.1 12625.5 12683.2 282703.0 289540.0 292260.0 292720.0 294683.0 40867.6 41063.8 41353.2 41431.2 41632.0 1026.8 1033.9 1041.0 1051.8 1063.3 22604.5 22674.7 23191.9 23490.5 23509.3 35176.3 35470.8 35726.8 35955.0 35984.1 279498.0 281339.8 281352.0 281383.0 282310.0 19998.4 20585.9 20726.8 20807.4 20827.0 34995.5 36886.3 37796.7 38190.2 38209.2 30763.5 30796.8 30970.0 31023.7 32495.2 30215.7 30852.2 31184.7 32276.8 32310.5 24908.3 25799.2 29612.9 30377.4 30534.7 27276.8 27353.0 29917.3 30251.0 30397.2 21800.6 23264.0 23391.8 23695.3 23906.9 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#2] jquery.html Cycles:180 Average:19634.78 Median:5082.45 stddev:47929.50 (943.0%) stddev-sans-first:48041.97 08:20:06 INFO - PROCESS | 1784 | Values: 298.8 301.5 314.1 314.5 321.9 11338.9 11415.3 14775.6 15910.8 16000.9 11131.6 15142.4 15508.8 15570.8 15592.1 4623.4 4780.7 4837.1 4841.1 4863.9 4622.6 4654.5 4672.4 4704.1 4724.8 4754.7 5054.4 5055.6 5079.3 5085.6 4994.5 5007.3 5013.2 5017.2 5025.3 4167.1 4420.1 4439.1 4933.1 4951.4 10551.2 10695.7 10714.2 10757.1 10774.6 1572.9 1582.6 1606.4 1606.9 1607.7 7491.9 7918.3 7924.2 8063.6 8142.4 1431.6 1462.7 1485.8 1521.9 1563.3 59400.7 63065.4 64402.4 65082.3 65597.7 8045.0 8125.3 8446.4 8500.5 8521.9 11007.8 11064.4 11095.8 11103.5 11127.8 5479.5 5524.2 5646.1 5680.9 5686.1 5191.2 5194.9 5268.9 5310.5 5352.0 3487.3 3562.3 3593.4 3644.2 3695.1 1481.2 1541.9 1542.6 1547.8 1563.8 308.2 316.4 716.8 732.2 782.8 8575.4 8675.7 8907.1 15206.8 16239.5 729.5 730.1 757.7 771.7 771.8 8358.4 8667.1 8751.7 8876.9 8934.4 282776.0 284047.0 286029.0 287066.0 287670.0 53592.0 72165.3 72800.9 73263.6 73344.6 761.6 779.1 787.4 789.6 791.6 13733.2 14008.8 14223.9 14227.9 14555.3 36426.0 36704.1 36776.0 37206.6 37404.4 48445.8 49516.0 50014.7 51232.0 52000.4 6036.6 6095.2 6114.0 6187.1 6202.3 9216.8 9270.5 9765.0 9799.8 9805.5 7987.8 8164.6 8217.5 8421.7 8535.2 7811.2 8010.3 8262.7 8309.3 8639.7 7806.2 8009.9 8300.5 8611.6 8754.1 6250.2 6348.3 6348.5 6451.6 6507.1 8097.9 8273.7 8366.6 8464.3 8552.7 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#3] mootools.html Cycles:180 Average:3771.72 Median:4769.46 stddev:1994.36 (41.8%) stddev-sans-first:1990.29 08:20:06 INFO - PROCESS | 1784 | Values: 1159.4 1165.3 1185.3 1351.9 1400.5 4698.0 5028.3 5513.7 5922.3 5927.0 5634.9 5780.3 5857.7 5937.6 5965.4 1706.5 1735.1 1744.3 1745.9 1746.6 1691.7 1732.7 1743.3 1744.6 1763.3 1697.0 1710.8 1713.1 1715.7 1719.7 1602.8 1629.9 1643.0 1666.6 1666.6 1673.2 1683.2 1693.9 1693.9 1700.1 5565.5 5593.4 5655.4 5707.0 5727.5 3328.5 3442.5 3443.1 3448.6 3450.1 1972.7 2033.5 2218.2 2241.7 2335.5 708.8 733.0 733.1 743.9 766.8 7112.1 7336.7 7370.4 7372.6 7401.6 3149.2 3249.2 3284.9 3291.0 3306.3 6509.2 6559.4 6567.3 6594.1 6598.4 3412.7 3446.9 3541.9 3552.2 3572.1 3151.5 3195.3 3196.4 3196.9 3201.3 2718.3 2750.4 2785.8 2805.1 2818.3 936.8 951.0 955.2 955.3 961.2 621.1 663.3 705.2 712.0 722.1 3676.7 3689.5 3770.1 3814.6 3923.9 713.0 726.1 729.4 752.6 774.9 2593.0 2594.0 2594.1 2609.6 2656.4 6207.4 6691.6 6762.8 6894.8 6981.7 6412.6 6605.7 6634.0 6634.9 6643.6 5422.2 5555.2 5567.3 5577.2 5578.1 3859.7 3914.6 3926.6 3968.7 3980.8 6198.2 6304.8 6395.8 6494.2 6540.5 6226.7 6443.0 6454.8 6461.8 6522.4 4871.3 4904.7 4917.3 5030.7 5035.2 5331.4 5364.9 5475.6 5512.0 5514.2 4985.0 5103.0 5137.1 5164.3 5166.9 4841.0 4921.7 4949.2 4966.0 4978.2 4494.6 4520.8 4522.3 4570.8 4575.4 3392.6 3402.3 3402.7 3417.9 3426.4 3416.6 3428.2 3487.9 3514.9 3517.0 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#4] prototype.html Cycles:180 Average:3891.83 Median:4536.90 stddev:1187.56 (26.2%) stddev-sans-first:1158.77 08:20:06 INFO - PROCESS | 1784 | Values: 236.7 236.7 242.8 244.8 251.0 4409.8 4504.0 4512.9 4559.8 4566.8 4412.7 4500.5 4503.5 4516.2 4528.3 3312.0 3319.7 3334.7 3338.6 3346.9 3316.5 3316.5 3328.4 3337.6 3338.0 3271.7 3285.0 3287.7 3311.0 3316.2 3196.1 3214.3 3287.0 3304.4 3306.6 2912.2 2917.1 2918.0 2923.8 2924.4 4343.8 4404.3 4405.8 4406.6 4416.0 3870.8 3917.4 3923.4 3924.2 3927.7 4670.3 4670.6 4675.7 4680.5 4689.2 3171.7 3219.8 3229.8 3248.2 3276.5 4844.5 4846.6 4847.0 4855.7 4870.4 3328.7 3332.4 3338.6 3349.6 3354.3 3372.9 3383.8 3384.7 3395.5 3397.7 3912.5 3913.6 3918.3 3919.2 3919.5 4407.6 4671.2 4703.4 4705.5 4709.2 1086.3 1110.5 1112.2 1114.4 1115.3 720.0 736.9 744.4 745.4 747.8 4430.4 4559.6 4571.3 4586.8 4586.9 4872.4 4883.5 4895.3 4912.6 4914.5 4557.4 4569.1 4570.3 4574.2 4575.5 2448.5 2522.8 2552.6 2553.8 2566.9 4917.9 4921.4 4928.8 4935.6 4936.3 4842.9 4884.8 4886.8 4892.5 4902.3 4909.6 4952.8 4953.6 4953.9 4969.8 4543.5 4726.2 4762.4 4784.6 4794.9 4688.2 4701.5 4703.8 4703.9 4715.5 4899.2 4915.2 4930.3 4934.3 4937.2 3175.5 3180.5 3186.0 3194.2 3215.8 4576.0 4772.8 4774.4 4782.6 4793.3 4573.3 4584.4 4588.6 4598.7 4605.8 4588.2 4598.9 4600.7 4623.8 4624.6 4503.3 4508.7 4514.7 4519.6 4530.3 4490.6 4647.3 4647.6 4653.1 4657.0 4553.1 4556.0 4596.2 4689.4 4712.6 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - PROCESS | 1784 | [#5] yui.html Cycles:180 Average:13821.63 Median:57407.64 stddev:18508.09 (32.2%) stddev-sans-first:18533.12 08:20:06 INFO - PROCESS | 1784 | Values: 534.5 535.4 537.8 570.6 632.9 11468.0 12299.4 12341.3 12371.4 12405.4 10710.1 11185.9 11312.0 11420.7 11462.8 615.6 628.8 631.1 633.9 634.5 496.3 496.7 628.5 630.9 631.0 641.3 654.9 655.6 660.1 660.2 633.4 644.8 645.8 646.9 648.0 474.9 482.1 482.6 483.3 483.5 11930.1 11933.6 11934.6 11952.5 11957.4 11307.4 11363.5 11401.6 11497.3 11676.5 8746.0 9019.8 9029.8 9241.2 9446.5 2780.8 2787.8 2898.8 2916.3 2962.9 48544.8 49089.3 49382.8 49868.8 50265.0 8182.5 8604.4 8708.1 9160.1 9211.7 15919.8 16009.8 16059.7 16576.1 16592.8 5778.0 5887.5 5953.9 5986.3 6011.5 2964.4 3116.2 3129.1 3139.3 3232.3 2393.5 2525.9 2527.8 2534.2 2534.5 409.5 413.2 416.7 417.3 420.4 412.0 482.6 482.7 484.1 523.4 6741.5 6808.6 6920.4 6927.3 7208.2 412.7 442.0 467.8 471.7 476.9 3276.1 3277.0 3360.0 3370.3 3370.4 61323.0 66608.7 67986.3 69040.0 69505.7 60785.7 60886.7 61889.7 63166.7 64059.0 56227.7 56241.4 56583.7 57048.4 57766.8 6462.5 6479.0 6532.9 6564.6 6599.8 33482.0 39214.4 39273.2 39794.6 40161.2 41039.2 41726.4 42656.1 47118.8 47347.8 8606.4 8689.8 8917.8 8918.8 9068.9 9231.8 9326.0 9330.9 9827.3 10036.3 9320.5 9384.3 9477.7 9508.6 9516.1 9012.0 9131.0 9148.9 9695.7 9702.7 9026.2 9065.6 9067.2 9182.7 9234.4 8038.1 8052.8 8110.6 8183.6 8332.8 8389.9 8402.0 8520.7 8715.3 8767.8 08:20:06 INFO - PROCESS | 1784 | -------- Summary: end -------- 08:20:06 INFO - PROCESS | 1784 | 08:20:06 INFO - TEST-INFO | 1784: exit 0 08:20:07 INFO - TEST-OK | dromaeo_css | took 1126710ms 08:20:07 INFO - TEST-START | kraken 08:20:07 INFO - operating with platform_type : w7_ 08:20:07 INFO - Initialising browser for kraken test... 08:20:07 INFO - TEST-INFO | started process 3304 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmphojog6\profile http://localhost:49895/getInfo.html) 08:20:15 INFO - PROCESS | 3304 | __metrics Screen width/height:1600/1200 08:20:15 INFO - PROCESS | 3304 | colorDepth:24 08:20:15 INFO - PROCESS | 3304 | Browser inner width/height: 1010/674 08:20:15 INFO - PROCESS | 3304 | __metrics 08:20:17 ERROR - PROCESS | 3304 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 08:20:17 INFO - PROCESS | 3304 | [Child 4040] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w32-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 08:20:17 INFO - PROCESS | 3304 | [Child 4040] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w32-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 08:20:17 INFO - PROCESS | 3304 | console.error: 08:20:17 INFO - PROCESS | 3304 | Message: Error: Connection closed before committing the transaction. 08:20:17 INFO - PROCESS | 3304 | Stack: 08:20:17 INFO - PROCESS | 3304 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 08:20:17 INFO - PROCESS | 3304 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 08:20:17 INFO - PROCESS | 3304 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 08:20:17 INFO - PROCESS | 3304 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 08:20:17 INFO - PROCESS | 3304 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 08:20:17 INFO - PROCESS | 3304 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 08:20:17 INFO - PROCESS | 3304 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 08:20:17 INFO - PROCESS | 3304 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 08:20:17 INFO - PROCESS | 3304 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 08:20:17 INFO - PROCESS | 3304 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 08:20:17 INFO - PROCESS | 3304 | Barrier/this.client.addBlocker/promise13.5% 08:21:19 INFO - TinderboxPrint: I/O read bytes / time
80,899,072 / 1,851,990 08:21:19 INFO - TinderboxPrint: I/O write bytes / time
553,242,112 / 4,598,710 08:21:19 INFO - TinderboxPrint: CPU idle
8,363.7 (86.5%) 08:21:19 INFO - TinderboxPrint: CPU user
1,252.5 (13.0%) 08:21:19 INFO - install - Wall time: 8s; CPU: 3.0%; Read bytes: 0; Write bytes: 89587200; Read time: 0; Write time: 988100 08:21:19 INFO - run-tests - Wall time: 1201s; CPU: 14.0%; Read bytes: 80899072; Write bytes: 455913472; Read time: 1851990; Write time: 3347390 08:21:19 INFO - Running post-run listener: _upload_blobber_files 08:21:19 INFO - Blob upload gear active. 08:21:19 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 08:21:19 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:21:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 08:21:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 08:21:20 INFO - (blobuploader) - INFO - Open directory for files ... 08:21:20 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 08:21:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:21:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:21 INFO - (blobuploader) - INFO - Done attempting. 08:21:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 08:21:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:21:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:22 INFO - (blobuploader) - INFO - Done attempting. 08:21:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 08:21:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:21:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:21:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:21:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:21:30 INFO - (blobuploader) - INFO - Done attempting. 08:21:30 INFO - (blobuploader) - INFO - Iteration through files over. 08:21:30 INFO - Return code: 0 08:21:30 INFO - rmtree: C:\slave\test\build\uploaded_files.json 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 08:21:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dec3824d8d2b5a1e9ffb917d87ce5756d8708c553fd9977da3fc678acf377b0cfeb2d182a20b5686b40a071bb4b3ca20fec4a9c8f716c873846df4fab3102572", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cf275f5f4653530616eee725fb6e0606fed13ee054541306fa195e3cfeacf2d70bf0c77b70fb32290d282ed071f63abb02fbe0f22eaab67cb2d4928b75587d9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3bd652a58059f3211d1b6469a960681ed6e6f14742ffaca29d91c352d9a60e25060194a062755a3f104668d38b1cbca0c3039e07756efac96db0fb2e1307ae5"} 08:21:30 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 08:21:30 INFO - Writing to file C:\slave\test\properties\blobber_files 08:21:30 INFO - Contents: 08:21:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dec3824d8d2b5a1e9ffb917d87ce5756d8708c553fd9977da3fc678acf377b0cfeb2d182a20b5686b40a071bb4b3ca20fec4a9c8f716c873846df4fab3102572", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cf275f5f4653530616eee725fb6e0606fed13ee054541306fa195e3cfeacf2d70bf0c77b70fb32290d282ed071f63abb02fbe0f22eaab67cb2d4928b75587d9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3bd652a58059f3211d1b6469a960681ed6e6f14742ffaca29d91c352d9a60e25060194a062755a3f104668d38b1cbca0c3039e07756efac96db0fb2e1307ae5"} 08:21:30 INFO - Running post-run listener: copy_logs_to_upload_dir 08:21:30 INFO - Copying logs to upload dir... 08:21:30 INFO - mkdir: C:\slave\test\build\upload\logs 08:21:30 INFO - Copying logs to upload dir... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... 08:21:30 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1504.279000 ========= master_lag: 0.28 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 4 secs) (at 2016-11-22 08:21:30.464145) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:30.470458) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dec3824d8d2b5a1e9ffb917d87ce5756d8708c553fd9977da3fc678acf377b0cfeb2d182a20b5686b40a071bb4b3ca20fec4a9c8f716c873846df4fab3102572", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cf275f5f4653530616eee725fb6e0606fed13ee054541306fa195e3cfeacf2d70bf0c77b70fb32290d282ed071f63abb02fbe0f22eaab67cb2d4928b75587d9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3bd652a58059f3211d1b6469a960681ed6e6f14742ffaca29d91c352d9a60e25060194a062755a3f104668d38b1cbca0c3039e07756efac96db0fb2e1307ae5"} build_url:https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/YjQ9K8ZdRLy7ekjTnqt6EA/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dec3824d8d2b5a1e9ffb917d87ce5756d8708c553fd9977da3fc678acf377b0cfeb2d182a20b5686b40a071bb4b3ca20fec4a9c8f716c873846df4fab3102572", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cf275f5f4653530616eee725fb6e0606fed13ee054541306fa195e3cfeacf2d70bf0c77b70fb32290d282ed071f63abb02fbe0f22eaab67cb2d4928b75587d9f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e3bd652a58059f3211d1b6469a960681ed6e6f14742ffaca29d91c352d9a60e25060194a062755a3f104668d38b1cbca0c3039e07756efac96db0fb2e1307ae5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:30.584888) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:30.585233) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-015 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-015 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:30.695589) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:30.695919) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-22 08:21:31.227131) ========= ========= Total master_lag: 0.49 =========