builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-030 starttime: 1477013504.0 results: success (0) buildid: 20161020153022 builduid: 2f5a59bc02c84ee5be6ffd67bff0b271 revision: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:31:43.997296) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 18:31:43.997762) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:31:43.998048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 18:31:44.196425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 18:31:44.196811) ========= '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-030 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-030 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-030 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 --18:31:44-- 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% 2.46 GB/s 18:31:44 (2.46 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.049000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 18:31:45.264794) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 21 secs) (at 2016-10-20 18:31:45.265089) ========= '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-030 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-030 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-030 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=15.516000 ========= master_lag: 5.78 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 21 secs) (at 2016-10-20 18:32:06.559142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-10-20 18:32:06.559490) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-030 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-030 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-030 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-10-20 18:32:06,428 truncating revision to first 12 chars 2016-10-20 18:32:06,428 Setting DEBUG logging. 2016-10-20 18:32:06,428 attempt 1/10 2016-10-20 18:32:06,428 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ba6fc133264d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 18:32:07,825 unpacking tar archive at: fx-team-ba6fc133264d/testing/mozharness/ 2016-10-20 18:32:07,943 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.197000 ========= master_lag: 4.71 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-10-20 18:32:13.465257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:32:13.465554) ========= script_repo_revision: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 18:32:13.465931) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:32:13.466207) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 18:32:13.483240) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 44 secs) (at 2016-10-20 18:32:13.483566) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-030 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-030 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-030 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 18:32:13 INFO - MultiFileLogger online at 20161020 18:32:13 in C:\slave\test 18:32:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:32:13 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 18:32:13 INFO - {'append_to_log': False, 18:32:13 INFO - 'base_work_dir': 'C:\\slave\\test', 18:32:13 INFO - 'blob_upload_branch': 'Fx-Team', 18:32:13 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 18:32:13 INFO - 'branch': 'Fx-Team', 18:32:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:32:13 INFO - 'config_files': ('talos/windows_config.py',), 18:32:13 INFO - 'default_actions': ('clobber', 18:32:13 INFO - 'read-buildbot-config', 18:32:13 INFO - 'download-and-extract', 18:32:13 INFO - 'populate-webroot', 18:32:13 INFO - 'create-virtualenv', 18:32:13 INFO - 'install', 18:32:13 INFO - 'run-tests'), 18:32:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:32:13 INFO - 'download_minidump_stackwalk': True, 18:32:13 INFO - 'download_symbols': 'ondemand', 18:32:13 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 18:32:13 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 18:32:13 INFO - 'hg': 'c:/mozilla-build/hg/hg', 18:32:13 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 18:32:13 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 18:32:13 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 18:32:13 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 18:32:13 INFO - 'C:/mozilla-build/tooltool.py'), 18:32:13 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 18:32:13 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 18:32:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:32:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:32:13 INFO - 'installer_path': 'installer.exe', 18:32:13 INFO - 'log_level': 'info', 18:32:13 INFO - 'log_name': 'talos', 18:32:13 INFO - 'log_to_console': True, 18:32:13 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 18:32:13 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 18:32:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 18:32:13 INFO - 'opt_config_files': (), 18:32:13 INFO - 'pip_index': False, 18:32:13 INFO - 'sps_profile': False, 18:32:13 INFO - 'sps_profile_interval': 0, 18:32:13 INFO - 'suite': 'dromaeojs', 18:32:13 INFO - 'system_bits': '32', 18:32:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:32:13 INFO - 'title': 'T-W732-IX-030', 18:32:13 INFO - 'use_talos_json': True, 18:32:13 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 18:32:13 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 18:32:13 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 18:32:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:32:13 INFO - 'work_dir': 'build'} 18:32:13 INFO - [mozharness: 2016-10-21 01:32:13.642000Z] Running clobber step. 18:32:13 INFO - Running pre-action listener: _resource_record_pre_action 18:32:13 INFO - Running main action method: clobber 18:32:13 INFO - rmtree: C:\slave\test\build 18:32:13 INFO - Using _rmtree_windows ... 18:32:13 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 18:35:04 INFO - Running post-action listener: _resource_record_post_action 18:35:04 INFO - [mozharness: 2016-10-21 01:35:04.639000Z] Finished clobber step (success) 18:35:04 INFO - [mozharness: 2016-10-21 01:35:04.639000Z] Running read-buildbot-config step. 18:35:04 INFO - Running pre-action listener: _resource_record_pre_action 18:35:04 INFO - Running main action method: read_buildbot_config 18:35:04 INFO - Using buildbot properties: 18:35:04 INFO - { 18:35:04 INFO - "project": "", 18:35:04 INFO - "product": "firefox", 18:35:04 INFO - "script_repo_revision": "production", 18:35:04 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 18:35:04 INFO - "repository": "", 18:35:04 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 18:35:04 INFO - "buildid": "20161020153022", 18:35:04 INFO - "pgo_build": "True", 18:35:04 INFO - "basedir": "C:\\slave\\test", 18:35:04 INFO - "buildnumber": 242, 18:35:04 INFO - "slavename": "t-w732-ix-030", 18:35:04 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 18:35:04 INFO - "platform": "win7_ix", 18:35:04 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 18:35:04 INFO - "branch": "fx-team", 18:35:04 INFO - "repo_path": "integration/fx-team", 18:35:04 INFO - "revision": "ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac", 18:35:04 INFO - "stage_platform": "win32-pgo", 18:35:04 INFO - "builduid": "2f5a59bc02c84ee5be6ffd67bff0b271", 18:35:04 INFO - "slavebuilddir": "test" 18:35:04 INFO - } 18:35:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 18:35:04 INFO - Running post-action listener: _resource_record_post_action 18:35:04 INFO - [mozharness: 2016-10-21 01:35:04.643000Z] Finished read-buildbot-config step (success) 18:35:04 INFO - [mozharness: 2016-10-21 01:35:04.643000Z] Running download-and-extract step. 18:35:04 INFO - Running pre-action listener: _resource_record_pre_action 18:35:04 INFO - Running main action method: download_and_extract 18:35:04 INFO - mkdir: C:\slave\test\build\tests 18:35:04 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')]} 18:35:04 INFO - trying https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 18:35:04 INFO - Downloading https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 18:35:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 18:35:05 INFO - Downloaded 1218 bytes. 18:35:05 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 18:35:05 INFO - Using the following test package requirements: 18:35:05 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:35:05 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 18:35:05 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:35:05 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'jsshell-win32.zip'], 18:35:05 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 18:35:05 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:35:05 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 18:35:05 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 18:35:05 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 18:35:05 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:35:05 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 18:35:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 18:35:05 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 18:35:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:35:05 INFO - Fetch https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:35:06 INFO - Http code: 200 18:35:06 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 18:35:06 INFO - x-amz-cf-id: OsK_BfRMfzpfp2R241Zr0z58AiG1g0hVSp6WD5HETZanKwQa_3VOiw== 18:35:06 INFO - x-amz-version-id: v6qAaR.TOEDFCRmbDPfsoCF10Ek.YNzf 18:35:06 INFO - Expected file size: 17265192 18:35:06 INFO - Obtained file size: 17265192 18:35:43 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 18:35:43 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 18:35:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:35:43 INFO - Fetch https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:35:43 INFO - Http code: 200 18:35:43 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 18:35:43 INFO - x-amz-cf-id: 95Mwgrqq4j5B5WmpCraAI-MAKogDQRvu38_FhD_N1z2miur_pw8V_Q== 18:35:43 INFO - x-amz-version-id: v6qAaR.TOEDFCRmbDPfsoCF10Ek.YNzf 18:35:44 INFO - Expected file size: 17265192 18:35:44 INFO - Obtained file size: 17265192 18:35:56 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 18:35:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 18:35:56 INFO - Fetch https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 18:35:56 INFO - Http code: 200 18:35:56 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 18:35:56 INFO - x-amz-cf-id: cztnsZFMB5BDI2SOF_efE-fcwcgLluy4_XdKQLSZ-4DyVCCkbNY17w== 18:35:56 INFO - x-amz-version-id: Dra8pFXB_Zk843CP8F6IFKQ1qm0xCjb_ 18:35:57 INFO - Expected file size: 14409028 18:35:57 INFO - Obtained file size: 14409028 18:36:00 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')]} 18:36:00 INFO - trying https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:36:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 18:36:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 18:36:02 INFO - Downloaded 54324330 bytes. 18:36:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:36:02 INFO - mkdir: C:\slave\test\properties 18:36:02 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 18:36:02 INFO - Writing to file C:\slave\test\properties\build_url 18:36:02 INFO - Contents: 18:36:02 INFO - build_url:https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:36:02 INFO - Running post-action listener: _resource_record_post_action 18:36:02 INFO - Running post-action listener: set_extra_try_arguments 18:36:02 INFO - [mozharness: 2016-10-21 01:36:02.838000Z] Finished download-and-extract step (success) 18:36:02 INFO - [mozharness: 2016-10-21 01:36:02.838000Z] Running populate-webroot step. 18:36:02 INFO - Running pre-action listener: _resource_record_pre_action 18:36:02 INFO - Running main action method: populate_webroot 18:36:02 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 18:36:02 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 18:36:02 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 18:36:02 INFO - u'--tppagecycles', 18:36:02 INFO - u'7'], 18:36:02 INFO - u'tests': [u'tsvgm']}}, 18:36:02 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 18:36:02 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 18:36:02 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 18:36:02 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 18:36:02 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:36:02 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:36:02 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'damp', u'tps']}, 18:36:02 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'tests': [u'damp', u'tps']}, 18:36:02 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'dromaeo_dom']}, 18:36:02 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 18:36:02 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'basic_compositor_video']}, 18:36:02 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 18:36:02 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'other-e10s': {u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'a11yr', 18:36:02 INFO - u'ts_paint', 18:36:02 INFO - u'tpaint', 18:36:02 INFO - u'sessionrestore', 18:36:02 INFO - u'sessionrestore_no_auto_restore', 18:36:02 INFO - u'tabpaint']}, 18:36:02 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'tsvgx', 18:36:02 INFO - u'tsvgr_opacity', 18:36:02 INFO - u'tart', 18:36:02 INFO - u'tscrollx', 18:36:02 INFO - u'cart']}, 18:36:02 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 18:36:02 INFO - u'tsvgr_opacity', 18:36:02 INFO - u'tart', 18:36:02 INFO - u'tscrollx', 18:36:02 INFO - u'cart']}, 18:36:02 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'talos_options': [u'--disable-e10s'], 18:36:02 INFO - u'tests': [u'tp5o']}, 18:36:02 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'tests': [u'tp5o']}, 18:36:02 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'talos_options': [u'--disable-e10s', 18:36:02 INFO - u'--xperf_path', 18:36:02 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:02 INFO - u'tests': [u'tp5n']}, 18:36:02 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:02 INFO - u'talos_options': [u'--xperf_path', 18:36:02 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:02 INFO - u'tests': [u'tp5n']}}, 18:36:02 INFO - u'talos.zip': {u'path': u'', 18:36:02 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:36:02 INFO - Running post-action listener: _resource_record_post_action 18:36:02 INFO - [mozharness: 2016-10-21 01:36:02.905000Z] Finished populate-webroot step (success) 18:36:02 INFO - [mozharness: 2016-10-21 01:36:02.905000Z] Running create-virtualenv step. 18:36:02 INFO - Running pre-action listener: _resource_record_pre_action 18:36:02 INFO - Running main action method: create_virtualenv 18:36:02 INFO - Creating virtualenv C:\slave\test\build/venv 18:36:02 INFO - mkdir: C:\slave\test\build\venv\Scripts 18:36:02 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 18:36:02 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'! 18:36:02 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 18:36:02 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 18:36:08 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 18:36:17 INFO - Installing distribute......................................................................................................................................................................................done. 18:36:18 INFO - Return code: 0 18:36:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 18:36:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 18:36:19 INFO - Reading from file tmpfile_stdout 18:36:19 INFO - Output received: 18:36:19 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 18:36:19 INFO - Using _rmtree_windows ... 18:36:19 INFO - Using _rmtree_windows ... 18:36:19 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 18:36:19 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')]} 18:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:19 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')]} 18:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:19 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:19 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 18:36:19 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 18:36:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:19 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:19 INFO - 'DCLOCATION': 'SCL3', 18:36:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:19 INFO - 'HOMEDRIVE': 'C:', 18:36:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:19 INFO - 'KTS_VERSION': '1.19c', 18:36:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:19 INFO - 'MOZ_NO_REMOTE': '1', 18:36:19 INFO - 'NO_EM_RESTART': '1', 18:36:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:19 INFO - 'OS': 'Windows_NT', 18:36:19 INFO - 'OURDRIVE': 'C:', 18:36: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;', 18:36:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:19 INFO - 'PROCESSOR_LEVEL': '6', 18:36:19 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:19 INFO - 'PROMPT': '$P$G', 18:36:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:19 INFO - 'PWD': 'C:\\slave\\test', 18:36:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:19 INFO - 'SYSTEMDRIVE': 'C:', 18:36:19 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:19 INFO - 'TEST1': 'testie', 18:36:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:19 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:19 INFO - 'USERNAME': 'cltbld', 18:36:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:19 INFO - 'WINDIR': 'C:\\windows', 18:36:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:20 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:36:20 INFO - Downloading/unpacking pip>=1.5 18:36:24 INFO - Running setup.py egg_info for package pip 18:36:24 INFO - warning: no files found matching 'pip\cacert.pem' 18:36:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:36:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:36:24 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:36:24 INFO - Installing collected packages: pip 18:36:24 INFO - Found existing installation: pip 0.8.2 18:36:24 INFO - Uninstalling pip: 18:36:24 INFO - Successfully uninstalled pip 18:36:24 INFO - Running setup.py install for pip 18:36:24 INFO - warning: no files found matching 'pip\cacert.pem' 18:36:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:36:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:36:24 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:36:24 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 18:36:24 INFO - Successfully installed pip 18:36:24 INFO - Cleaning up... 18:36:24 INFO - Return code: 0 18:36:24 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 18:36: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')]} 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36: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')]} 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36: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', '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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36: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', 'psutil>=3.1.1'] in C:\slave\test\build 18:36: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 psutil>=3.1.1 18:36:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:24 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:24 INFO - 'DCLOCATION': 'SCL3', 18:36:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:24 INFO - 'HOMEDRIVE': 'C:', 18:36:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:24 INFO - 'KTS_VERSION': '1.19c', 18:36:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:24 INFO - 'MOZ_NO_REMOTE': '1', 18:36:24 INFO - 'NO_EM_RESTART': '1', 18:36:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:24 INFO - 'OS': 'Windows_NT', 18:36:24 INFO - 'OURDRIVE': 'C:', 18:36: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;', 18:36:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:24 INFO - 'PROCESSOR_LEVEL': '6', 18:36:24 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:24 INFO - 'PROMPT': '$P$G', 18:36:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:24 INFO - 'PWD': 'C:\\slave\\test', 18:36:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:24 INFO - 'SYSTEMDRIVE': 'C:', 18:36:24 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:24 INFO - 'TEST1': 'testie', 18:36:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:24 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:24 INFO - 'USERNAME': 'cltbld', 18:36:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:24 INFO - 'WINDIR': 'C:\\windows', 18:36:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:26 INFO - Downloading/unpacking psutil>=3.1.1 18:36:26 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 18:36:26 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 18:36:27 INFO - Installing collected packages: psutil 18:36:27 INFO - Successfully installed psutil 18:36:27 INFO - Cleaning up... 18:36:27 INFO - Return code: 0 18:36:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 18:36:27 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')]} 18:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:27 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')]} 18:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:27 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:27 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 18:36:27 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 18:36:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:27 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:27 INFO - 'DCLOCATION': 'SCL3', 18:36:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:27 INFO - 'HOMEDRIVE': 'C:', 18:36:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:27 INFO - 'KTS_VERSION': '1.19c', 18:36:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:27 INFO - 'MOZ_NO_REMOTE': '1', 18:36:27 INFO - 'NO_EM_RESTART': '1', 18:36:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:27 INFO - 'OS': 'Windows_NT', 18:36:27 INFO - 'OURDRIVE': 'C:', 18:36:27 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;', 18:36:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:27 INFO - 'PROCESSOR_LEVEL': '6', 18:36:27 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:27 INFO - 'PROMPT': '$P$G', 18:36:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:27 INFO - 'PWD': 'C:\\slave\\test', 18:36:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:27 INFO - 'SYSTEMDRIVE': 'C:', 18:36:27 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:27 INFO - 'TEST1': 'testie', 18:36:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:27 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:27 INFO - 'USERNAME': 'cltbld', 18:36:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:27 INFO - 'WINDIR': 'C:\\windows', 18:36:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:36:27 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 18:36:27 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 18:36:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:36:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 18:36:29 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) 18:36:29 INFO - Installing collected packages: mozsystemmonitor 18:36:29 INFO - Running setup.py install for mozsystemmonitor 18:36:29 INFO - Successfully installed mozsystemmonitor 18:36:29 INFO - Cleaning up... 18:36:29 INFO - Return code: 0 18:36:29 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 18:36:29 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')]} 18:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:29 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')]} 18:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:29 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:29 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 18:36:29 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 18:36:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:29 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:29 INFO - 'DCLOCATION': 'SCL3', 18:36:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:29 INFO - 'HOMEDRIVE': 'C:', 18:36:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:29 INFO - 'KTS_VERSION': '1.19c', 18:36:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:29 INFO - 'MOZ_NO_REMOTE': '1', 18:36:29 INFO - 'NO_EM_RESTART': '1', 18:36:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:29 INFO - 'OS': 'Windows_NT', 18:36:29 INFO - 'OURDRIVE': 'C:', 18:36:29 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;', 18:36:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:29 INFO - 'PROCESSOR_LEVEL': '6', 18:36:29 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:29 INFO - 'PROMPT': '$P$G', 18:36:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:29 INFO - 'PWD': 'C:\\slave\\test', 18:36:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:29 INFO - 'SYSTEMDRIVE': 'C:', 18:36:29 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:29 INFO - 'TEST1': 'testie', 18:36:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:29 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:29 INFO - 'USERNAME': 'cltbld', 18:36:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:29 INFO - 'WINDIR': 'C:\\windows', 18:36:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:29 INFO - Downloading/unpacking jsonschema==2.5.1 18:36:29 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 18:36:29 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 18:36:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:36:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:36:31 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 18:36:31 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 18:36:31 INFO - Downloading functools32-3.2.3-2.tar.gz 18:36:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 18:36:31 INFO - warning: no files found matching '*.txt' 18:36:31 INFO - no previously-included directories found matching 'build' 18:36:31 INFO - no previously-included directories found matching 'dist' 18:36:31 INFO - no previously-included directories found matching '.git*' 18:36:31 INFO - Installing collected packages: jsonschema, functools32 18:36:31 INFO - Running setup.py install for functools32 18:36:31 INFO - warning: no files found matching '*.txt' 18:36:31 INFO - no previously-included directories found matching 'build' 18:36:31 INFO - no previously-included directories found matching 'dist' 18:36:31 INFO - no previously-included directories found matching '.git*' 18:36:32 INFO - Successfully installed jsonschema functools32 18:36:32 INFO - Cleaning up... 18:36:32 INFO - Return code: 0 18:36:32 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 18:36:32 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')]} 18:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:32 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')]} 18:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:32 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:32 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 18:36:32 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 18:36:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:32 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:32 INFO - 'DCLOCATION': 'SCL3', 18:36:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:32 INFO - 'HOMEDRIVE': 'C:', 18:36:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:32 INFO - 'KTS_VERSION': '1.19c', 18:36:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:32 INFO - 'MOZ_NO_REMOTE': '1', 18:36:32 INFO - 'NO_EM_RESTART': '1', 18:36:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:32 INFO - 'OS': 'Windows_NT', 18:36:32 INFO - 'OURDRIVE': 'C:', 18:36:32 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;', 18:36:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:32 INFO - 'PROCESSOR_LEVEL': '6', 18:36:32 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:32 INFO - 'PROMPT': '$P$G', 18:36:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:32 INFO - 'PWD': 'C:\\slave\\test', 18:36:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:32 INFO - 'SYSTEMDRIVE': 'C:', 18:36:32 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:32 INFO - 'TEST1': 'testie', 18:36:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:32 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:32 INFO - 'USERNAME': 'cltbld', 18:36:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:32 INFO - 'WINDIR': 'C:\\windows', 18:36:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 18:36:32 INFO - Cleaning up... 18:36:32 INFO - Return code: 0 18:36:32 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 18:36:32 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')]} 18:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:32 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')]} 18:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:32 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:32 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 18:36:32 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 18:36:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:32 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:32 INFO - 'DCLOCATION': 'SCL3', 18:36:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:32 INFO - 'HOMEDRIVE': 'C:', 18:36:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:32 INFO - 'KTS_VERSION': '1.19c', 18:36:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:32 INFO - 'MOZ_NO_REMOTE': '1', 18:36:32 INFO - 'NO_EM_RESTART': '1', 18:36:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:32 INFO - 'OS': 'Windows_NT', 18:36:32 INFO - 'OURDRIVE': 'C:', 18:36:32 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;', 18:36:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:32 INFO - 'PROCESSOR_LEVEL': '6', 18:36:32 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:32 INFO - 'PROMPT': '$P$G', 18:36:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:32 INFO - 'PWD': 'C:\\slave\\test', 18:36:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:32 INFO - 'SYSTEMDRIVE': 'C:', 18:36:32 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:32 INFO - 'TEST1': 'testie', 18:36:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:32 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:32 INFO - 'USERNAME': 'cltbld', 18:36:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:32 INFO - 'WINDIR': 'C:\\windows', 18:36:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:33 INFO - Downloading/unpacking blobuploader==1.2.4 18:36: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 18:36: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 18:36:34 INFO - Downloading blobuploader-1.2.4.tar.gz 18:36:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 18:36:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:36: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 18:36: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 18:36:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 18:36:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:36:35 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 18:36:35 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 18:36:35 INFO - Downloading docopt-0.6.1.tar.gz 18:36:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 18:36:35 INFO - Installing collected packages: blobuploader, requests, docopt 18:36:35 INFO - Running setup.py install for blobuploader 18:36:35 INFO - Running setup.py install for requests 18:36:36 INFO - Running setup.py install for docopt 18:36:36 INFO - Successfully installed blobuploader requests docopt 18:36:36 INFO - Cleaning up... 18:36:38 INFO - Return code: 0 18:36:38 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:36:38 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')]} 18:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:38 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')]} 18:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:38 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:38 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 18:36:38 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 18:36:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:38 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:38 INFO - 'DCLOCATION': 'SCL3', 18:36:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:38 INFO - 'HOMEDRIVE': 'C:', 18:36:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:38 INFO - 'KTS_VERSION': '1.19c', 18:36:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:38 INFO - 'MOZ_NO_REMOTE': '1', 18:36:38 INFO - 'NO_EM_RESTART': '1', 18:36:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:38 INFO - 'OS': 'Windows_NT', 18:36:38 INFO - 'OURDRIVE': 'C:', 18:36:38 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;', 18:36:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:38 INFO - 'PROCESSOR_LEVEL': '6', 18:36:38 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:38 INFO - 'PROMPT': '$P$G', 18:36:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:38 INFO - 'PWD': 'C:\\slave\\test', 18:36:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:38 INFO - 'SYSTEMDRIVE': 'C:', 18:36:38 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:38 INFO - 'TEST1': 'testie', 18:36:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:38 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:38 INFO - 'USERNAME': 'cltbld', 18:36:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:38 INFO - 'WINDIR': 'C:\\windows', 18:36:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:36:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-blpjla-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:36:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r4ywck-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:36:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:36:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ksa8cw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nyhebf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dpzewa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iiwmf9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ttqnja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ounfnj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ku7wu3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:36:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:36:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b7ninv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d_szba-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_lg38c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-49bpnq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-77icag-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ey16td-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ux_gjf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:36:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:36:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dpk8yk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:36:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:36:42 INFO - Running setup.py install for manifestparser 18:36:42 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 18:36:42 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 18:36:42 INFO - Running setup.py install for mozcrash 18:36:43 INFO - Running setup.py install for mozdebug 18:36:43 INFO - Running setup.py install for mozdevice 18:36:43 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 18:36:43 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 18:36:43 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 18:36:43 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 18:36:43 INFO - Running setup.py install for mozfile 18:36:44 INFO - Running setup.py install for mozhttpd 18:36:44 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 18:36:44 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 18:36:44 INFO - Running setup.py install for mozinfo 18:36:44 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 18:36:44 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Running setup.py install for mozInstall 18:36:45 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 18:36:45 INFO - Running setup.py install for mozleak 18:36:45 INFO - Running setup.py install for mozlog 18:36:46 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 18:36:46 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 18:36:46 INFO - Running setup.py install for moznetwork 18:36:46 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 18:36:46 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Running setup.py install for mozprocess 18:36:47 INFO - Running setup.py install for mozprofile 18:36:47 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 18:36:47 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 18:36:48 INFO - Running setup.py install for mozrunner 18:36:48 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 18:36:48 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 18:36:49 INFO - Running setup.py install for mozscreenshot 18:36:49 INFO - Running setup.py install for moztest 18:36:50 INFO - Running setup.py install for mozversion 18:36:50 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 18:36:50 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 18:36:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:36:50 INFO - Cleaning up... 18:36:50 INFO - Return code: 0 18:36:50 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:36:50 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')]} 18:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:50 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')]} 18:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:50 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:50 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 18:36:50 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 18:36:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:50 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:50 INFO - 'DCLOCATION': 'SCL3', 18:36:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:50 INFO - 'HOMEDRIVE': 'C:', 18:36:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:50 INFO - 'KTS_VERSION': '1.19c', 18:36:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:50 INFO - 'MOZ_NO_REMOTE': '1', 18:36:50 INFO - 'NO_EM_RESTART': '1', 18:36:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:50 INFO - 'OS': 'Windows_NT', 18:36:50 INFO - 'OURDRIVE': 'C:', 18:36:50 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;', 18:36:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:50 INFO - 'PROCESSOR_LEVEL': '6', 18:36:50 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:50 INFO - 'PROMPT': '$P$G', 18:36:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:50 INFO - 'PWD': 'C:\\slave\\test', 18:36:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:50 INFO - 'SYSTEMDRIVE': 'C:', 18:36:50 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:50 INFO - 'TEST1': 'testie', 18:36:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:50 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:50 INFO - 'USERNAME': 'cltbld', 18:36:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:50 INFO - 'WINDIR': 'C:\\windows', 18:36:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:36:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wvdsxg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:36:51 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fnkkrj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xpjv6a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:36:51 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oj_wfe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pkhqta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:36:51 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-defzyw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:36:51 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wzj8zi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:36:51 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)) 18:36:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:36:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jgm92c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o1kihi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-45cvs6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oj7c17-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aaobfi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nvghza-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:36:52 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)) 18:36:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:36:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4yr9bj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:36:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:36:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-igq4ga-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:36:53 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)) 18:36:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:36:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-08zb8s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:36:53 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)) 18:36:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:36:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-463koi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:36:53 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)) 18:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 18:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 18:36:53 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)) 18:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 18:36:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 18:36:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 18:36:53 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 18:36:53 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 18:36:54 INFO - Downloading blessings-1.6.tar.gz 18:36:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 18:36:54 INFO - Installing collected packages: blessings 18:36:54 INFO - Running setup.py install for blessings 18:36:54 INFO - Successfully installed blessings 18:36:54 INFO - Cleaning up... 18:36:57 INFO - Return code: 0 18:36:57 INFO - Done creating virtualenv C:\slave\test\build/venv. 18:36:57 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:36:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:36:58 INFO - Reading from file tmpfile_stdout 18:36:58 INFO - Using _rmtree_windows ... 18:36:58 INFO - Using _rmtree_windows ... 18:36:58 INFO - Current package versions: 18:36:58 INFO - blessings == 1.6 18:36:58 INFO - blobuploader == 1.2.4 18:36:58 INFO - docopt == 0.6.1 18:36:58 INFO - functools32 == 3.2.3-2 18:36:58 INFO - jsonschema == 2.5.1 18:36:58 INFO - manifestparser == 1.1 18:36:58 INFO - mozInstall == 1.12 18:36:58 INFO - mozcrash == 0.17 18:36:58 INFO - mozdebug == 0.1 18:36:58 INFO - mozdevice == 0.48 18:36:58 INFO - mozfile == 1.2 18:36:58 INFO - mozhttpd == 0.7 18:36:58 INFO - mozinfo == 0.9 18:36:58 INFO - mozleak == 0.1 18:36:58 INFO - mozlog == 3.4 18:36:58 INFO - moznetwork == 0.27 18:36:58 INFO - mozprocess == 0.23 18:36:58 INFO - mozprofile == 0.28 18:36:58 INFO - mozrunner == 6.12 18:36:58 INFO - mozscreenshot == 0.1 18:36:58 INFO - mozsystemmonitor == 0.3 18:36:58 INFO - moztest == 0.7 18:36:58 INFO - mozversion == 1.4 18:36:58 INFO - psutil == 3.1.1 18:36:58 INFO - requests == 1.2.3 18:36:58 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:36:58 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')]} 18:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:58 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')]} 18:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:58 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:58 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 18:36:58 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 18:36:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:58 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:58 INFO - 'DCLOCATION': 'SCL3', 18:36:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:58 INFO - 'HOMEDRIVE': 'C:', 18:36:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:58 INFO - 'KTS_VERSION': '1.19c', 18:36:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:58 INFO - 'MOZ_NO_REMOTE': '1', 18:36:58 INFO - 'NO_EM_RESTART': '1', 18:36:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:58 INFO - 'OS': 'Windows_NT', 18:36:58 INFO - 'OURDRIVE': 'C:', 18:36:58 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;', 18:36:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:58 INFO - 'PROCESSOR_LEVEL': '6', 18:36:58 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:58 INFO - 'PROMPT': '$P$G', 18:36:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:58 INFO - 'PWD': 'C:\\slave\\test', 18:36:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:58 INFO - 'SYSTEMDRIVE': 'C:', 18:36:58 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:58 INFO - 'TEST1': 'testie', 18:36:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:58 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:58 INFO - 'USERNAME': 'cltbld', 18:36:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:58 INFO - 'WINDIR': 'C:\\windows', 18:36:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 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)) 18:36:58 INFO - Cleaning up... 18:36:58 INFO - Return code: 0 18:36:58 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 18:36:58 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')]} 18:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:58 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')]} 18:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:58 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 0x018D1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-030', 'USERDOMAIN': 'T-W732-IX-030', '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-030', '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 18:36:58 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 18:36:58 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 18:36:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:36:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:36:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:36:58 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:36:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:36:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:36:58 INFO - 'DCLOCATION': 'SCL3', 18:36:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:36:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:36:58 INFO - 'HOMEDRIVE': 'C:', 18:36:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:36:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:36:58 INFO - 'KTS_VERSION': '1.19c', 18:36:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:36:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:36:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:36:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:58 INFO - 'MOZ_NO_REMOTE': '1', 18:36:58 INFO - 'NO_EM_RESTART': '1', 18:36:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:36:58 INFO - 'OS': 'Windows_NT', 18:36:58 INFO - 'OURDRIVE': 'C:', 18:36:58 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;', 18:36:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:36:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:36:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:36:58 INFO - 'PROCESSOR_LEVEL': '6', 18:36:58 INFO - 'PROCESSOR_REVISION': '1e05', 18:36:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:36:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:36:58 INFO - 'PROMPT': '$P$G', 18:36:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:36:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:36:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:36:58 INFO - 'PWD': 'C:\\slave\\test', 18:36:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:36:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:36:58 INFO - 'SYSTEMDRIVE': 'C:', 18:36:58 INFO - 'SYSTEMROOT': 'C:\\windows', 18:36:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:58 INFO - 'TEST1': 'testie', 18:36:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:36:58 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:36:58 INFO - 'USERNAME': 'cltbld', 18:36:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:36:58 INFO - 'WINDIR': 'C:\\windows', 18:36:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:36:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 18:36:58 INFO - Cleaning up... 18:36:58 INFO - Return code: 0 18:36:58 INFO - Running post-action listener: _resource_record_post_action 18:36:58 INFO - Running post-action listener: _start_resource_monitoring 18:36:58 INFO - Starting resource monitoring. 18:36:59 INFO - [mozharness: 2016-10-21 01:36:59.042000Z] Finished create-virtualenv step (success) 18:36:59 INFO - [mozharness: 2016-10-21 01:36:59.042000Z] Running install step. 18:36:59 INFO - Running pre-action listener: _resource_record_pre_action 18:36:59 INFO - Running main action method: install 18:36:59 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:36:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:36:59 INFO - Reading from file tmpfile_stdout 18:36:59 INFO - Using _rmtree_windows ... 18:36:59 INFO - Using _rmtree_windows ... 18:36:59 INFO - Detecting whether we're running mozinstall >=1.0... 18:36:59 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 18:36:59 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 18:36:59 INFO - Reading from file tmpfile_stdout 18:36:59 INFO - Output received: 18:36:59 INFO - Usage: mozinstall-script.py [options] installer 18:36:59 INFO - Options: 18:36:59 INFO - -h, --help show this help message and exit 18:36:59 INFO - -d DEST, --destination=DEST 18:36:59 INFO - Directory to install application into. [default: 18:36:59 INFO - "C:\slave\test"] 18:36:59 INFO - --app=APP Application being installed. [default: firefox] 18:36:59 INFO - Using _rmtree_windows ... 18:36:59 INFO - Using _rmtree_windows ... 18:36:59 INFO - mkdir: C:\slave\test\build\application 18:36:59 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-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 18:36:59 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 18:37:02 INFO - Reading from file tmpfile_stdout 18:37:02 INFO - Output received: 18:37:02 INFO - C:\slave\test\build\application\firefox\firefox.exe 18:37:02 INFO - Using _rmtree_windows ... 18:37:02 INFO - Using _rmtree_windows ... 18:37:02 INFO - Running post-action listener: _resource_record_post_action 18:37:02 INFO - [mozharness: 2016-10-21 01:37:02.639000Z] Finished install step (success) 18:37:02 INFO - [mozharness: 2016-10-21 01:37:02.640000Z] Running run-tests step. 18:37:02 INFO - Running pre-action listener: _resource_record_pre_action 18:37:02 INFO - Running main action method: run_tests 18:37:02 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 18:37:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 18:37:02 INFO - Python 2.7.3 18:37:02 INFO - Return code: 0 18:37:02 INFO - grabbing minidump binary from tooltool 18:37:02 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')]} 18:37:02 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 0x01998E90>, '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 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A4E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 18:37:02 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 18:37:02 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 18:37:02 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 18:37:02 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 18:37:03 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpfzvxlo 18:37:03 INFO - INFO - File integrity verified, renaming tmpfzvxlo to win32-minidump_stackwalk.exe 18:37:03 INFO - Return code: 0 18:37:03 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 18:37:03 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 18:37:03 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 18:37:03 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 18:37:03 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 18:37:03 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 18:37:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-030 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 18:37:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:37:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:37:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:37:03 INFO - 'COMPUTERNAME': 'T-W732-IX-030', 18:37:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:37:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:37:03 INFO - 'DCLOCATION': 'SCL3', 18:37:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:37:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:37:03 INFO - 'HOMEDRIVE': 'C:', 18:37:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:37:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:37:03 INFO - 'KTS_VERSION': '1.19c', 18:37:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:37:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-030', 18:37:03 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:37:03 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 18:37:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:37:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:03 INFO - 'MOZ_NO_REMOTE': '1', 18:37:03 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:37:03 INFO - 'NO_EM_RESTART': '1', 18:37:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:37:03 INFO - 'OS': 'Windows_NT', 18:37:03 INFO - 'OURDRIVE': 'C:', 18:37:03 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;', 18:37:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:37:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:37:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:37:03 INFO - 'PROCESSOR_LEVEL': '6', 18:37:03 INFO - 'PROCESSOR_REVISION': '1e05', 18:37:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:37:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:37:03 INFO - 'PROMPT': '$P$G', 18:37:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:37:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:37:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:37:03 INFO - 'PWD': 'C:\\slave\\test', 18:37:03 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 18:37:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:37:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:37:03 INFO - 'SYSTEMDRIVE': 'C:', 18:37:03 INFO - 'SYSTEMROOT': 'C:\\windows', 18:37:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:37:03 INFO - 'TEST1': 'testie', 18:37:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:37:03 INFO - 'USERDOMAIN': 'T-W732-IX-030', 18:37:03 INFO - 'USERNAME': 'cltbld', 18:37:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:37:03 INFO - 'WINDIR': 'C:\\windows', 18:37:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:37:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:37:03 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-030', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 18:37:04 INFO - mozversion application_buildid: 20161020153022 18:37:04 INFO - mozversion application_changeset: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac 18:37:04 INFO - mozversion application_display_name: Nightly 18:37:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:37:04 INFO - mozversion application_name: Firefox 18:37:04 INFO - mozversion application_remotingname: firefox 18:37:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:37:04 INFO - mozversion application_vendor: Mozilla 18:37:04 INFO - mozversion application_version: 52.0a1 18:37:04 INFO - mozversion platform_buildid: 20161020153022 18:37:04 INFO - mozversion platform_changeset: ba6fc133264d8e7aa8adf43d9d88f48b6dc2ffac 18:37:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:37:04 INFO - mozversion platform_version: 52.0a1 18:37:04 INFO - using testdate: 1477013824 18:37:04 INFO - actual date: 1477013824 18:37:04 INFO - starting webserver on 'localhost:49676' 18:37:04 INFO - SUITE-START | Running 2 tests 18:37:04 INFO - TEST-START | dromaeo_css 18:37:04 INFO - operating with platform_type : w7_ 18:37:04 INFO - Initialising browser for dromaeo_css test... 18:37:04 INFO - TEST-INFO | started process 2752 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpj6ofe8\profile http://localhost:49676/getInfo.html) 18:37:11 INFO - PROCESS | 2752 | __metrics Screen width/height:1600/1200 18:37:11 INFO - PROCESS | 2752 | colorDepth:24 18:37:11 INFO - PROCESS | 2752 | Browser inner width/height: 1010/674 18:37:11 INFO - PROCESS | 2752 | __metrics 18:37:12 INFO - PROCESS | 2752 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:12 INFO - PROCESS | 2752 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:13 INFO - TEST-INFO | 2752: exit 0 18:37:13 INFO - Browser initialized. 18:37:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:37:13 INFO - TEST-INFO | started process 448 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpj6ofe8\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:14 INFO - PROCESS | 448 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 18:37:25 INFO - PROCESS | 448 | RSS: Main: 123129856 18:37:25 INFO - PROCESS | 448 | 18:38:16 INFO - PROCESS | 448 | 1477013896446 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] 18:40:27 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49676/tests/dromaeo/cssquery-ext.html) 18:40:28 INFO - PROCESS | 448 | RSS: Main: 201965568 18:40:28 INFO - PROCESS | 448 | 18:43:31 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-ext.html (next: http://localhost:49676/tests/dromaeo/cssquery-jquery.html) 18:43:31 INFO - PROCESS | 448 | RSS: Main: 199446528 18:43:31 INFO - PROCESS | 448 | 18:46:33 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49676/tests/dromaeo/cssquery-mootools.html) 18:46:34 INFO - PROCESS | 448 | RSS: Main: 153190400 18:46:34 INFO - PROCESS | 448 | 18:49:36 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49676/tests/dromaeo/cssquery-prototype.html) 18:49:36 INFO - PROCESS | 448 | RSS: Main: 143634432 18:49:36 INFO - PROCESS | 448 | 18:52:39 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49676/tests/dromaeo/cssquery-yui.html) 18:52:40 INFO - PROCESS | 448 | RSS: Main: 134959104 18:52:40 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | Cycle 1(1): loaded http://localhost:49676/tests/dromaeo/cssquery-yui.html (next: http://localhost:49676/tests/dromaeo/cssquery-dojo.html) 18:55:42 INFO - PROCESS | 448 | RSS: Main: 180277248 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | __start_tp_report 18:55:42 INFO - PROCESS | 448 | _x_x_mozilla_page_load 18:55:42 INFO - PROCESS | 448 | _x_x_mozilla_page_load_details 18:55:42 INFO - PROCESS | 448 | |i|pagename|runs| 18:55:42 INFO - PROCESS | 448 | |0;dojo.html;2716.972830271697;2744.9862750686248;2746.9175924722267;2748.8350698958066;2759.6136540884268;4566.703164294318;4643.326717625946;4694.507076756942;4733.763311834412;4741.857744267669;4553.157665831815;4598.678092533524;4639;4749.548792864681;4751.572358487735;4092.877213683594;4107.568705285947;4149.460570125894;4155.252054630166;4190.0572371216385;3968.5436174839974;4017.216642754664;4027;4080.775767269822;4127.112670775783;4091.4476545666253;4155.023569461174;4157.5010998680045;4158.916821663565;4172.77049762263;3967.246223217608;4000.3599424092004;4047.615476529725;4049.372347286175;4147.564505726916;4021.4369988201674;4075.8369665213354;4109.054917368992;4118.176364727066;4121.855735049259;4197;4478.686491945533;4618.445786505607;4633.235516139833;4641.443026836801;4666.198310971827;4719.174144524729;4761.238201887716;4770.76146192689;4775.188218002948;4725.527447255281;4797.0645724083815;4823.589994850442;4827.541383568555;4831.178699621073;4274;4481.35020422037;4499.1451624191295;4515;4519.661025423074;4894.412670479565;4943.777530011158;4944.90110197794;4959.156943319645;4965.925511117336;4743.146233677971;4744.5492678195515;4746.240601503812;4756.7145971241835;4757.5718185363485;4795.304680821261;4834.903301933941;4835.492273311322;4836.854894353175;4839.491853355417;4757.238841785367;4791.6885402449425;4814.350062741555;4818.277258411196;4855.101806165871;4842;4867.415910090812;4879.2925025871045;4921.606271498272;4943.604511639132;3262.755293353008;3266.3304022675293;3335.483000134983;3341.3818443588016;3341.7493687973015;2903.738663520251;2984.0749367696085;2995.400919816002;3020.0788759428583;3048.9695103048684;4459.130469558405;4485.798139083749;4555.567221113989;4563.475200351935;4577.107464044546;4702.482726900038;4784.832530861403;4882.316475693406;4885.877853053702;4911.263310503453;4453.153900758845;4501.392312037833;4523;4554.795034223402;4564.543545645409;4090.263752524574;4103.589641035932;4107.466029416217;4109.116539943926;4164.3961625564725;4903.583195428429;4942.9258561121615;5081.364829396325;5181.326427564392;5194.298769666122;4452.465704115527;4854.296127061627;4858.441279252931;4976.377952755905;4976.427710813158;4866.318715379779;5014.147594908947;5016.072026675077;5089.05852417304;5173.379194496684;4770.737609431376;4808.2547205183255;4915.827946021872;4923.990581930624;4930.408350997904;4901.148568823457;4904.166291730343;4959.454460009469;4998.625103117208;5000.649954503296;4610.308453731967;4622.86131416058;4632.9305060424795;4707.303558681512;4724.362211101457;4717.622590192845;4744.952550474589;4780.426348838162;4814.566688998007;4826.0589185108565;4624.606908412823;4631.652626053127;4720.197566413649;4723.574878260973;4733.715977041388;4689.976550117364;4692.1084993851055;4740.19416699155;4810.230363142021;4814.975925120352;4655.837045703384;4670.392848929618;4715.24556071027;4761.857154282878;4764.880877978078 18:55:42 INFO - PROCESS | 448 | |1;ext.html;547.0945585056731;560.1289994059239;571.5170680774744;584.6404461256328;595.5890435599439;29219.85390073052;39367.60632393675;40107.99730006748;40203.78879161645;41204.38193427101;40381;40801;41161.176776464454;41176.382354264635;41200.79399603005;7894.960525197366;7909.841803163933;7984.201579842027;8078.959605201995;8100.51396916184;6827.317268273183;7007;9918.454485003322;10031.498425078753;10130;10276.434796086212;10296.330738501973;10310.639127630535;10347.482625868714;10349.068583827455;6781.25406205317;10264.74338141545;10285.125764310043;10363.63727269542;10366.378017318984;6946.687399067054;9665.420074795535;9969.950150249275;9972.451515166664;10073.597056117747;36220.637793622256;37342.81328593367;37651.623483765085;37970.81014594937;37985.430218546746;2942.5291953287583;3018.9245268868235;3048.192229059301;3268.7058164765285;3292.6048874135017;29580.704192958226;29856.40287194244;30064.69935300641;30384.240393990105;30582;3208.253482806887;3272.214668479548;3279.3769183854993;3315.602127744662;3333.8999830004896;299783;305630;306511;306911;307523;49208.507914920396;51274.48725512697;51449.742751286;51507.74246128745;51523;27646.170614881587;28185.16796408227;28595.285117871805;53901.1914821278;53909.46090539123;12341.197822141532;12359.505619775287;12461.875381246253;12475.93762031184;12596.62210133679;8433.873491897626;8619.267362274151;9036.45781253127;9100.954495227614;9102.13529714676;16656.666866662843;16738.83261167397;17089.401870934456;17292.65414691699;17361;6819.215790184093;6854.9657251713425;6856.24581296057;6885.758998435031;6895.758763422532;783.8628240057967;820.3765138494667;855.6791203298762;928.9642049115188;930.4277869110583;26319.605205921926;27389.178324650293;27815.443691126064;27954.301142471602;28163;907.832051070554;913.2876356441986;943.5093751249312;961.5432669481941;1029.7683021320142;14022.368993395321;14243.139760683953;14378.28108594587;14602.196879171535;14613.78079328811;459870;465117;465818;467348;471543;46466;47811;49154.26268605974;49157.26264106041;49199.75400122976;1010.8180527505119;1029.6756521695793;1097.05653138301;1119.9440027998567;1121.713962939429;27031;27716.44567108687;28097.016604418746;28504.857475712488;28600.570991434728;40279;40367.3944890821;42380.36429453499;42541.57458425376;42544.361834571886;291005;338134;338944.85299630923;339066;339831;35713.28587425999;36776.264474709824;37524;37601;37748.245035099695;42408.36387454129;42870;42870;43018.56981430271;43491;36218.818905905304;36476;37121.44317835181;37431.62568374391;37863.43204851985;35505;35931.28137437289;36590.8170459146;37744.811275943444;38600;34392.828035860664;34690;35070.12324691903;35159.12102197466;35373;31867.681323186473;32526.34947301088;32998;33694.3261134771;34081.48877766885;23514.764852351258;24366.030322571085;24967.375815604755;25229.24312270635;25337.239882803544 18:55:42 INFO - PROCESS | 448 | |2;jquery.html;259.25593546521486;290.9636295463067;296.6973686839424;298.041795627059;300.1146617478439;13165.868341316584;17411.912940435297;17541.82458175418;17990.640187196266;18754.437366879014;17073;17383.13084345784;17479.47561573155;17624.383146589873;17649.470515884543;4990.525900039491;5049.722265275408;5237.973810130944;5242.606804489659;5244.763985620656;4901.901961960759;5033.11920413928;5120.820771273006;5122.948770512303;5129.281900533928;4889.119958407486;4972.875678108058;5057.115004874132;5066.341375621164;5070.518300761421;4885.828995985141;5022.899542009176;5031.798728050873;5055.292259083712;5057.595392368602;4272.530021697611;4311.37485064667;4312.892177695583;4319.524852266248;4320.135972805451;20600.27899023527;21413.464663383384;21990.560188796295;22139.557208855895;22274.88862555693;1854.7217917312375;1885.58517126232;2022.2214447437696;2115.386537904006;2396.400899775056;13984.230867302294;14211.573652790434;14211.928940355232;14554.126752394784;14601.488947886879;1637.6397192617724;1686.477192070462;1704.9147542622943;1720.380662961333;1721.5782133377277;79871.80833245821;81553.59223203846;81713.18286817115;81735.18264817336;82153.58923205406;8189.099199088096;8280;8287.917120828835;8329.208725171098;8381.41330106899;9394.67118650844;9745.366551174151;10744.94627526873;11331.546738130382;11334.886651133384;6424.1327420798525;6453.515986301046;6453.709583068773;6624.56940298879;6631.403173714389;6416.711247993851;6434.64609446485;6442.194725659293;6484.286728459866;6492.67536623167;5409.350877894678;5499.532539734168;5503.147012213113;5506.972465137648;5587.692676902728;1413.5440496288838;1422.2035660030417;1446.8770154536771;1487.8437764034836;1510.358097808427;847.2586486824028;857.8412993596205;888.5157589113819;924.2236521322121;932.7994481186578;17845.821541784677;18024.549386265186;18687.15907784153;18754.92985871893;18975.33586324472;765.6324964017302;815;834.5693661623628;844.0082902589432;870.0299166429512;8701.303895688456;10297.12474439681;10546.41994690284;10569.947150264354;10576.831749491335;356158;358992.6654767432;361949;362620;363960;82092.58953705312;82423;82974.17025829785;84067.15932840716;85194;917.4724533393326;920.0661328751323;920.604140219712;935.5743136872707;941.6374695742188;16386.67226655484;16407.507774766775;16487.505374838773;16502.33990640361;16966.745498817774;30831.69168308378;40109.39835902405;42681.57318426776;45016.981611980395;46476.53523464722;60685.69657151862;60821.39178608157;60901;61525;61653;6656.30108838565;6708.094407254958;6745.862544250193;6966;7063.540869843444;11986.880131198577;13046.739065218453;13331.66670833237;13439.529616463376;13633;10640.84038739404;10755.516001780095;11103.222774405715;11119.27724697892;11285.153613479186;10796.244262901522;10937.453127343442;10999.34003959765;11027.944860275647;11260.15548833857;9949.452780097165;10390.428526431118;11207.43962801873;11339.886601134212;11468;6768.120144381199;6775.085363476065;6776.424003959719;6791.830204244933;6811.591304521745;7777.72777952769;10931.56273749041;10971.23201375896;11173.106151508022;11354.26197297206 18:55:42 INFO - PROCESS | 448 | |3;mootools.html;1161.425094578184;1188.887055729706;1273.06429774116;1545.165610570292;1565.5851199432152;6586.374294442026;7006.649667516623;7077.150741910965;7085.858282834341;7100.96449517753;5961.612495187821;6129.387061293868;6741.76403825865;6890.138732658418;6955.617441040753;1786.4372722592375;1827.8629102817276;1827.9634407311846;1829.5060333709891;1829.5791967847404;1840.5950690847992;1849.6300739852015;1865.6548538520417;1871.447922862752;1875.756151700284;1587.8174009988825;1637.6397192617605;1786.919588618509;1832.3220408448892;1835.6604028254815;1857.3870622694546;1859.609482008773;1868.0566314011337;1868.271374164077;1869.8504119670395;1799.4511673939444;1823.6626224148574;1831.432256000644;1837.8070302618166;1850.0194896704768;6752.864942701168;6851.417629501499;6859.348361905611;6894.827629309258;6896.586204827727;3857.84568617255;3900.6294402031763;3916.373380259145;3932.2528719543197;3937.8031098444962;2451.9387015324583;2513.472170844125;2539.149384956042;2552.885120169597;2578.587426011848;753.4048101999468;778.4122987144726;779.0534500581821;791.3589992106355;794.241499368102;8425.410221284574;8539.530325832076;8549.615267312985;8555.775498370407;8566.742997710078;3144.889928852479;3244.626867910173;3248.642649308574;3275.3121844412462;3311.586051743532;6877.2091209510545;6922.5154239202775;6977.33715297046;6982.022516847645;7008.334208250208;3828.4065969774174;3850.114473671071;3859.517560304962;3861.169848482534;3863.2466668999277;3676.4301533262387;3680.245549662313;3711.5175027246287;3716.3310604090984;3723.6090210528046;2617.738226177367;2782.318332008671;2805.803593748418;2815.2680303121338;2818.633577634894;816.14304979771;840.7267638017669;842.0611018714089;861.5218553702755;863.9611217495228;657.7204688007578;779.7582749347721;793.4882001109253;794.654325368455;803.8512875118122;4611.631069514431;4649.093426781813;4666.300054991779;4699.624030077585;4759.904801903943;325.7817262434175;334.2079272125079;336.16127761235873;337.36575238551364;339.5924890131852;3124.390743804982;3228.7255583275214;3240.416724989477;3292.456744637156;3295.126791400281;7938.364930805522;7996.680132794623;8118.878216826753;8162.020557533134;8177.836443271101;8081.6363263653275;8083.434159608889;8110.270075693216;8118.431709780259;8119.594020298961;6402.103705481236;6487.059376390397;6515.7393704252245;6516.120323756327;6520.739170433129;4613.49251582319;4633.86098417048;4654.534546545318;4667.346571480063;4814.20565606671;7429;7569.205233450409;7649.120351159688;7751.806204844924;7762.844743105219;7735.690572377267;7785.883211751941;7798.415118865994;7808.609569521387;7817.960910195413;5451.672899626035;5663.518600918803;5968.671723055274;6034.547408944259;6175.783847565313;6851.109355783916;6881.41507971828;6895;6896.931030689629;6905.620190889549;5896.734646940984;6163.96918015407;6222.782202623068;6299.874002520016;6316.873662526816;6021.789237376846;6049.243844519435;6178.227721534809;6183.876322473615;6208.1308616792785;5391.919121213106;5498.5876059294915;5503.532199762904;5551.2228288040515;5554.916676249779;4102.364133559303;4103.405006274013;4219.303814870512;4229.894252643586;4258.87223383299;4049.5950404959267;4053.351463765783;4075.4498142751536;4079.082206503513;4144.067584793397 18:55:42 INFO - PROCESS | 448 | |4;prototype.html;216.176368037776;226.39778190014565;231.44453312051075;231.93273950554328;232.66612411189945;4964.404271487422;5043.420006699231;5192.273081768546;5194.792208311673;5232.476752324766;5050.217216331465;5072.416672082712;5126.487351264872;5130.537796727934;5142.074426603219;3630.0743310455928;3646.270745850827;3649.85400583977;3662.432322989941;3666.944995825065;3636.2000359920776;3658.4451608066597;3658.890233292992;3661.4690869823858;3673.3020726062095;3505.4216054351127;3551.254236610315;3551.2897420516;3627.510286111381;3632.309861126391;3606.6213047629885;3615.1685112424157;3625.854965801365;3627.7279204059537;3629.981850090746;3026.379592183597;3042.574039634453;3046;3066.6013418255707;3070.2170946408523;4971.602271818282;5019.14674505335;5026;5048.545630893237;5053.343065401511;3813.4470501777364;4097.385392191165;4100.630943215095;4117.3206420940505;4210.831566737327;5079.288899554065;5258.475931552838;5260.289860868772;5273.630845840793;5280.313559237313;3123.32848437587;3263.379957808009;3277.0987978305916;3291.572095627553;3310.2220978070136;5488.945110548883;5525.778968841241;5533.114701647757;5539.584531160179;5554.19464177696;3295.8846440374473;3344.2141096842224;3417.8120203388553;3431.227973705946;3438.7592868498964;3161.68383161682;3460.325236578843;3468.976651887699;3477.1307173206696;3487.755857089979;4157.6695457453925;4176.519700234512;4178.791060446965;4188.6649068074485;4191.7484950903045;5119.720069982504;5326.9467305327225;5341.252224688546;5347.25138480613;5366.356037275552;1177.7526719389025;1185.958491452795;1196.5453127811377;1196.7666305070602;1203.8314635950974;687.7317846040048;714.9428045756431;723.8914162875611;725.1189804387694;725.6408078001423;4566.794494247767;4585.197590421662;4585.633149348044;4594.150082234797;4626.606738427204;4941.50584941503;4975.825846095369;4977.1538838397555;4980.726060066731;5007.223880298487;4588.472325682523;4589.384051090913;4605.493395726535;4876.390451193601;4969.453360130383;2868.4693331733697;2897.8116422432477;2936.9559456608167;2941.8529073546238;2944.455275773989;5711.314642242957;5741.913871291934;5750.827475175752;5772.076467765221;5779.884402312015;5499.450054994469;5601.187827765033;5626.831195064155;5657.7270114223975;5663.207150998864;5564.221009058735;5728.541716662657;5730.140478928194;5754.251947246831;5762.481376676119;5468.28912241406;5470.261514695465;5510.283663123768;5511.779528818803;5515.806946756844;5417.214503896992;5442.700651464207;5444.945550544602;5455.263539422127;5455.863603409946;5536.4186760389575;5704.657720536781;5721.22763426932;5730.426957304237;5740.899914046368;3287.079617707046;3400.3029378977744;3404.5233667287093;3407.0971192633474;3409.7954122752712;5411.2965314508865;5507.256520369859;5520.558355331644;5522.392536820867;5538;5105.923411148761;5117.948820511747;5274.472552744694;5345.38528069277;5348.491893270133;5226.771708648388;5301.628885977944;5303.708296043755;5318.97340513295;5335.413104558418;5171.612129090408;5241.292425522658;5241.711705856214;5245.527902488641;5262.473752624707;5295.841124766263;5347.278117454094;5355.169948657965;5355.3305836770405;5370.865728356823;5107.668001579886;5252.080885845038;5264.894372181885;5267.7366131694025;5294 18:55:42 INFO - PROCESS | 448 | |5;yui.html;633.5744574706906;694.163532942804;694.8054544727472;699.9090118284626;703.3142685881264;14031.859681403183;14705.779413308783;14774.77837832432;14842;15464.458743943964;14843.703125937474;14926.925365373185;15248.542543723679;15330.616734581641;15470.071795692267;646.1115965547373;660.3957378998211;662.1127688896877;662.569329935541;663.7079684938611;525.1728527569094;537.0735481294755;643.3405759096917;655.6852710698844;655.855711743418;676.2290988273372;680.3468670076733;682.2938258902037;692.2938602625319;692.6329045605837;681.0975457518807;689.762032098925;691.4226620771661;692.6605963078056;694.9930500694979;399.8640462242851;402.2819267607307;402.3843519415299;410.39671682626494;500.08983649757454;13729.656758581015;13917.443302267897;14220.715585688331;14237.430502779876;14294.428222871073;13361.86638133616;13394.06241563091;13418.46326146953;13741.03812733109;13747.931260343734;11398.23468069077;11398.658040258806;11620.244684095507;11683.299002059903;11764.41177941108;3234.40163569738;3279.2785587171024;3370.713489353408;3375.122468158272;3402.3535528249554;81368.59315703383;83522.58238708768;84046.57976710077;84199.57900210521;84243.15756842475;10321.729219571682;10329.380237185793;10359.171266298677;10378.169746420268;10510.421926793946;18825;18890.622187556448;18913.24347026131;18936.621267574847;18961.525961850788;6694.196696396366;7054.541454805421;7167.247439018828;7310.59791711461;7330.743423980135;3754.380527213034;3825.158465137665;3830.9233815323537;3887.319719049155;3940.074082590589;2697.85161816102;2793.9301517462227;2802.341449759305;2884.048264072851;2890.8120972136744;487.80487804878334;499.21623051809036;505.4667325971062;509.6025100421677;509.6203328520276;593.8426317025992;649.9350064993463;669.5045666207069;673.6059405247887;686.6909890549273;8979.551022448852;8989.146031127033;9061.275097992146;9145.679901203426;9334.533273336307;614.7479533391378;644.3556443556444;678.5792808458788;682.61423340233;703.3529153179087;4442.555744425532;4711.858644240679;4725.5510726480925;4753.786079626425;4803.78382972767;100455;105999;107961.29807334328;110174.44912775545;111484.44257778821;98800;100731;100841;101126.49436752916;102304;83323.5833820827;87354.56322718473;87396.12603874007;87894.56052719695;89009.55495222611;8307.335413167053;8319.126491718525;8548.461276969956;8567.485950842969;8784.956075219583;49243.50756492389;49266.507334926195;50245;51237.48762512327;52209.738951305;65876.34123658702;69297.3070269311;70035;71482.28517714965;72471;10028;11007.61473348429;11089.88910110921;11423.543058277908;11641.59254426125;13556.864431355954;13648.317584120952;13747.195435431675;13841.100328478617;13919.791203132163;12533.435995380047;12560.93719531433;12684.87315126837;13058.608241752763;13182.406791694588;11564.595239166589;12136.575219867262;12137.271763694207;12336.321502317458;12466.563670271496;12315.69210769738;12500.374981251083;12542.749145017231;12636.620901372973;12683.731626837243;10969.835452468378;11211.943940280247;11272.66182014541;11472.139589530985;11497.597584084517;10384.169266458815;10427.68716938493;10562.841557376492;10723.89276107229;10903 18:55:42 INFO - PROCESS | 448 | __end_tp_report 18:55:42 INFO - PROCESS | 448 | __start_cc_report 18:55:42 INFO - PROCESS | 448 | _x_x_mozilla_cycle_collect,1131 18:55:42 INFO - PROCESS | 448 | __end_cc_report 18:55:42 INFO - PROCESS | 448 | __startTimestamp1477014942875__endTimestamp 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | ------- Summary: start ------- 18:55:42 INFO - PROCESS | 448 | Number of tests: 6 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#0] dojo.html Cycles:165 Average:4481.50 Median:4702.48 stddev:555.91 (11.8%) stddev-sans-first:540.10 18:55:42 INFO - PROCESS | 448 | Values: 2717.0 2745.0 2746.9 2748.8 2759.6 4566.7 4643.3 4694.5 4733.8 4741.9 4553.2 4598.7 4639.0 4749.5 4751.6 4092.9 4107.6 4149.5 4155.3 4190.1 3968.5 4017.2 4027.0 4080.8 4127.1 4091.4 4155.0 4157.5 4158.9 4172.8 3967.2 4000.4 4047.6 4049.4 4147.6 4021.4 4075.8 4109.1 4118.2 4121.9 4197.0 4478.7 4618.4 4633.2 4641.4 4666.2 4719.2 4761.2 4770.8 4775.2 4725.5 4797.1 4823.6 4827.5 4831.2 4274.0 4481.4 4499.1 4515.0 4519.7 4894.4 4943.8 4944.9 4959.2 4965.9 4743.1 4744.5 4746.2 4756.7 4757.6 4795.3 4834.9 4835.5 4836.9 4839.5 4757.2 4791.7 4814.4 4818.3 4855.1 4842.0 4867.4 4879.3 4921.6 4943.6 3262.8 3266.3 3335.5 3341.4 3341.7 2903.7 2984.1 2995.4 3020.1 3049.0 4459.1 4485.8 4555.6 4563.5 4577.1 4702.5 4784.8 4882.3 4885.9 4911.3 4453.2 4501.4 4523.0 4554.8 4564.5 4090.3 4103.6 4107.5 4109.1 4164.4 4903.6 4942.9 5081.4 5181.3 5194.3 4452.5 4854.3 4858.4 4976.4 4976.4 4866.3 5014.1 5016.1 5089.1 5173.4 4770.7 4808.3 4915.8 4924.0 4930.4 4901.1 4904.2 4959.5 4998.6 5000.6 4610.3 4622.9 4632.9 4707.3 4724.4 4717.6 4745.0 4780.4 4814.6 4826.1 4624.6 4631.7 4720.2 4723.6 4733.7 4690.0 4692.1 4740.2 4810.2 4815.0 4655.8 4670.4 4715.2 4761.9 4764.9 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#1] ext.html Cycles:180 Average:51223.59 Median:36219.73 stddev:98895.16 (273.0%) stddev-sans-first:99099.39 18:55:42 INFO - PROCESS | 448 | Values: 547.1 560.1 571.5 584.6 595.6 29219.9 39367.6 40108.0 40203.8 41204.4 40381.0 40801.0 41161.2 41176.4 41200.8 7895.0 7909.8 7984.2 8079.0 8100.5 6827.3 7007.0 9918.5 10031.5 10130.0 10276.4 10296.3 10310.6 10347.5 10349.1 6781.3 10264.7 10285.1 10363.6 10366.4 6946.7 9665.4 9970.0 9972.5 10073.6 36220.6 37342.8 37651.6 37970.8 37985.4 2942.5 3018.9 3048.2 3268.7 3292.6 29580.7 29856.4 30064.7 30384.2 30582.0 3208.3 3272.2 3279.4 3315.6 3333.9 299783.0 305630.0 306511.0 306911.0 307523.0 49208.5 51274.5 51449.7 51507.7 51523.0 27646.2 28185.2 28595.3 53901.2 53909.5 12341.2 12359.5 12461.9 12475.9 12596.6 8433.9 8619.3 9036.5 9101.0 9102.1 16656.7 16738.8 17089.4 17292.7 17361.0 6819.2 6855.0 6856.2 6885.8 6895.8 783.9 820.4 855.7 929.0 930.4 26319.6 27389.2 27815.4 27954.3 28163.0 907.8 913.3 943.5 961.5 1029.8 14022.4 14243.1 14378.3 14602.2 14613.8 459870.0 465117.0 465818.0 467348.0 471543.0 46466.0 47811.0 49154.3 49157.3 49199.8 1010.8 1029.7 1097.1 1119.9 1121.7 27031.0 27716.4 28097.0 28504.9 28600.6 40279.0 40367.4 42380.4 42541.6 42544.4 291005.0 338134.0 338944.9 339066.0 339831.0 35713.3 36776.3 37524.0 37601.0 37748.2 42408.4 42870.0 42870.0 43018.6 43491.0 36218.8 36476.0 37121.4 37431.6 37863.4 35505.0 35931.3 36590.8 37744.8 38600.0 34392.8 34690.0 35070.1 35159.1 35373.0 31867.7 32526.3 32998.0 33694.3 34081.5 23514.8 24366.0 24967.4 25229.2 25337.2 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#2] jquery.html Cycles:180 Average:24531.81 Median:4316.21 stddev:60410.69 (1399.6%) stddev-sans-first:60552.67 18:55:42 INFO - PROCESS | 448 | Values: 259.3 291.0 296.7 298.0 300.1 13165.9 17411.9 17541.8 17990.6 18754.4 17073.0 17383.1 17479.5 17624.4 17649.5 4990.5 5049.7 5238.0 5242.6 5244.8 4901.9 5033.1 5120.8 5122.9 5129.3 4889.1 4972.9 5057.1 5066.3 5070.5 4885.8 5022.9 5031.8 5055.3 5057.6 4272.5 4311.4 4312.9 4319.5 4320.1 20600.3 21413.5 21990.6 22139.6 22274.9 1854.7 1885.6 2022.2 2115.4 2396.4 13984.2 14211.6 14211.9 14554.1 14601.5 1637.6 1686.5 1704.9 1720.4 1721.6 79871.8 81553.6 81713.2 81735.2 82153.6 8189.1 8280.0 8287.9 8329.2 8381.4 9394.7 9745.4 10744.9 11331.5 11334.9 6424.1 6453.5 6453.7 6624.6 6631.4 6416.7 6434.6 6442.2 6484.3 6492.7 5409.4 5499.5 5503.1 5507.0 5587.7 1413.5 1422.2 1446.9 1487.8 1510.4 847.3 857.8 888.5 924.2 932.8 17845.8 18024.5 18687.2 18754.9 18975.3 765.6 815.0 834.6 844.0 870.0 8701.3 10297.1 10546.4 10569.9 10576.8 356158.0 358992.7 361949.0 362620.0 363960.0 82092.6 82423.0 82974.2 84067.2 85194.0 917.5 920.1 920.6 935.6 941.6 16386.7 16407.5 16487.5 16502.3 16966.7 30831.7 40109.4 42681.6 45017.0 46476.5 60685.7 60821.4 60901.0 61525.0 61653.0 6656.3 6708.1 6745.9 6966.0 7063.5 11986.9 13046.7 13331.7 13439.5 13633.0 10640.8 10755.5 11103.2 11119.3 11285.2 10796.2 10937.5 10999.3 11027.9 11260.2 9949.5 10390.4 11207.4 11339.9 11468.0 6768.1 6775.1 6776.4 6791.8 6811.6 7777.7 10931.6 10971.2 11173.1 11354.3 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#3] mootools.html Cycles:180 Average:4403.38 Median:5421.80 stddev:2471.08 (45.6%) stddev-sans-first:2466.01 18:55:42 INFO - PROCESS | 448 | Values: 1161.4 1188.9 1273.1 1545.2 1565.6 6586.4 7006.6 7077.2 7085.9 7101.0 5961.6 6129.4 6741.8 6890.1 6955.6 1786.4 1827.9 1828.0 1829.5 1829.6 1840.6 1849.6 1865.7 1871.4 1875.8 1587.8 1637.6 1786.9 1832.3 1835.7 1857.4 1859.6 1868.1 1868.3 1869.9 1799.5 1823.7 1831.4 1837.8 1850.0 6752.9 6851.4 6859.3 6894.8 6896.6 3857.8 3900.6 3916.4 3932.3 3937.8 2451.9 2513.5 2539.1 2552.9 2578.6 753.4 778.4 779.1 791.4 794.2 8425.4 8539.5 8549.6 8555.8 8566.7 3144.9 3244.6 3248.6 3275.3 3311.6 6877.2 6922.5 6977.3 6982.0 7008.3 3828.4 3850.1 3859.5 3861.2 3863.2 3676.4 3680.2 3711.5 3716.3 3723.6 2617.7 2782.3 2805.8 2815.3 2818.6 816.1 840.7 842.1 861.5 864.0 657.7 779.8 793.5 794.7 803.9 4611.6 4649.1 4666.3 4699.6 4759.9 325.8 334.2 336.2 337.4 339.6 3124.4 3228.7 3240.4 3292.5 3295.1 7938.4 7996.7 8118.9 8162.0 8177.8 8081.6 8083.4 8110.3 8118.4 8119.6 6402.1 6487.1 6515.7 6516.1 6520.7 4613.5 4633.9 4654.5 4667.3 4814.2 7429.0 7569.2 7649.1 7751.8 7762.8 7735.7 7785.9 7798.4 7808.6 7818.0 5451.7 5663.5 5968.7 6034.5 6175.8 6851.1 6881.4 6895.0 6896.9 6905.6 5896.7 6164.0 6222.8 6299.9 6316.9 6021.8 6049.2 6178.2 6183.9 6208.1 5391.9 5498.6 5503.5 5551.2 5554.9 4102.4 4103.4 4219.3 4229.9 4258.9 4049.6 4053.4 4075.4 4079.1 4144.1 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#4] prototype.html Cycles:180 Average:4317.67 Median:5049.38 stddev:1405.45 (27.8%) stddev-sans-first:1375.27 18:55:42 INFO - PROCESS | 448 | Values: 216.2 226.4 231.4 231.9 232.7 4964.4 5043.4 5192.3 5194.8 5232.5 5050.2 5072.4 5126.5 5130.5 5142.1 3630.1 3646.3 3649.9 3662.4 3666.9 3636.2 3658.4 3658.9 3661.5 3673.3 3505.4 3551.3 3551.3 3627.5 3632.3 3606.6 3615.2 3625.9 3627.7 3630.0 3026.4 3042.6 3046.0 3066.6 3070.2 4971.6 5019.1 5026.0 5048.5 5053.3 3813.4 4097.4 4100.6 4117.3 4210.8 5079.3 5258.5 5260.3 5273.6 5280.3 3123.3 3263.4 3277.1 3291.6 3310.2 5488.9 5525.8 5533.1 5539.6 5554.2 3295.9 3344.2 3417.8 3431.2 3438.8 3161.7 3460.3 3469.0 3477.1 3487.8 4157.7 4176.5 4178.8 4188.7 4191.7 5119.7 5326.9 5341.3 5347.3 5366.4 1177.8 1186.0 1196.5 1196.8 1203.8 687.7 714.9 723.9 725.1 725.6 4566.8 4585.2 4585.6 4594.2 4626.6 4941.5 4975.8 4977.2 4980.7 5007.2 4588.5 4589.4 4605.5 4876.4 4969.5 2868.5 2897.8 2937.0 2941.9 2944.5 5711.3 5741.9 5750.8 5772.1 5779.9 5499.5 5601.2 5626.8 5657.7 5663.2 5564.2 5728.5 5730.1 5754.3 5762.5 5468.3 5470.3 5510.3 5511.8 5515.8 5417.2 5442.7 5444.9 5455.3 5455.9 5536.4 5704.7 5721.2 5730.4 5740.9 3287.1 3400.3 3404.5 3407.1 3409.8 5411.3 5507.3 5520.6 5522.4 5538.0 5105.9 5117.9 5274.5 5345.4 5348.5 5226.8 5301.6 5303.7 5319.0 5335.4 5171.6 5241.3 5241.7 5245.5 5262.5 5295.8 5347.3 5355.2 5355.3 5370.9 5107.7 5252.1 5264.9 5267.7 5294.0 18:55:42 INFO - PROCESS | 448 | 18:55:42 INFO - PROCESS | 448 | [#5] yui.html Cycles:180 Average:20172.77 Median:3828.04 stddev:29754.99 (777.3%) stddev-sans-first:29802.29 18:55:42 INFO - PROCESS | 448 | Values: 633.6 694.2 694.8 699.9 703.3 14031.9 14705.8 14774.8 14842.0 15464.5 14843.7 14926.9 15248.5 15330.6 15470.1 646.1 660.4 662.1 662.6 663.7 525.2 537.1 643.3 655.7 655.9 676.2 680.3 682.3 692.3 692.6 681.1 689.8 691.4 692.7 695.0 399.9 402.3 402.4 410.4 500.1 13729.7 13917.4 14220.7 14237.4 14294.4 13361.9 13394.1 13418.5 13741.0 13747.9 11398.2 11398.7 11620.2 11683.3 11764.4 3234.4 3279.3 3370.7 3375.1 3402.4 81368.6 83522.6 84046.6 84199.6 84243.2 10321.7 10329.4 10359.2 10378.2 10510.4 18825.0 18890.6 18913.2 18936.6 18961.5 6694.2 7054.5 7167.2 7310.6 7330.7 3754.4 3825.2 3830.9 3887.3 3940.1 2697.9 2793.9 2802.3 2884.0 2890.8 487.8 499.2 505.5 509.6 509.6 593.8 649.9 669.5 673.6 686.7 8979.6 8989.1 9061.3 9145.7 9334.5 614.7 644.4 678.6 682.6 703.4 4442.6 4711.9 4725.6 4753.8 4803.8 100455.0 105999.0 107961.3 110174.4 111484.4 98800.0 100731.0 100841.0 101126.5 102304.0 83323.6 87354.6 87396.1 87894.6 89009.6 8307.3 8319.1 8548.5 8567.5 8785.0 49243.5 49266.5 50245.0 51237.5 52209.7 65876.3 69297.3 70035.0 71482.3 72471.0 10028.0 11007.6 11089.9 11423.5 11641.6 13556.9 13648.3 13747.2 13841.1 13919.8 12533.4 12560.9 12684.9 13058.6 13182.4 11564.6 12136.6 12137.3 12336.3 12466.6 12315.7 12500.4 12542.7 12636.6 12683.7 10969.8 11211.9 11272.7 11472.1 11497.6 10384.2 10427.7 10562.8 10723.9 10903.0 18:55:42 INFO - PROCESS | 448 | -------- Summary: end -------- 18:55:42 INFO - PROCESS | 448 | 18:55:43 INFO - TEST-INFO | 448: exit 0 18:55:44 INFO - TEST-OK | dromaeo_css | took 1119937ms 18:55:44 INFO - TEST-START | kraken 18:55:44 INFO - operating with platform_type : w7_ 18:55:44 INFO - Initialising browser for kraken test... 18:55:44 INFO - TEST-INFO | started process 3304 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpo_2t05\profile http://localhost:49676/getInfo.html) 18:55:48 INFO - PROCESS | 3304 | __metrics Screen width/height:1600/1200 18:55:48 INFO - PROCESS | 3304 | colorDepth:24 18:55:48 INFO - PROCESS | 3304 | Browser inner width/height: 1010/674 18:55:48 INFO - PROCESS | 3304 | __metrics 18:55:49 INFO - PROCESS | 3304 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:55:49 INFO - PROCESS | 3304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:55:49 INFO - PROCESS | 3304 | console.error: 18:55:49 INFO - PROCESS | 3304 | Message: Error: Connection closed before committing the transaction. 18:55:49 INFO - PROCESS | 3304 | Stack: 18:55:49 INFO - PROCESS | 3304 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 18:55:49 INFO - PROCESS | 3304 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 18:55:49 INFO - PROCESS | 3304 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 18:55:49 INFO - PROCESS | 3304 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 18:55:49 INFO - PROCESS | 3304 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 18:55:49 INFO - PROCESS | 3304 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 18:55:49 INFO - PROCESS | 3304 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 18:55:49 INFO - PROCESS | 3304 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 18:55:49 INFO - PROCESS | 3304 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 18:55:49 INFO - PROCESS | 3304 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 18:55:49 INFO - PROCESS | 3304 | Barrier/this.client.addBlocker/promise13.1% 18:56:46 INFO - TinderboxPrint: I/O read bytes / time
96,495,104 / 1,293,120 18:56:46 INFO - TinderboxPrint: I/O write bytes / time
473,456,640 / 3,925,380 18:56:46 INFO - TinderboxPrint: CPU idle
8,251.9 (86.9%) 18:56:46 INFO - TinderboxPrint: CPU user
1,209.3 (12.7%) 18:56:46 INFO - install - Wall time: 4s; CPU: 7.0%; Read bytes: 0; Write bytes: 61447680; Read time: 0; Write time: 703690 18:56:46 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 96467456; Write bytes: 399790592; Read time: 1246170; Write time: 3068290 18:56:47 INFO - Running post-run listener: _upload_blobber_files 18:56:47 INFO - Blob upload gear active. 18:56:47 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 18:56:47 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 18:56:47 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', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 18:56:47 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 Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 18:56:48 INFO - (blobuploader) - INFO - Open directory for files ... 18:56:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 18:56:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:56:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:48 INFO - (blobuploader) - INFO - Done attempting. 18:56:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 18:56:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:49 INFO - (blobuploader) - INFO - Done attempting. 18:56:49 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 18:56:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:56:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:57 INFO - (blobuploader) - INFO - Done attempting. 18:56:57 INFO - (blobuploader) - INFO - Iteration through files over. 18:56:57 INFO - Return code: 0 18:56:57 INFO - rmtree: C:\slave\test\build\uploaded_files.json 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 18:56:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66429a56fadacaf0d29df293357a0e15054f940db58f23a7f7976f9a51536c5a88c321f39cf2bc7deb7b6afc304582e9f47d7a7a61711dccbee291d9eec0b142", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56e375bd98dc711992bd402e3ba3c3f8d03c02aa8816966965d52c3d4bda9c6007cf78257e18d9100825bf94af7b9cd286ff9c516aac008f16f981a65c6030a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ef3db37ea965c257efdf728957e7f1d154204cae5e2215a50c581e914f1adb31a74872587d634a4aa4d3aef4c9efba68f51afc03706b6b558060e5109d1c686"} 18:56:57 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 18:56:57 INFO - Writing to file C:\slave\test\properties\blobber_files 18:56:57 INFO - Contents: 18:56:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66429a56fadacaf0d29df293357a0e15054f940db58f23a7f7976f9a51536c5a88c321f39cf2bc7deb7b6afc304582e9f47d7a7a61711dccbee291d9eec0b142", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56e375bd98dc711992bd402e3ba3c3f8d03c02aa8816966965d52c3d4bda9c6007cf78257e18d9100825bf94af7b9cd286ff9c516aac008f16f981a65c6030a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ef3db37ea965c257efdf728957e7f1d154204cae5e2215a50c581e914f1adb31a74872587d634a4aa4d3aef4c9efba68f51afc03706b6b558060e5109d1c686"} 18:56:57 INFO - Running post-run listener: copy_logs_to_upload_dir 18:56:57 INFO - Copying logs to upload dir... 18:56:57 INFO - mkdir: C:\slave\test\build\upload\logs 18:56:57 INFO - Copying logs to upload dir... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... 18:56:57 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1484.884000 ========= master_lag: -0.57 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 44 secs) (at 2016-10-20 18:56:57.797429) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 18:56:57.798109) ========= '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-030 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-030 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-030 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66429a56fadacaf0d29df293357a0e15054f940db58f23a7f7976f9a51536c5a88c321f39cf2bc7deb7b6afc304582e9f47d7a7a61711dccbee291d9eec0b142", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56e375bd98dc711992bd402e3ba3c3f8d03c02aa8816966965d52c3d4bda9c6007cf78257e18d9100825bf94af7b9cd286ff9c516aac008f16f981a65c6030a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ef3db37ea965c257efdf728957e7f1d154204cae5e2215a50c581e914f1adb31a74872587d634a4aa4d3aef4c9efba68f51afc03706b6b558060e5109d1c686"} build_url:https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/NH1LJkkcTiyRSM9Q1L6qbQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66429a56fadacaf0d29df293357a0e15054f940db58f23a7f7976f9a51536c5a88c321f39cf2bc7deb7b6afc304582e9f47d7a7a61711dccbee291d9eec0b142", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/56e375bd98dc711992bd402e3ba3c3f8d03c02aa8816966965d52c3d4bda9c6007cf78257e18d9100825bf94af7b9cd286ff9c516aac008f16f981a65c6030a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ef3db37ea965c257efdf728957e7f1d154204cae5e2215a50c581e914f1adb31a74872587d634a4aa4d3aef4c9efba68f51afc03706b6b558060e5109d1c686"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 18:56:57.911488) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 18:56:57.911808) ========= '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-030 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-030 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-030 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-10-20 18:56:58.023621) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 18:56:58.023926) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-20 18:56:58.552598) ========= ========= Total master_lag: 9.96 =========