builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-095 starttime: 1474508770.0 results: success (0) buildid: 20160921153000 builduid: 38d70451cdc84c52b462ba29f3834586 revision: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:10.001880) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:10.002533) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:10.003362) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:10.138411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 18:46:10.138783) ========= '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-095 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-095 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-095 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:46:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 9.28 MB/s 18:46:11 (9.28 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.272000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 18:46:11.474025) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-21 18:46:11.474385) ========= '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-095 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-095 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-095 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=11.001000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-09-21 18:46:22.485912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-21 18:46:22.486288) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 94d6bc5adab3d63533f5c5c33df14c7d3f763859 --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 94d6bc5adab3d63533f5c5c33df14c7d3f763859 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-095 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-095 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-095 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-09-21 18:46:22,749 truncating revision to first 12 chars 2016-09-21 18:46:22,749 Setting DEBUG logging. 2016-09-21 18:46:22,749 attempt 1/10 2016-09-21 18:46:22,749 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/94d6bc5adab3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 18:46:24,270 unpacking tar archive at: fx-team-94d6bc5adab3/testing/mozharness/ 2016-09-21 18:46:24,414 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.096000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-21 18:46:24.649969) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:24.650311) ========= script_repo_revision: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:24.650727) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:24.651004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 18:46:24.662072) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 22 secs) (at 2016-09-21 18:46:24.662498) ========= '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-095 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-095 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-095 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:46:25 INFO - MultiFileLogger online at 20160921 18:46:25 in C:\slave\test 18:46:25 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:46:25 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 18:46:25 INFO - {'append_to_log': False, 18:46:25 INFO - 'base_work_dir': 'C:\\slave\\test', 18:46:25 INFO - 'blob_upload_branch': 'Fx-Team', 18:46:25 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 18:46:25 INFO - 'branch': 'Fx-Team', 18:46:25 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:25 INFO - 'config_files': ('talos/windows_config.py',), 18:46:25 INFO - 'default_actions': ('clobber', 18:46:25 INFO - 'read-buildbot-config', 18:46:25 INFO - 'download-and-extract', 18:46:25 INFO - 'populate-webroot', 18:46:25 INFO - 'create-virtualenv', 18:46:25 INFO - 'install', 18:46:25 INFO - 'run-tests'), 18:46:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:25 INFO - 'download_minidump_stackwalk': True, 18:46:25 INFO - 'download_symbols': 'ondemand', 18:46:25 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 18:46:25 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 18:46:25 INFO - 'hg': 'c:/mozilla-build/hg/hg', 18:46:25 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 18:46:25 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 18:46:25 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 18:46:25 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 18:46:25 INFO - 'C:/mozilla-build/tooltool.py'), 18:46:25 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 18:46:25 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 18:46:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:25 INFO - 'installer_path': 'installer.exe', 18:46:25 INFO - 'log_level': 'info', 18:46:25 INFO - 'log_name': 'talos', 18:46:25 INFO - 'log_to_console': True, 18:46:25 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 18:46:25 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 18:46:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 18:46:25 INFO - 'opt_config_files': (), 18:46:25 INFO - 'pip_index': False, 18:46:25 INFO - 'sps_profile': False, 18:46:25 INFO - 'sps_profile_interval': 0, 18:46:25 INFO - 'suite': 'dromaeojs', 18:46:25 INFO - 'system_bits': '32', 18:46:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:25 INFO - 'title': 'T-W732-IX-095', 18:46:25 INFO - 'use_talos_json': True, 18:46:25 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 18:46:25 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 18:46:25 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 18:46:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:25 INFO - 'work_dir': 'build'} 18:46:25 INFO - [mozharness: 2016-09-22 01:46:25.164000Z] Running clobber step. 18:46:25 INFO - Running pre-action listener: _resource_record_pre_action 18:46:25 INFO - Running main action method: clobber 18:46:25 INFO - rmtree: C:\slave\test\build 18:46:25 INFO - Using _rmtree_windows ... 18:46:25 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 18:47:34 INFO - Running post-action listener: _resource_record_post_action 18:47:34 INFO - [mozharness: 2016-09-22 01:47:34.515000Z] Finished clobber step (success) 18:47:34 INFO - [mozharness: 2016-09-22 01:47:34.515000Z] Running read-buildbot-config step. 18:47:34 INFO - Running pre-action listener: _resource_record_pre_action 18:47:34 INFO - Running main action method: read_buildbot_config 18:47:34 INFO - Using buildbot properties: 18:47:34 INFO - { 18:47:34 INFO - "project": "", 18:47:34 INFO - "product": "firefox", 18:47:34 INFO - "script_repo_revision": "production", 18:47:34 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 18:47:34 INFO - "repository": "", 18:47:34 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 18:47:34 INFO - "buildid": "20160921153000", 18:47:34 INFO - "pgo_build": "True", 18:47:34 INFO - "basedir": "C:\\slave\\test", 18:47:34 INFO - "buildnumber": 199, 18:47:34 INFO - "slavename": "t-w732-ix-095", 18:47:34 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 18:47:34 INFO - "platform": "win7_ix", 18:47:34 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 18:47:34 INFO - "branch": "fx-team", 18:47:34 INFO - "repo_path": "integration/fx-team", 18:47:34 INFO - "revision": "94d6bc5adab3d63533f5c5c33df14c7d3f763859", 18:47:34 INFO - "stage_platform": "win32-pgo", 18:47:34 INFO - "builduid": "38d70451cdc84c52b462ba29f3834586", 18:47:34 INFO - "slavebuilddir": "test" 18:47:34 INFO - } 18:47:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 18:47:34 INFO - Running post-action listener: _resource_record_post_action 18:47:34 INFO - [mozharness: 2016-09-22 01:47:34.518000Z] Finished read-buildbot-config step (success) 18:47:34 INFO - [mozharness: 2016-09-22 01:47:34.519000Z] Running download-and-extract step. 18:47:34 INFO - Running pre-action listener: _resource_record_pre_action 18:47:34 INFO - Running main action method: download_and_extract 18:47:34 INFO - mkdir: C:\slave\test\build\tests 18:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:34 INFO - trying https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 18:47:34 INFO - Downloading https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/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:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/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:47:35 INFO - Downloaded 1218 bytes. 18:47:35 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 18:47:35 INFO - Using the following test package requirements: 18:47:35 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:47:35 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 18:47:35 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:47:35 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'jsshell-win32.zip'], 18:47:35 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 18:47:35 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 18:47:35 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 18:47:35 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 18:47:35 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 18:47:35 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 18:47:35 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 18:47:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 18:47:35 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 18:47:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:47:35 INFO - Fetch https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:47:36 INFO - Expected file size: 17284460 18:48:11 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:48:11 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 18:48:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 18:48:11 INFO - Fetch https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 18:48:11 INFO - Expected file size: 17284460 18:48:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 18:48:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 18:48:22 INFO - Fetch https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 18:48:23 INFO - Expected file size: 14414059 18:48:39 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:48:39 INFO - trying https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:48:39 INFO - Downloading https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 18:48:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/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:48:42 INFO - Downloaded 54689945 bytes. 18:48:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:48:42 INFO - mkdir: C:\slave\test\properties 18:48:42 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 18:48:42 INFO - Writing to file C:\slave\test\properties\build_url 18:48:42 INFO - Contents: 18:48:42 INFO - build_url:https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 18:48:43 INFO - Running post-action listener: _resource_record_post_action 18:48:43 INFO - Running post-action listener: set_extra_try_arguments 18:48:43 INFO - [mozharness: 2016-09-22 01:48:43.518000Z] Finished download-and-extract step (success) 18:48:43 INFO - [mozharness: 2016-09-22 01:48:43.518000Z] Running populate-webroot step. 18:48:43 INFO - Running pre-action listener: _resource_record_pre_action 18:48:43 INFO - Running main action method: populate_webroot 18:48:43 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 18:48:43 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 18:48:43 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 18:48:43 INFO - u'--tppagecycles', 18:48:43 INFO - u'7'], 18:48:43 INFO - u'tests': [u'tsvgm']}}, 18:48:43 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 18:48:43 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 18:48:43 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 18:48:43 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 18:48:43 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:48:43 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 18:48:43 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'damp', u'tps']}, 18:48:43 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'tests': [u'damp', u'tps']}, 18:48:43 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'dromaeo_dom']}, 18:48:43 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 18:48:43 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'basic_compositor_video']}, 18:48:43 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 18:48:43 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'other-e10s': {u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'a11yr', 18:48:43 INFO - u'ts_paint', 18:48:43 INFO - u'tpaint', 18:48:43 INFO - u'sessionrestore', 18:48:43 INFO - u'sessionrestore_no_auto_restore', 18:48:43 INFO - u'tabpaint']}, 18:48:43 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'tsvgx', 18:48:43 INFO - u'tsvgr_opacity', 18:48:43 INFO - u'tart', 18:48:43 INFO - u'tscrollx', 18:48:43 INFO - u'cart']}, 18:48:43 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 18:48:43 INFO - u'tsvgr_opacity', 18:48:43 INFO - u'tart', 18:48:43 INFO - u'tscrollx', 18:48:43 INFO - u'cart']}, 18:48:43 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'talos_options': [u'--disable-e10s'], 18:48:43 INFO - u'tests': [u'tp5o']}, 18:48:43 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'tests': [u'tp5o']}, 18:48:43 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'talos_options': [u'--disable-e10s', 18:48:43 INFO - u'--xperf_path', 18:48:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:43 INFO - u'tests': [u'tp5n']}, 18:48:43 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:48:43 INFO - u'talos_options': [u'--xperf_path', 18:48:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:48:43 INFO - u'tests': [u'tp5n']}}, 18:48:43 INFO - u'talos.zip': {u'path': u'', 18:48:43 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:48:43 INFO - Running post-action listener: _resource_record_post_action 18:48:43 INFO - [mozharness: 2016-09-22 01:48:43.553000Z] Finished populate-webroot step (success) 18:48:43 INFO - [mozharness: 2016-09-22 01:48:43.553000Z] Running create-virtualenv step. 18:48:43 INFO - Running pre-action listener: _resource_record_pre_action 18:48:43 INFO - Running main action method: create_virtualenv 18:48:43 INFO - Creating virtualenv C:\slave\test\build/venv 18:48:43 INFO - mkdir: C:\slave\test\build\venv\Scripts 18:48:43 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 18:48:43 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:48:43 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:48:43 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:48:52 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 18:48:55 INFO - Installing distribute......................................................................................................................................................................................done. 18:48:56 INFO - Return code: 0 18:48:56 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 18:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:56 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:48:56 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:48:56 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:48:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:48:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:48:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:48:56 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:48:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:48:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:48:56 INFO - 'DCLOCATION': 'SCL3', 18:48:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:48:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:48:56 INFO - 'HOMEDRIVE': 'C:', 18:48:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:48:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:48:56 INFO - 'KTS_VERSION': '1.19c', 18:48:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:48:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:48:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:48:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:56 INFO - 'MOZ_NO_REMOTE': '1', 18:48:56 INFO - 'NO_EM_RESTART': '1', 18:48:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:48:56 INFO - 'OS': 'Windows_NT', 18:48:56 INFO - 'OURDRIVE': 'C:', 18:48:56 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:48:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:48:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:48:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:48:56 INFO - 'PROCESSOR_LEVEL': '6', 18:48:56 INFO - 'PROCESSOR_REVISION': '1e05', 18:48:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:48:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:48:56 INFO - 'PROMPT': '$P$G', 18:48:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:48:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:48:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:48:56 INFO - 'PWD': 'C:\\slave\\test', 18:48:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:48:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:48:56 INFO - 'SYSTEMDRIVE': 'C:', 18:48:56 INFO - 'SYSTEMROOT': 'C:\\windows', 18:48:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:48:56 INFO - 'TEST1': 'testie', 18:48:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:48:56 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:48:56 INFO - 'USERNAME': 'cltbld', 18:48:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:48:56 INFO - 'WINDIR': 'C:\\windows', 18:48:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:48:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:48:57 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:48:57 INFO - Downloading/unpacking pip>=1.5 18:49:02 INFO - Running setup.py egg_info for package pip 18:49:02 INFO - warning: no files found matching 'pip\cacert.pem' 18:49:02 INFO - warning: no files found matching '*.html' under directory 'docs' 18:49:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:49:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:49:02 INFO - Installing collected packages: pip 18:49:02 INFO - Found existing installation: pip 0.8.2 18:49:02 INFO - Uninstalling pip: 18:49:02 INFO - Successfully uninstalled pip 18:49:02 INFO - Running setup.py install for pip 18:49:02 INFO - warning: no files found matching 'pip\cacert.pem' 18:49:02 INFO - warning: no files found matching '*.html' under directory 'docs' 18:49:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:49:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:49:02 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 18:49:02 INFO - Successfully installed pip 18:49:02 INFO - Cleaning up... 18:49:02 INFO - Return code: 0 18:49:02 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 18:49: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:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49: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:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:02 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:02 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:49:02 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:49:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:02 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:02 INFO - 'DCLOCATION': 'SCL3', 18:49:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:02 INFO - 'HOMEDRIVE': 'C:', 18:49:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:02 INFO - 'KTS_VERSION': '1.19c', 18:49:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:02 INFO - 'MOZ_NO_REMOTE': '1', 18:49:02 INFO - 'NO_EM_RESTART': '1', 18:49:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:02 INFO - 'OS': 'Windows_NT', 18:49:02 INFO - 'OURDRIVE': 'C:', 18:49:02 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:49:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:02 INFO - 'PROCESSOR_LEVEL': '6', 18:49:02 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:02 INFO - 'PROMPT': '$P$G', 18:49:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:02 INFO - 'PWD': 'C:\\slave\\test', 18:49:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:02 INFO - 'SYSTEMDRIVE': 'C:', 18:49:02 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:02 INFO - 'TEST1': 'testie', 18:49:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:02 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:02 INFO - 'USERNAME': 'cltbld', 18:49:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:02 INFO - 'WINDIR': 'C:\\windows', 18:49:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:03 INFO - Downloading/unpacking psutil>=3.1.1 18:49:03 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:49:03 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:49:04 INFO - Installing collected packages: psutil 18:49:05 INFO - Successfully installed psutil 18:49:05 INFO - Cleaning up... 18:49:05 INFO - Return code: 0 18:49:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 18:49:05 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:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:05 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:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:05 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:05 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:49:05 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:49:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:05 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:05 INFO - 'DCLOCATION': 'SCL3', 18:49:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:05 INFO - 'HOMEDRIVE': 'C:', 18:49:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:05 INFO - 'KTS_VERSION': '1.19c', 18:49:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:05 INFO - 'MOZ_NO_REMOTE': '1', 18:49:05 INFO - 'NO_EM_RESTART': '1', 18:49:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:05 INFO - 'OS': 'Windows_NT', 18:49:05 INFO - 'OURDRIVE': 'C:', 18:49:05 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:49:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:05 INFO - 'PROCESSOR_LEVEL': '6', 18:49:05 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:05 INFO - 'PROMPT': '$P$G', 18:49:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:05 INFO - 'PWD': 'C:\\slave\\test', 18:49:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:05 INFO - 'SYSTEMDRIVE': 'C:', 18:49:05 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:05 INFO - 'TEST1': 'testie', 18:49:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:05 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:05 INFO - 'USERNAME': 'cltbld', 18:49:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:05 INFO - 'WINDIR': 'C:\\windows', 18:49:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:49:05 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:49:05 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:49:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:49:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 18:49:06 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:49:06 INFO - Installing collected packages: mozsystemmonitor 18:49:06 INFO - Running setup.py install for mozsystemmonitor 18:49:06 INFO - Successfully installed mozsystemmonitor 18:49:06 INFO - Cleaning up... 18:49:06 INFO - Return code: 0 18:49:06 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 18:49:06 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:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:06 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:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:06 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:06 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:49:06 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:49:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:06 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:06 INFO - 'DCLOCATION': 'SCL3', 18:49:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:06 INFO - 'HOMEDRIVE': 'C:', 18:49:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:06 INFO - 'KTS_VERSION': '1.19c', 18:49:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:06 INFO - 'MOZ_NO_REMOTE': '1', 18:49:06 INFO - 'NO_EM_RESTART': '1', 18:49:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:06 INFO - 'OS': 'Windows_NT', 18:49:06 INFO - 'OURDRIVE': 'C:', 18:49:06 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:49:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:06 INFO - 'PROCESSOR_LEVEL': '6', 18:49:06 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:06 INFO - 'PROMPT': '$P$G', 18:49:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:06 INFO - 'PWD': 'C:\\slave\\test', 18:49:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:06 INFO - 'SYSTEMDRIVE': 'C:', 18:49:06 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:06 INFO - 'TEST1': 'testie', 18:49:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:06 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:06 INFO - 'USERNAME': 'cltbld', 18:49:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:06 INFO - 'WINDIR': 'C:\\windows', 18:49:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:07 INFO - Downloading/unpacking jsonschema==2.5.1 18:49:07 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:49:07 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:49:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:49:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:49:08 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:49:08 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:49:08 INFO - Downloading functools32-3.2.3-2.tar.gz 18:49:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 18:49:08 INFO - warning: no files found matching '*.txt' 18:49:08 INFO - no previously-included directories found matching 'build' 18:49:08 INFO - no previously-included directories found matching 'dist' 18:49:08 INFO - no previously-included directories found matching '.git*' 18:49:08 INFO - Installing collected packages: jsonschema, functools32 18:49:08 INFO - Running setup.py install for functools32 18:49:08 INFO - warning: no files found matching '*.txt' 18:49:08 INFO - no previously-included directories found matching 'build' 18:49:08 INFO - no previously-included directories found matching 'dist' 18:49:08 INFO - no previously-included directories found matching '.git*' 18:49:09 INFO - Successfully installed jsonschema functools32 18:49:09 INFO - Cleaning up... 18:49:09 INFO - Return code: 0 18:49:09 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 18:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 18:49:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:49:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:09 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:09 INFO - 'DCLOCATION': 'SCL3', 18:49:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:09 INFO - 'HOMEDRIVE': 'C:', 18:49:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:09 INFO - 'KTS_VERSION': '1.19c', 18:49:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:09 INFO - 'MOZ_NO_REMOTE': '1', 18:49:09 INFO - 'NO_EM_RESTART': '1', 18:49:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:09 INFO - 'OS': 'Windows_NT', 18:49:09 INFO - 'OURDRIVE': 'C:', 18:49:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:49:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:09 INFO - 'PROCESSOR_LEVEL': '6', 18:49:09 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:09 INFO - 'PROMPT': '$P$G', 18:49:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:09 INFO - 'PWD': 'C:\\slave\\test', 18:49:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:09 INFO - 'SYSTEMDRIVE': 'C:', 18:49:09 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:09 INFO - 'TEST1': 'testie', 18:49:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:09 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:09 INFO - 'USERNAME': 'cltbld', 18:49:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:09 INFO - 'WINDIR': 'C:\\windows', 18:49:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 18:49:09 INFO - Cleaning up... 18:49:09 INFO - Return code: 0 18:49:09 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 18:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 18:49:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:49:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:09 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:09 INFO - 'DCLOCATION': 'SCL3', 18:49:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:09 INFO - 'HOMEDRIVE': 'C:', 18:49:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:09 INFO - 'KTS_VERSION': '1.19c', 18:49:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:09 INFO - 'MOZ_NO_REMOTE': '1', 18:49:09 INFO - 'NO_EM_RESTART': '1', 18:49:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:09 INFO - 'OS': 'Windows_NT', 18:49:09 INFO - 'OURDRIVE': 'C:', 18:49:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 18:49:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:09 INFO - 'PROCESSOR_LEVEL': '6', 18:49:09 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:09 INFO - 'PROMPT': '$P$G', 18:49:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:09 INFO - 'PWD': 'C:\\slave\\test', 18:49:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:09 INFO - 'SYSTEMDRIVE': 'C:', 18:49:09 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:09 INFO - 'TEST1': 'testie', 18:49:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:09 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:09 INFO - 'USERNAME': 'cltbld', 18:49:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:09 INFO - 'WINDIR': 'C:\\windows', 18:49:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:10 INFO - Downloading/unpacking blobuploader==1.2.4 18:49:10 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:49:10 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:49:11 INFO - Downloading blobuploader-1.2.4.tar.gz 18:49:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 18:49:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:49:11 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:49:11 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:49:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 18:49:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:49:11 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:49:11 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:49:11 INFO - Downloading docopt-0.6.1.tar.gz 18:49:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 18:49:12 INFO - Installing collected packages: blobuploader, requests, docopt 18:49:12 INFO - Running setup.py install for blobuploader 18:49:12 INFO - Running setup.py install for requests 18:49:13 INFO - Running setup.py install for docopt 18:49:13 INFO - Successfully installed blobuploader requests docopt 18:49:13 INFO - Cleaning up... 18:49:14 INFO - Return code: 0 18:49:14 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:49:14 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:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:14 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:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:14 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:14 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:49:14 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:49:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:14 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:14 INFO - 'DCLOCATION': 'SCL3', 18:49:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:14 INFO - 'HOMEDRIVE': 'C:', 18:49:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:14 INFO - 'KTS_VERSION': '1.19c', 18:49:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:14 INFO - 'MOZ_NO_REMOTE': '1', 18:49:14 INFO - 'NO_EM_RESTART': '1', 18:49:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:14 INFO - 'OS': 'Windows_NT', 18:49:14 INFO - 'OURDRIVE': 'C:', 18:49:14 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:49:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:14 INFO - 'PROCESSOR_LEVEL': '6', 18:49:14 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:14 INFO - 'PROMPT': '$P$G', 18:49:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:14 INFO - 'PWD': 'C:\\slave\\test', 18:49:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:14 INFO - 'SYSTEMDRIVE': 'C:', 18:49:14 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:14 INFO - 'TEST1': 'testie', 18:49:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:14 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:14 INFO - 'USERNAME': 'cltbld', 18:49:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:14 INFO - 'WINDIR': 'C:\\windows', 18:49:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:49:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ex4c63-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qx7gkg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5xhsmd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2xcoqv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qmwuj3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yjcmqg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8nzuh5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jxgikr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:49:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:49:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vswptl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:49:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:49:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o8i0eu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j6_kde-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fvoaob-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-os0ejy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1cuvld-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2rqdch-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1yaiij-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:49:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:49:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ru52n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:49:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:49:18 INFO - Running setup.py install for manifestparser 18:49:19 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 18:49:19 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 18:49:19 INFO - Running setup.py install for mozcrash 18:49:20 INFO - Running setup.py install for mozdebug 18:49:20 INFO - Running setup.py install for mozdevice 18:49:20 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 18:49:20 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 18:49:20 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 18:49:20 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 18:49:21 INFO - Running setup.py install for mozfile 18:49:21 INFO - Running setup.py install for mozhttpd 18:49:21 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 18:49:21 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 18:49:21 INFO - Running setup.py install for mozinfo 18:49:22 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Running setup.py install for mozInstall 18:49:22 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 18:49:22 INFO - Running setup.py install for mozleak 18:49:22 INFO - Running setup.py install for mozlog 18:49:23 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 18:49:23 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 18:49:23 INFO - Running setup.py install for moznetwork 18:49:23 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 18:49:23 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Running setup.py install for mozprocess 18:49:24 INFO - Running setup.py install for mozprofile 18:49:24 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 18:49:24 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 18:49:25 INFO - Running setup.py install for mozrunner 18:49:25 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 18:49:25 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 18:49:26 INFO - Running setup.py install for mozscreenshot 18:49:26 INFO - Running setup.py install for moztest 18:49:27 INFO - Running setup.py install for mozversion 18:49:27 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 18:49:27 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 18:49:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:49:27 INFO - Cleaning up... 18:49:27 INFO - Return code: 0 18:49:27 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:49: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:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49: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:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:27 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:27 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:49:27 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:49:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:27 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:27 INFO - 'DCLOCATION': 'SCL3', 18:49:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:27 INFO - 'HOMEDRIVE': 'C:', 18:49:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:27 INFO - 'KTS_VERSION': '1.19c', 18:49:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:27 INFO - 'MOZ_NO_REMOTE': '1', 18:49:27 INFO - 'NO_EM_RESTART': '1', 18:49:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:27 INFO - 'OS': 'Windows_NT', 18:49:27 INFO - 'OURDRIVE': 'C:', 18:49: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:49:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:27 INFO - 'PROCESSOR_LEVEL': '6', 18:49:27 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:27 INFO - 'PROMPT': '$P$G', 18:49:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:27 INFO - 'PWD': 'C:\\slave\\test', 18:49:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:27 INFO - 'SYSTEMDRIVE': 'C:', 18:49:27 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:27 INFO - 'TEST1': 'testie', 18:49:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:27 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:27 INFO - 'USERNAME': 'cltbld', 18:49:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:27 INFO - 'WINDIR': 'C:\\windows', 18:49:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:49:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-edox98-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1x2qv0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w7dwsz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_wzgqn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5ynhpf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9epffx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qga5vy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jgkgmo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:49:28 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:49:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:49:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kcsrii-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bugi5w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kpfnq_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ylt0au-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-72dh7k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-akp1zs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:49:29 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:49:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:49:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jrsjds-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:49:30 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:49:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:49:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ssu15e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:49:30 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:49:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:49:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ykkbjz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 18:49: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:49: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:49:32 INFO - Downloading blessings-1.6.tar.gz 18:49:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 18:49:32 INFO - Installing collected packages: blessings 18:49:32 INFO - Running setup.py install for blessings 18:49:32 INFO - Successfully installed blessings 18:49:32 INFO - Cleaning up... 18:49:35 INFO - Return code: 0 18:49:35 INFO - Done creating virtualenv C:\slave\test\build/venv. 18:49:35 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:49:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:49:35 INFO - Reading from file tmpfile_stdout 18:49:35 INFO - Using _rmtree_windows ... 18:49:35 INFO - Using _rmtree_windows ... 18:49:35 INFO - Current package versions: 18:49:35 INFO - blessings == 1.6 18:49:35 INFO - blobuploader == 1.2.4 18:49:35 INFO - docopt == 0.6.1 18:49:35 INFO - functools32 == 3.2.3-2 18:49:35 INFO - jsonschema == 2.5.1 18:49:35 INFO - manifestparser == 1.1 18:49:35 INFO - mozInstall == 1.12 18:49:35 INFO - mozcrash == 0.17 18:49:35 INFO - mozdebug == 0.1 18:49:35 INFO - mozdevice == 0.48 18:49:35 INFO - mozfile == 1.2 18:49:35 INFO - mozhttpd == 0.7 18:49:35 INFO - mozinfo == 0.9 18:49:35 INFO - mozleak == 0.1 18:49:35 INFO - mozlog == 3.4 18:49:35 INFO - moznetwork == 0.27 18:49:35 INFO - mozprocess == 0.23 18:49:35 INFO - mozprofile == 0.28 18:49:35 INFO - mozrunner == 6.12 18:49:35 INFO - mozscreenshot == 0.1 18:49:35 INFO - mozsystemmonitor == 0.3 18:49:35 INFO - moztest == 0.7 18:49:35 INFO - mozversion == 1.4 18:49:35 INFO - psutil == 3.1.1 18:49:35 INFO - requests == 1.2.3 18:49:35 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:49:35 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:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:35 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:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:35 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:35 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:49:35 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:49:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:35 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:35 INFO - 'DCLOCATION': 'SCL3', 18:49:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:35 INFO - 'HOMEDRIVE': 'C:', 18:49:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:35 INFO - 'KTS_VERSION': '1.19c', 18:49:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:35 INFO - 'MOZ_NO_REMOTE': '1', 18:49:35 INFO - 'NO_EM_RESTART': '1', 18:49:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:35 INFO - 'OS': 'Windows_NT', 18:49:35 INFO - 'OURDRIVE': 'C:', 18:49:35 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:49:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:35 INFO - 'PROCESSOR_LEVEL': '6', 18:49:35 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:35 INFO - 'PROMPT': '$P$G', 18:49:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:35 INFO - 'PWD': 'C:\\slave\\test', 18:49:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:35 INFO - 'SYSTEMDRIVE': 'C:', 18:49:35 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:35 INFO - 'TEST1': 'testie', 18:49:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:35 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:35 INFO - 'USERNAME': 'cltbld', 18:49:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:35 INFO - 'WINDIR': 'C:\\windows', 18:49:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 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:49:36 INFO - Cleaning up... 18:49:36 INFO - Return code: 0 18:49:36 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 18:49:36 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:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:36 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:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:36 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 0x0181B640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01896E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01792B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-095', 'USERDOMAIN': 'T-W732-IX-095', '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-095', '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:49:36 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:49:36 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:49:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:36 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:36 INFO - 'DCLOCATION': 'SCL3', 18:49:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:36 INFO - 'HOMEDRIVE': 'C:', 18:49:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:36 INFO - 'KTS_VERSION': '1.19c', 18:49:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:36 INFO - 'MOZ_NO_REMOTE': '1', 18:49:36 INFO - 'NO_EM_RESTART': '1', 18:49:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:36 INFO - 'OS': 'Windows_NT', 18:49:36 INFO - 'OURDRIVE': 'C:', 18:49:36 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:49:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:36 INFO - 'PROCESSOR_LEVEL': '6', 18:49:36 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:36 INFO - 'PROMPT': '$P$G', 18:49:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:36 INFO - 'PWD': 'C:\\slave\\test', 18:49:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:36 INFO - 'SYSTEMDRIVE': 'C:', 18:49:36 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:36 INFO - 'TEST1': 'testie', 18:49:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:36 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:36 INFO - 'USERNAME': 'cltbld', 18:49:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:36 INFO - 'WINDIR': 'C:\\windows', 18:49:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 18:49:36 INFO - Cleaning up... 18:49:36 INFO - Return code: 0 18:49:36 INFO - Running post-action listener: _resource_record_post_action 18:49:36 INFO - Running post-action listener: _start_resource_monitoring 18:49:36 INFO - Starting resource monitoring. 18:49:36 INFO - [mozharness: 2016-09-22 01:49:36.524000Z] Finished create-virtualenv step (success) 18:49:36 INFO - [mozharness: 2016-09-22 01:49:36.524000Z] Running install step. 18:49:36 INFO - Running pre-action listener: _resource_record_pre_action 18:49:36 INFO - Running main action method: install 18:49:36 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:49:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:49:36 INFO - Reading from file tmpfile_stdout 18:49:36 INFO - Using _rmtree_windows ... 18:49:36 INFO - Using _rmtree_windows ... 18:49:36 INFO - Detecting whether we're running mozinstall >=1.0... 18:49:36 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 18:49:36 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 18:49:36 INFO - Reading from file tmpfile_stdout 18:49:36 INFO - Output received: 18:49:36 INFO - Usage: mozinstall-script.py [options] installer 18:49:36 INFO - Options: 18:49:36 INFO - -h, --help show this help message and exit 18:49:36 INFO - -d DEST, --destination=DEST 18:49:36 INFO - Directory to install application into. [default: 18:49:36 INFO - "C:\slave\test"] 18:49:36 INFO - --app=APP Application being installed. [default: firefox] 18:49:36 INFO - Using _rmtree_windows ... 18:49:36 INFO - Using _rmtree_windows ... 18:49:36 INFO - mkdir: C:\slave\test\build\application 18:49:36 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:49:36 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:49:39 INFO - Reading from file tmpfile_stdout 18:49:39 INFO - Output received: 18:49:39 INFO - C:\slave\test\build\application\firefox\firefox.exe 18:49:39 INFO - Using _rmtree_windows ... 18:49:39 INFO - Using _rmtree_windows ... 18:49:39 INFO - Running post-action listener: _resource_record_post_action 18:49:39 INFO - [mozharness: 2016-09-22 01:49:39.591000Z] Finished install step (success) 18:49:39 INFO - [mozharness: 2016-09-22 01:49:39.591000Z] Running run-tests step. 18:49:39 INFO - Running pre-action listener: _resource_record_pre_action 18:49:39 INFO - Running main action method: run_tests 18:49:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 18:49:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 18:49:39 INFO - Python 2.7.3 18:49:39 INFO - Return code: 0 18:49:39 INFO - grabbing minidump binary from tooltool 18:49:39 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:49:39 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 0x01870E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FA5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 18:49:39 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:49:39 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:49:39 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:49:39 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 18:49:41 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp6i_w2d 18:49:41 INFO - INFO - File integrity verified, renaming tmp6i_w2d to win32-minidump_stackwalk.exe 18:49:41 INFO - Return code: 0 18:49:41 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 18:49:41 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 18:49:41 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 18:49:41 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 18:49:41 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 18:49:41 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/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-095', '--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:49:41 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/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-095 --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:49:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:49:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 18:49:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 18:49:41 INFO - 'COMPUTERNAME': 'T-W732-IX-095', 18:49:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:49:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 18:49:41 INFO - 'DCLOCATION': 'SCL3', 18:49:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:49:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:49:41 INFO - 'HOMEDRIVE': 'C:', 18:49:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 18:49:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:49:41 INFO - 'KTS_VERSION': '1.19c', 18:49:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 18:49:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-095', 18:49:41 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:49:41 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 18:49:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:49:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:41 INFO - 'MOZ_NO_REMOTE': '1', 18:49:41 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:49:41 INFO - 'NO_EM_RESTART': '1', 18:49:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:49:41 INFO - 'OS': 'Windows_NT', 18:49:41 INFO - 'OURDRIVE': 'C:', 18:49:41 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:49:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:49:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:49:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 18:49:41 INFO - 'PROCESSOR_LEVEL': '6', 18:49:41 INFO - 'PROCESSOR_REVISION': '1e05', 18:49:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:49:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 18:49:41 INFO - 'PROMPT': '$P$G', 18:49:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:49:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:49:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:49:41 INFO - 'PWD': 'C:\\slave\\test', 18:49:41 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 18:49:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:49:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:49:41 INFO - 'SYSTEMDRIVE': 'C:', 18:49:41 INFO - 'SYSTEMROOT': 'C:\\windows', 18:49:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:41 INFO - 'TEST1': 'testie', 18:49:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 18:49:41 INFO - 'USERDOMAIN': 'T-W732-IX-095', 18:49:41 INFO - 'USERNAME': 'cltbld', 18:49:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 18:49:41 INFO - 'WINDIR': 'C:\\windows', 18:49:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 18:49:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 18:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:49:41 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/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-095', '--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:49:42 INFO - mozversion application_buildid: 20160921153000 18:49:42 INFO - mozversion application_changeset: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 18:49:42 INFO - mozversion application_display_name: Nightly 18:49:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:49:42 INFO - mozversion application_name: Firefox 18:49:42 INFO - mozversion application_remotingname: firefox 18:49:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:49:42 INFO - mozversion application_vendor: Mozilla 18:49:42 INFO - mozversion application_version: 52.0a1 18:49:42 INFO - mozversion platform_buildid: 20160921153000 18:49:42 INFO - mozversion platform_changeset: 94d6bc5adab3d63533f5c5c33df14c7d3f763859 18:49:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:49:42 INFO - mozversion platform_version: 52.0a1 18:49:42 INFO - using testdate: 1474508982 18:49:42 INFO - actual date: 1474508982 18:49:42 INFO - starting webserver on 'localhost:49903' 18:49:42 INFO - SUITE-START | Running 2 tests 18:49:42 INFO - TEST-START | dromaeo_css 18:49:42 INFO - operating with platform_type : w7_ 18:49:42 INFO - Initialising browser for dromaeo_css test... 18:49:42 INFO - TEST-INFO | started process 2468 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpcbbw3m\profile http://localhost:49903/getInfo.html) 18:49:52 INFO - PROCESS | 2468 | __metrics Screen width/height:1600/1200 18:49:52 INFO - PROCESS | 2468 | colorDepth:24 18:49:52 INFO - PROCESS | 2468 | Browser inner width/height: 1008/673 18:49:52 INFO - PROCESS | 2468 | __metrics 18:49:53 INFO - PROCESS | 2468 | 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:49:53 INFO - PROCESS | 2468 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:54 INFO - TEST-INFO | 2468: exit 0 18:49:54 INFO - Browser initialized. 18:49:54 INFO - Running cycle 1/1 for dromaeo_css test... 18:49:54 INFO - TEST-INFO | started process 2328 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpcbbw3m\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:49:55 INFO - PROCESS | 2328 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 18:50:06 INFO - PROCESS | 2328 | RSS: Main: 124071936 18:50:06 INFO - PROCESS | 2328 | 18:50:57 INFO - PROCESS | 2328 | 1474509057122 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:53:07 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49903/tests/dromaeo/cssquery-ext.html) 18:53:09 INFO - PROCESS | 2328 | RSS: Main: 266854400 18:53:09 INFO - PROCESS | 2328 | 18:56:12 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-ext.html (next: http://localhost:49903/tests/dromaeo/cssquery-jquery.html) 18:56:12 INFO - PROCESS | 2328 | RSS: Main: 195502080 18:56:12 INFO - PROCESS | 2328 | 18:59:14 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49903/tests/dromaeo/cssquery-mootools.html) 18:59:15 INFO - PROCESS | 2328 | RSS: Main: 142221312 18:59:15 INFO - PROCESS | 2328 | 19:02:17 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49903/tests/dromaeo/cssquery-prototype.html) 19:02:17 INFO - PROCESS | 2328 | RSS: Main: 143343616 19:02:17 INFO - PROCESS | 2328 | 19:05:20 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49903/tests/dromaeo/cssquery-yui.html) 19:05:21 INFO - PROCESS | 2328 | RSS: Main: 134451200 19:05:21 INFO - PROCESS | 2328 | 19:08:23 INFO - PROCESS | 2328 | Cycle 1(1): loaded http://localhost:49903/tests/dromaeo/cssquery-yui.html (next: http://localhost:49903/tests/dromaeo/cssquery-dojo.html) 19:08:24 INFO - PROCESS | 2328 | RSS: Main: 180944896 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | __start_tp_report 19:08:24 INFO - PROCESS | 2328 | _x_x_mozilla_page_load 19:08:24 INFO - PROCESS | 2328 | _x_x_mozilla_page_load_details 19:08:24 INFO - PROCESS | 2328 | |i|pagename|runs| 19:08:24 INFO - PROCESS | 2328 | |0;dojo.html;2636.7495087966636;2664.467106578684;2764.654418197725;2786.763125134364;2800.1599520143964;4644.3033544968275;4681.765911704414;4690.671652984292;4772.141014617368;4801.90396192076;4596.287575425806;4627.791749371278;4789.544993225638;4846.442659094205;4960.206366981283;4151.584841515847;4263.616274535291;4265.658747300224;4286.314189729644;4288.249556327646;4062.4718786557705;4154.31453810121;4214.115035842477;4221.55673654265;4308.418363520916;4089.9591004090025;4134.565870583606;4229.048464095569;4265.189613973351;4322.8703138905885;4060.7563546187325;4159.292920203573;4187.120704652027;4199.496060472732;4272.572742725733;4277.743335399886;4284.250256205151;4295.763732994684;4302.376155457441;4303.182395344906;4763.833265835714;4771.141194584973;4884.926726099111;4887.29134275528;4889.804407823683;4966.379202599675;5009.649324547283;5027.4721154279005;5071;5091.592672586185;5037.093323201822;5061.392632884077;5120.487951204887;5120.820771272987;5122.641415100945;4668.603168730662;4687.250039993584;4713.29300604912;4745.501722319142;4754.904901901978;4940.6788558743565;5111.87220319495;5130.615203859651;5210.088234559013;5289.285946397187;4788.473267940523;4797.848516356119;4993.575546078624;5093.770780314895;5094.847154585368;4883.853484395474;4977.651564390458;4986.925196122061;5038.89144388234;5059.139946209153;4705.529447055267;4760.428748550196;4816.109019831342;4843.297721830315;4866.075445665312;4781.9043619128115;4823.782929768169;4836.274558816206;4951.282064100757;5164.096283150434;3203.6475987641343;3217.1957010747315;3324.5013248012992;3389.084947064328;3404.2340473393288;2860.184847318504;2938.676745557987;3028.712272334125;3030.8333041682918;3094.5977022986867;4662.207424737802;4677.789499472532;4855.635827312965;4883.438404583448;4978.4025916890205;4822.445418776815;4964.304997300381;5125.794968201305;5132.30713853625;5233.738313084331;4559.4072770539615;4641.094986477605;4646.279826626878;4821.2045012572535;4833.371661684029;3982.9601703983167;4170.082581831992;4198.6221240087925;4235.894102647397;4254.064105896698;5108.897822043538;5169.198774190007;5297.020051290524;5423;5431.158170483582;5119.232115182753;5129.846104616868;5205.817796377109;5226.216067589892;5227.18978558324;5193.42872284056;5232.3459567554055;5350.491703288181;5388.676679399248;5463.31708536433;4958.8760280993265;5031.320771695774;5075.771590278372;5078.5175408336145;5079.746012699276;5021.44764075944;5031.094403007493;5159.9164175522055;5189.299444575085;5193.454687257784;4848.878778030577;5015.724135172601;5025.497450254945;5030.9245361320345;5032.798688052437;4773;4896.6572339935865;4990.677470470256;4999.600031997359;5030.220315851049;4709.199436095941;4732.47942726293;4751.429828420612;4859.2954021667365;4889.315495830658;4850.393700787402;4989.80040798364;5010.874728131826;5030.346055012825;5103;4514.841980530666;4785.521447855187;4830.058138663067;5023.1711767557945;5024.874378140576 19:08:24 INFO - PROCESS | 2328 | |1;ext.html;498.8004798080767;506.46288514358906;515.633899931049;520.9531142197202;550.2736387967882;27891.721082789165;36707.81646091766;37089.25821483569;37941;37977.620223797756;36602.63397366025;37026.62973370272;37174.628253717456;37961.62038379615;38574.4213836792;7699.730509432171;8129.309008734264;8215.137410571893;8350.582470876463;8362.58187090646;6825.488088393366;6865.519413641047;7253.854922901565;10071.597136114546;10320.845187322197;10039.899601004006;10246.795064098713;10299.691009269734;10365.533550990223;10411.583536658562;9702.805943881154;9943.850842237372;10047.748806279827;10220.48897555123;10285.691429257171;7208.2431344708575;7350.889736653954;9449;9604;10121.69634910954;34583.308333833185;34653.30693386144;36056;36376;36387.90836274916;2708.756211940915;3058.388322335542;3115;3210.9357812843846;3407.522946787452;27798.861005694842;28017.719822801715;28347.732181425436;28728.712712872813;29807.552886706715;3446.5691788526433;3456.7061799747053;3470.0804286864;3493.510908472816;3562.679358857715;314125;315087.42456287803;316448;318044;318155.9999999977;48003;48171.27743083856;48214.5178548217;48773;49287.75356123268;46994.120235190894;47392.5260747395;48377.75811120992;50151.24773128406;50887;11955.282683039046;11979.52081916731;12014.579489717993;12364.62906112818;12713.872861271453;10262.640807571848;10437.895621043845;10512.264141510173;10523.368597884151;10649.893501065046;12875.291858947849;13793.379297931617;13842;13987.650308742159;14971.251437428085;5666.660000399989;5985.311689155716;6172.8765424691255;6178.876422471525;6225.128482012522;718.1884470548351;772;793.8571057209758;812.2892469089546;912.3522299167665;24896.626550601755;26133.346666333495;26406.867965660047;26947.461050779268;27082.458350832872;840.5125027484045;848.9957550212333;877.9692710755093;917.2891009467583;986.713852982627;13656.795148072786;13831.585052448443;13854;13992.720145597032;14116.85883141176;459344;461265;465907;467996;473210;43455;44818;45327.54672453299;45914.31128533075;45917.7704111484;913.9405938613969;974.5906719177821;1058.25921854702;1070.8661417322678;1082.4317233452343;25270.84114485689;25965.870170649025;27149.1855244343;27335.179944601692;27866.582001270403;39536.60463395329;40506;41169.58830411657;41277;41603.1679366417;350083;381081;382235;383177;390224;21169.259075932885;21703.34889953304;21871.78128218761;21935.451613709785;21939.451513712283;37934.24131517299;39899;40357.596424035386;40805.38791918183;40934.79532602318;33082.33835323232;34125.82937085299;34362.31275374528;34847.47728784121;35401.114972125906;32747.508787368686;33380;33899.15252118717;34086.829565852015;35778.64221357753;32775.18062048468;32826.671733283314;32877.83561082275;33356.499652504746;33408.83295583507;29760.255993600334;30335.393292134475;31652.525212121374;31742.206444839063;31751;22956;23321.533569328178;23431.024568222034;23657;23902.282931512083 19:08:24 INFO - PROCESS | 2328 | |2;jquery.html;263.06480461957733;279.74543165719194;280.5931399470768;283.981541199822;284.77787325885805;13279.867201327996;17460.650786984268;17616.647667046684;17655;17894.836835605678;15573.53279401617;16397.344106235767;16545.25546350414;16572.50282491527;16829.242684079214;4877.561019508253;4966;5024.397072351323;5253.50091741284;5303.65526240795;3919.510061242345;5324;5488.643238189505;5601.579881508883;5930.169776231331;5763.567732420064;5779.248697669318;5788.855278618026;6065.969670151643;6066.908996365058;5656.575756818255;5684.715764211794;5713.571482138815;5873.471387575096;6039.9396006039815;4664.416947881515;4685.180093483661;4715.905681886377;4760.714357138583;4972.825951091694;20230.291939782037;20420.468464865047;21936.45158871041;22024.229151979765;22028.229011984662;1723.672502224586;1743.317366161175;1770.0707128757376;1827.4791684370023;2226.365485836545;8524.659013639446;8726.738197854074;11763.58827441044;13653.249071301074;13785.655358616015;1711.3667942861096;1758.9824101759075;1778.6976214043643;1796.9460916172536;1824.4435447188603;83154;85084.57457712734;86329.13670863274;88374;88732.11267887303;8693.913260842395;8834.558272086499;9157.862632060524;9244.953775231215;9250.95374523123;10281.228907831943;10625.46872656364;10771.89228107709;11658.53365865356;11713.472893719803;6647;6656.434203092791;6959.338862808127;6968.581885086911;7122.643867806589;6755.4257888080065;6786.524943254023;6886.758963436256;7068.257832927468;7174.964125179341;3523.418635925095;3669.5779985301497;3714.3685573452576;3766.6045065267754;3775.169462718198;1519.3163076615567;1565.9295331709873;1577.5819407856927;1595.5133684226191;1598.2248609424653;291.3822695884738;723.4104205072849;755.3994574313434;777.8366543026028;897.9551022448982;9325.720228393158;9940.701778946643;17522.649547009245;18699.626007480045;19118.330858420166;742.9925700743031;801.8717005279127;820.5240960243045;864.5893200729729;924.3298608508911;9068.8639670405;9453.291003174789;9651.613935442503;9737.805243895082;9882.901170988342;425626;429034;430823;451754;452898;95609;97109.51445242729;99469.50265248772;102413.48793256134;102943;815.8816971539094;828.3869936247133;831.7920519870032;836.6695155413578;851.0765819086237;16059.919700401424;16085.839141608902;16329.510114696102;16437.58906027359;16547.83452165463;42356.57643423526;43858.342124867515;44242.55757442513;44546;46335.3049704248;56665.43334566602;61461.69269153626;63128.68435657792;64408;64813;5928.733207005609;6046.788362407295;6181.289151747606;6276.811695649138;7025.086738723933;8812.691555795516;11357.37534435614;11788.823167652321;12074.094442916992;12095.516179353079;9702.369345992487;9928.702138935843;10124.746881328027;10184.847227291448;10312.381257124596;9414.529273536433;9768.365056271605;9938.204943604638;10390.740231494274;10570.365778053341;10006.399616023062;10154.492275386054;10158;10170.186385089028;10337.586496540054;5847.093700476433;5910.7340169693325;6007.549433792396;6156.415140561639;6376.649284289409;6615.37153970372;9652.565634546321;9873.358231714916;9965.202783777426;10120.987901210116 19:08:24 INFO - PROCESS | 2328 | |3;mootools.html;1056.7727938493222;1072.3726619927343;1099.6536091131295;1198.5685153344793;1321.0950498908248;6358.8728225435525;7033.613151276684;7183;7193.280671932804;7256.4920455568135;6917.204521480031;6917.342852429024;6940.652967351637;6957.443404527638;6985.580865148095;1879.0980329441809;1937.3897222374978;1950.19067087159;1966.1349006437122;1968.271739456403;1815.3192552792636;1927.1231589626686;1930.2086144680684;1960.3530834824473;1960.4510736993593;1794.0491539484094;1825.3155066849931;1840.2730921286152;1897.4497395755213;1919.4913347962802;1743.7471566622833;1782.1891039577026;1872.1107473949764;1899.8955057471833;1899.9525011874675;1793.1303317890813;1793.4619614115843;1860.9627807443776;1874.240932422371;1891.6027634196835;6518.706658200345;6536.836579085513;6802.2857599951785;6907.378335949788;7001.704684633308;4026.2350153470748;4351.216780979423;4388.648908087377;4398.626116780078;4399.1641588098155;2813.08574713219;2871.6410448693914;2934.398448318111;2947.1747910585073;2973.6580293266334;879.1120967822482;884.494557143897;928.6331898899897;933.7012156110047;941.4163218804316;8649.65401383944;8652.178043085896;8856.247218986395;9089.81820363589;9282.350235483518;3363.1592101974506;3577.8926632201074;3646.1249300168297;3652.5982141964364;3657.085728567858;7049.68276427562;7114.786556403316;7114.786556403316;7159.713611455483;7375.262473752582;3909.3940439231965;4145.813438395279;4148.709590328648;4210.7684077375425;4242.66058715302;3464.7921124732597;3511.2450823073164;3996.2207369562657;4043.3126368517424;4045.069633984141;2938.5592161175996;2966.1398194523777;3042.6500952390315;3050.3289276359164;3066.8926587569326;887.7691800132012;908.56842999576;918.9081091890891;925.6852670092067;928.5264515097349;721.913370395556;743.5947408662292;747.3274053352027;754.7094368667993;765.9272369124924;4524.47968483622;4531.45622525299;4555.567221113989;4573.519780423074;4584.472785629695;759.9924000760033;791.0941971442666;793.476305638276;803.8713805791162;810.8216192437654;3118.8596513156963;3137.4195773781466;3174.412733644283;3221.130294820432;3257.2345498807763;7980.6408711606955;8291.378146638906;8343.374246931504;8595.5702214889;8663.610137543825;8191.83616327682;8445.282151017233;8490.27832634233;8647.135286471303;8650.480971141751;6329.968350158219;6466.385693359123;6675.232348279913;6764.255931847493;6770.458363330922;4529.162105010583;4614.077184563033;4679.766011699469;4747.62019038483;4763.118823017753;7303.977443158068;7540.585267810323;7662;7860.174681658572;8037.437379383387;7912.28789408956;7964.20357964199;8234;8259.38054645916;8260.215279548433;5597.412271711574;5658.264425624642;5845.41545845412;5872.442117998783;5929.970350148222;6184.257889053342;6421.935780642134;6434.0670602763275;6784.491163162881;6800.251972283144;6428.871422571616;6437.774677886251;6450.161479007887;6497.992811114284;6504.414602685781;6012.488938440281;6183.103449999635;6215.036669316263;6297.716602752978;6334.841628959313;5460.2355670207;5647.463490968352;5678.68767217807;5697.401772813795;5698.0598201296325;3955.624215699504;4022.8994275143355;4082.0611259411094;4127.463429754112;4128.504579450485;4129.525104612888;4172.31156859127;4217.367394890668;4218.029853133735;4238.19474299882 19:08:24 INFO - PROCESS | 2328 | |4;prototype.html;230.35845171198216;232.12835801565132;232.47692691444252;236.55881780479405;246.98518088914673;5021.6233782466325;5069.619778516612;5142.562882155012;5269.8155564555245;5306.92039619405;4743.5256474352545;4833.75831208439;5008.647665130413;5062.797683007885;5076.340075790152;3620.4026335654585;3743.1390780120587;3785.0726571989903;3802.163524024717;3806.6383693549205;3586.892393228194;3804.3152232598122;3814.694824414054;3814.7901865397394;3820.2741479119018;3461.4807778833124;3483.7735547189477;3574.910627234327;3654.5614526256886;3687.2809802088605;3548.6628770266784;3635.2002559436883;3696.6488183622514;3774;3790.639889210521;3058.663547009827;3223.3714425687;3249.2851572653976;3251.235959549504;3252.983735081333;4802.615790736732;4849.636277279218;4939.950600493985;4966.428860681031;5097.872553186163;3916.3146449371247;4020.457238272825;4158.6465150462245;4163.126593033147;4239.321708526621;5116.365071987929;5279.841604751864;5391.164369522691;5395.705030792621;5396.352437707461;3307.255867429833;3307.68576985186;3345.7323414126813;3429.7084747796466;3498;5389.137737961907;5417.918731218995;5511.539442047861;5639.887202255973;5645.435456454363;3367.8821241256437;3562.8218589070443;3564.536610240652;3565.910852228715;3573.3032058748813;3442.277121804399;3544.9822750886083;3563.3051554947056;3597.9100522486624;3615.385384484644;4249.362595610622;4267.16790225903;4267.701260911768;4279.764612946318;4286.957130428718;5302.814401496006;5394.865128371822;5414.07960646691;5443.101888188405;5460.399356070829;1177.087756988341;1191.820098102872;1246.2522486508203;1252.2861968677767;1255.5982085732478;737.4211244173298;739.3050532499433;762.7063580521428;771.3520642660193;781.6170076662395;5022.748862556858;5231.633785634969;5302.859885124717;5336.573074154058;5336.626436149432;5351.1973204019705;5460.808871689472;5510.81517473745;5564.081926482085;5579.414161513064;5098.719570423588;5147.6654017488745;5275.604329675213;5283.5773138149625;5342.519173274424;2805.8035937484588;2840.616516770251;2928.6192794936524;2949.734523892817;3007.8947236846607;5670.7731690732735;5727.7995270166175;5766.5675074368755;5818.709064546756;5927.73325200367;5443.537299329522;5523.944760552424;5537.861553461195;5646.520045796071;5695.40198279183;5717.914231286534;5735.942640573624;5849.181114644039;5903.911441328383;5956.195913551702;5374.704391258362;5395.676259424447;5400.351957765093;5505.366882808528;5558.555315574825;5199.4540573239265;5258.316418865523;5314.176302673091;5381.13901775714;5456.48163424474;5622.212890195294;5692.516136128475;5719.628224165416;5835.358110607915;5953.903969935695;3374.342003309331;3439.4324936385215;3456.1186897342022;3473.7915725056578;3492.602958816494;5359.490848369399;5374.892502150014;5412.377576578744;5451.6370907273185;5544.7782088716;5244.318238628954;5268;5297.231901374356;5301.310829592128;5455.727213639223;5108.489151084862;5328.920066199088;5460.972695136499;5473.28847249855;5477.123660214346;5067.239914012927;5098.719570423662;5183.274341592105;5326.547243484192;5355.785768569213;5244.082285599929;5379.865503362447;5413.160960051198;5492.450754924475;5499.367572729027;5064.4935506449065;5069.949300507095;5101.6683915546855;5143.537081662519;5319.6808191508635 19:08:24 INFO - PROCESS | 2328 | |5;yui.html;589.3163929841385;640.8077576726981;655.2366493035612;672.2504407585541;675.4191395399955;14355.28223588819;14766.557003289905;15880.364785408585;15904.363825446997;15921.442749503754;12918.806217906738;13050.629683883175;13828.10117342372;13877.930610346959;13941.372638231278;478.78454695387217;482.1393812045499;565.1099518258738;565.3103214078817;575.9654420734748;540.1654443884189;550.746656537993;552.5607142321844;574.4772257245907;582.301238513783;617.0959544267647;617.6016469377249;618.3538202578294;619.938006199381;620.011082323693;494.8243373602355;614.3089024847047;616.4759954039076;616.5868867858545;616.7009000634711;369.9704023678099;414.94605701258945;426.91248294099944;427.1094767409954;440.911817636474;12586.559470418582;12966.675833104153;13231.338433078405;13353;13721;12758.298293593849;12872.678183045498;12902.161359511601;13028.348582570929;13418.26199559024;12958.28729419891;13022.869771302261;13176.868231317756;13312.067414948744;13698.863011369858;4092.46797916269;4326.220986159094;4345.652347812167;4355.303151495746;4355.956440435587;73212;75205.62397187979;85213.57393213056;85931.85170370729;88377;10042.397456152508;10254.948725256476;10315.587376505006;10528.842067368996;10664.626738064131;17289.913550432168;17937.461876143738;18068.18693158811;18103.909480452512;18158.5460363492;7527.435442341846;7556.8866467001935;7641.808954776064;7780.8832867507035;7793.298603125632;3948.368261078214;4036.125755719647;4078.163976384834;4203.390508376268;4265.658747300271;2917.2560996946067;2951.896683616063;2969.9109026729234;3011.6988301170145;3027.9243018924703;522.154110341242;542.354598028345;542.3654324440414;549.4231057389725;554.1825806934739;495.7323045555432;553.9612227144062;633.6198281031344;648.7080813633884;703.0754557756534;9442.858357124649;9729.902700973042;9932.80134397308;10109.747256318651;10214.948925255327;649.3797974054669;650.9837254068591;659.2319947261483;673.276228054843;681.5331497923839;4649.023705021916;4680.695754775928;4738.573528382462;4877.487863774323;5016.498350164954;115917.42041289908;115973;116011.41994289974;117563;117605;105135;105310;107728.46135769271;109698;109989.45005274922;94157;94842;96423.0357696414;96768;97052;9109.453432794053;9204.631814727336;9298.488583127722;9378.202852757591;9428.481433521221;52660;55351.44648553624;55665.44334556765;55875;56193;75869;77892.61053694696;78396.60801695955;79296;81157;10475.109615682752;11129;11179.876421920779;11201;11478.426078696199;12630.494780208688;12972.35138243103;13045.152065115737;13059.085863989429;13116.934415327862;11431.314121152758;11989.64031079069;12313.445894934928;12391.504339826666;12652.87347126517;11855.229410088317;12417.44121514516;12439.875601243872;12810.615681529569;12911.483540658268;12233.388330583613;12650.810237846255;12720.491180352683;12972.870271297166;13002.934985325013;9843.212542996685;10537.051665350155;11511;11672.941635291769;12164.289891583152;11174.66476005721;11349.205555611028;11733.35466549348;11946.820797688215;11970.820437693268 19:08:24 INFO - PROCESS | 2328 | __end_tp_report 19:08:24 INFO - PROCESS | 2328 | __start_cc_report 19:08:24 INFO - PROCESS | 2328 | _x_x_mozilla_cycle_collect,1682 19:08:24 INFO - PROCESS | 2328 | __end_cc_report 19:08:24 INFO - PROCESS | 2328 | __startTimestamp1474510104150__endTimestamp 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | ------- Summary: start ------- 19:08:24 INFO - PROCESS | 2328 | Number of tests: 6 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#0] dojo.html Cycles:165 Average:4646.73 Median:4836.27 stddev:622.03 (12.9%) stddev-sans-first:603.62 19:08:24 INFO - PROCESS | 2328 | Values: 2636.7 2664.5 2764.7 2786.8 2800.2 4644.3 4681.8 4690.7 4772.1 4801.9 4596.3 4627.8 4789.5 4846.4 4960.2 4151.6 4263.6 4265.7 4286.3 4288.2 4062.5 4154.3 4214.1 4221.6 4308.4 4090.0 4134.6 4229.0 4265.2 4322.9 4060.8 4159.3 4187.1 4199.5 4272.6 4277.7 4284.3 4295.8 4302.4 4303.2 4763.8 4771.1 4884.9 4887.3 4889.8 4966.4 5009.6 5027.5 5071.0 5091.6 5037.1 5061.4 5120.5 5120.8 5122.6 4668.6 4687.3 4713.3 4745.5 4754.9 4940.7 5111.9 5130.6 5210.1 5289.3 4788.5 4797.8 4993.6 5093.8 5094.8 4883.9 4977.7 4986.9 5038.9 5059.1 4705.5 4760.4 4816.1 4843.3 4866.1 4781.9 4823.8 4836.3 4951.3 5164.1 3203.6 3217.2 3324.5 3389.1 3404.2 2860.2 2938.7 3028.7 3030.8 3094.6 4662.2 4677.8 4855.6 4883.4 4978.4 4822.4 4964.3 5125.8 5132.3 5233.7 4559.4 4641.1 4646.3 4821.2 4833.4 3983.0 4170.1 4198.6 4235.9 4254.1 5108.9 5169.2 5297.0 5423.0 5431.2 5119.2 5129.8 5205.8 5226.2 5227.2 5193.4 5232.3 5350.5 5388.7 5463.3 4958.9 5031.3 5075.8 5078.5 5079.7 5021.4 5031.1 5159.9 5189.3 5193.5 4848.9 5015.7 5025.5 5030.9 5032.8 4773.0 4896.7 4990.7 4999.6 5030.2 4709.2 4732.5 4751.4 4859.3 4889.3 4850.4 4989.8 5010.9 5030.3 5103.0 4514.8 4785.5 4830.1 5023.2 5024.9 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#1] ext.html Cycles:180 Average:51722.19 Median:19061.69 stddev:103821.88 (544.7%) stddev-sans-first:104041.90 19:08:24 INFO - PROCESS | 2328 | Values: 498.8 506.5 515.6 521.0 550.3 27891.7 36707.8 37089.3 37941.0 37977.6 36602.6 37026.6 37174.6 37961.6 38574.4 7699.7 8129.3 8215.1 8350.6 8362.6 6825.5 6865.5 7253.9 10071.6 10320.8 10039.9 10246.8 10299.7 10365.5 10411.6 9702.8 9943.9 10047.7 10220.5 10285.7 7208.2 7350.9 9449.0 9604.0 10121.7 34583.3 34653.3 36056.0 36376.0 36387.9 2708.8 3058.4 3115.0 3210.9 3407.5 27798.9 28017.7 28347.7 28728.7 29807.6 3446.6 3456.7 3470.1 3493.5 3562.7 314125.0 315087.4 316448.0 318044.0 318156.0 48003.0 48171.3 48214.5 48773.0 49287.8 46994.1 47392.5 48377.8 50151.2 50887.0 11955.3 11979.5 12014.6 12364.6 12713.9 10262.6 10437.9 10512.3 10523.4 10649.9 12875.3 13793.4 13842.0 13987.7 14971.3 5666.7 5985.3 6172.9 6178.9 6225.1 718.2 772.0 793.9 812.3 912.4 24896.6 26133.3 26406.9 26947.5 27082.5 840.5 849.0 878.0 917.3 986.7 13656.8 13831.6 13854.0 13992.7 14116.9 459344.0 461265.0 465907.0 467996.0 473210.0 43455.0 44818.0 45327.5 45914.3 45917.8 913.9 974.6 1058.3 1070.9 1082.4 25270.8 25965.9 27149.2 27335.2 27866.6 39536.6 40506.0 41169.6 41277.0 41603.2 350083.0 381081.0 382235.0 383177.0 390224.0 21169.3 21703.3 21871.8 21935.5 21939.5 37934.2 39899.0 40357.6 40805.4 40934.8 33082.3 34125.8 34362.3 34847.5 35401.1 32747.5 33380.0 33899.2 34086.8 35778.6 32775.2 32826.7 32877.8 33356.5 33408.8 29760.3 30335.4 31652.5 31742.2 31751.0 22956.0 23321.5 23431.0 23657.0 23902.3 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#2] jquery.html Cycles:180 Average:27000.15 Median:4969.41 stddev:73187.84 (1472.8%) stddev-sans-first:73365.62 19:08:24 INFO - PROCESS | 2328 | Values: 263.1 279.7 280.6 284.0 284.8 13279.9 17460.7 17616.6 17655.0 17894.8 15573.5 16397.3 16545.3 16572.5 16829.2 4877.6 4966.0 5024.4 5253.5 5303.7 3919.5 5324.0 5488.6 5601.6 5930.2 5763.6 5779.2 5788.9 6066.0 6066.9 5656.6 5684.7 5713.6 5873.5 6039.9 4664.4 4685.2 4715.9 4760.7 4972.8 20230.3 20420.5 21936.5 22024.2 22028.2 1723.7 1743.3 1770.1 1827.5 2226.4 8524.7 8726.7 11763.6 13653.2 13785.7 1711.4 1759.0 1778.7 1796.9 1824.4 83154.0 85084.6 86329.1 88374.0 88732.1 8693.9 8834.6 9157.9 9245.0 9251.0 10281.2 10625.5 10771.9 11658.5 11713.5 6647.0 6656.4 6959.3 6968.6 7122.6 6755.4 6786.5 6886.8 7068.3 7175.0 3523.4 3669.6 3714.4 3766.6 3775.2 1519.3 1565.9 1577.6 1595.5 1598.2 291.4 723.4 755.4 777.8 898.0 9325.7 9940.7 17522.6 18699.6 19118.3 743.0 801.9 820.5 864.6 924.3 9068.9 9453.3 9651.6 9737.8 9882.9 425626.0 429034.0 430823.0 451754.0 452898.0 95609.0 97109.5 99469.5 102413.5 102943.0 815.9 828.4 831.8 836.7 851.1 16059.9 16085.8 16329.5 16437.6 16547.8 42356.6 43858.3 44242.6 44546.0 46335.3 56665.4 61461.7 63128.7 64408.0 64813.0 5928.7 6046.8 6181.3 6276.8 7025.1 8812.7 11357.4 11788.8 12074.1 12095.5 9702.4 9928.7 10124.7 10184.8 10312.4 9414.5 9768.4 9938.2 10390.7 10570.4 10006.4 10154.5 10158.0 10170.2 10337.6 5847.1 5910.7 6007.5 6156.4 6376.6 6615.4 9652.6 9873.4 9965.2 10121.0 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#3] mootools.html Cycles:180 Average:4527.31 Median:5697.73 stddev:2503.05 (43.9%) stddev-sans-first:2496.48 19:08:24 INFO - PROCESS | 2328 | Values: 1056.8 1072.4 1099.7 1198.6 1321.1 6358.9 7033.6 7183.0 7193.3 7256.5 6917.2 6917.3 6940.7 6957.4 6985.6 1879.1 1937.4 1950.2 1966.1 1968.3 1815.3 1927.1 1930.2 1960.4 1960.5 1794.0 1825.3 1840.3 1897.4 1919.5 1743.7 1782.2 1872.1 1899.9 1900.0 1793.1 1793.5 1861.0 1874.2 1891.6 6518.7 6536.8 6802.3 6907.4 7001.7 4026.2 4351.2 4388.6 4398.6 4399.2 2813.1 2871.6 2934.4 2947.2 2973.7 879.1 884.5 928.6 933.7 941.4 8649.7 8652.2 8856.2 9089.8 9282.4 3363.2 3577.9 3646.1 3652.6 3657.1 7049.7 7114.8 7114.8 7159.7 7375.3 3909.4 4145.8 4148.7 4210.8 4242.7 3464.8 3511.2 3996.2 4043.3 4045.1 2938.6 2966.1 3042.7 3050.3 3066.9 887.8 908.6 918.9 925.7 928.5 721.9 743.6 747.3 754.7 765.9 4524.5 4531.5 4555.6 4573.5 4584.5 760.0 791.1 793.5 803.9 810.8 3118.9 3137.4 3174.4 3221.1 3257.2 7980.6 8291.4 8343.4 8595.6 8663.6 8191.8 8445.3 8490.3 8647.1 8650.5 6330.0 6466.4 6675.2 6764.3 6770.5 4529.2 4614.1 4679.8 4747.6 4763.1 7304.0 7540.6 7662.0 7860.2 8037.4 7912.3 7964.2 8234.0 8259.4 8260.2 5597.4 5658.3 5845.4 5872.4 5930.0 6184.3 6421.9 6434.1 6784.5 6800.3 6428.9 6437.8 6450.2 6498.0 6504.4 6012.5 6183.1 6215.0 6297.7 6334.8 5460.2 5647.5 5678.7 5697.4 5698.1 3955.6 4022.9 4082.1 4127.5 4128.5 4129.5 4172.3 4217.4 4218.0 4238.2 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#4] prototype.html Cycles:180 Average:4394.91 Median:5129.46 stddev:1410.36 (27.5%) stddev-sans-first:1379.24 19:08:24 INFO - PROCESS | 2328 | Values: 230.4 232.1 232.5 236.6 247.0 5021.6 5069.6 5142.6 5269.8 5306.9 4743.5 4833.8 5008.6 5062.8 5076.3 3620.4 3743.1 3785.1 3802.2 3806.6 3586.9 3804.3 3814.7 3814.8 3820.3 3461.5 3483.8 3574.9 3654.6 3687.3 3548.7 3635.2 3696.6 3774.0 3790.6 3058.7 3223.4 3249.3 3251.2 3253.0 4802.6 4849.6 4940.0 4966.4 5097.9 3916.3 4020.5 4158.6 4163.1 4239.3 5116.4 5279.8 5391.2 5395.7 5396.4 3307.3 3307.7 3345.7 3429.7 3498.0 5389.1 5417.9 5511.5 5639.9 5645.4 3367.9 3562.8 3564.5 3565.9 3573.3 3442.3 3545.0 3563.3 3597.9 3615.4 4249.4 4267.2 4267.7 4279.8 4287.0 5302.8 5394.9 5414.1 5443.1 5460.4 1177.1 1191.8 1246.3 1252.3 1255.6 737.4 739.3 762.7 771.4 781.6 5022.7 5231.6 5302.9 5336.6 5336.6 5351.2 5460.8 5510.8 5564.1 5579.4 5098.7 5147.7 5275.6 5283.6 5342.5 2805.8 2840.6 2928.6 2949.7 3007.9 5670.8 5727.8 5766.6 5818.7 5927.7 5443.5 5523.9 5537.9 5646.5 5695.4 5717.9 5735.9 5849.2 5903.9 5956.2 5374.7 5395.7 5400.4 5505.4 5558.6 5199.5 5258.3 5314.2 5381.1 5456.5 5622.2 5692.5 5719.6 5835.4 5953.9 3374.3 3439.4 3456.1 3473.8 3492.6 5359.5 5374.9 5412.4 5451.6 5544.8 5244.3 5268.0 5297.2 5301.3 5455.7 5108.5 5328.9 5461.0 5473.3 5477.1 5067.2 5098.7 5183.3 5326.5 5355.8 5244.1 5379.9 5413.2 5492.5 5499.4 5064.5 5069.9 5101.7 5143.5 5319.7 19:08:24 INFO - PROCESS | 2328 | 19:08:24 INFO - PROCESS | 2328 | [#5] yui.html Cycles:180 Average:21228.67 Median:2253.71 stddev:32036.29 (1421.5%) stddev-sans-first:32088.67 19:08:24 INFO - PROCESS | 2328 | Values: 589.3 640.8 655.2 672.3 675.4 14355.3 14766.6 15880.4 15904.4 15921.4 12918.8 13050.6 13828.1 13877.9 13941.4 478.8 482.1 565.1 565.3 576.0 540.2 550.7 552.6 574.5 582.3 617.1 617.6 618.4 619.9 620.0 494.8 614.3 616.5 616.6 616.7 370.0 414.9 426.9 427.1 440.9 12586.6 12966.7 13231.3 13353.0 13721.0 12758.3 12872.7 12902.2 13028.3 13418.3 12958.3 13022.9 13176.9 13312.1 13698.9 4092.5 4326.2 4345.7 4355.3 4356.0 73212.0 75205.6 85213.6 85931.9 88377.0 10042.4 10254.9 10315.6 10528.8 10664.6 17289.9 17937.5 18068.2 18103.9 18158.5 7527.4 7556.9 7641.8 7780.9 7793.3 3948.4 4036.1 4078.2 4203.4 4265.7 2917.3 2951.9 2969.9 3011.7 3027.9 522.2 542.4 542.4 549.4 554.2 495.7 554.0 633.6 648.7 703.1 9442.9 9729.9 9932.8 10109.7 10214.9 649.4 651.0 659.2 673.3 681.5 4649.0 4680.7 4738.6 4877.5 5016.5 115917.4 115973.0 116011.4 117563.0 117605.0 105135.0 105310.0 107728.5 109698.0 109989.5 94157.0 94842.0 96423.0 96768.0 97052.0 9109.5 9204.6 9298.5 9378.2 9428.5 52660.0 55351.4 55665.4 55875.0 56193.0 75869.0 77892.6 78396.6 79296.0 81157.0 10475.1 11129.0 11179.9 11201.0 11478.4 12630.5 12972.4 13045.2 13059.1 13116.9 11431.3 11989.6 12313.4 12391.5 12652.9 11855.2 12417.4 12439.9 12810.6 12911.5 12233.4 12650.8 12720.5 12972.9 13002.9 9843.2 10537.1 11511.0 11672.9 12164.3 11174.7 11349.2 11733.4 11946.8 11970.8 19:08:24 INFO - PROCESS | 2328 | -------- Summary: end -------- 19:08:24 INFO - PROCESS | 2328 | 19:08:25 INFO - TEST-INFO | 2328: exit 0 19:08:25 INFO - TEST-OK | dromaeo_css | took 1123311ms 19:08:25 INFO - TEST-START | kraken 19:08:25 INFO - operating with platform_type : w7_ 19:08:25 INFO - Initialising browser for kraken test... 19:08:25 INFO - TEST-INFO | started process 4084 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp1p2zft\profile http://localhost:49903/getInfo.html) 19:08:31 INFO - PROCESS | 4084 | __metrics Screen width/height:1600/1200 19:08:31 INFO - PROCESS | 4084 | colorDepth:24 19:08:31 INFO - PROCESS | 4084 | Browser inner width/height: 1008/673 19:08:31 INFO - PROCESS | 4084 | __metrics 19:08:33 INFO - PROCESS | 4084 | 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" 19:08:33 INFO - PROCESS | 4084 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:33 INFO - PROCESS | 4084 | console.error: 19:08:33 INFO - PROCESS | 4084 | Message: Error: Connection closed before committing the transaction. 19:08:33 INFO - PROCESS | 4084 | Stack: 19:08:33 INFO - PROCESS | 4084 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 19:08:33 INFO - PROCESS | 4084 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 19:08:33 INFO - PROCESS | 4084 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 19:08:33 INFO - PROCESS | 4084 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 19:08:33 INFO - PROCESS | 4084 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 19:08:33 INFO - PROCESS | 4084 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 19:08:33 INFO - PROCESS | 4084 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 19:08:33 INFO - PROCESS | 4084 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 19:08:33 INFO - PROCESS | 4084 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 19:08:33 INFO - PROCESS | 4084 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 19:08:33 INFO - PROCESS | 4084 | Barrier/this.client.addBlocker/promise12.9% 19:09:31 INFO - TinderboxPrint: I/O read bytes / time
47,983,616 / 998,450 19:09:31 INFO - TinderboxPrint: I/O write bytes / time
376,115,200 / 3,639,000 19:09:31 INFO - TinderboxPrint: CPU idle
8,320.7 (87.1%) 19:09:31 INFO - TinderboxPrint: CPU user
1,205.9 (12.6%) 19:09:31 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 34195456; Read time: 0; Write time: 95130 19:09:31 INFO - run-tests - Wall time: 1192s; CPU: 13.0%; Read bytes: 47983616; Write bytes: 305108992; Read time: 998450; Write time: 3496320 19:09:32 INFO - Running post-run listener: _upload_blobber_files 19:09:32 INFO - Blob upload gear active. 19:09:32 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 19:09:32 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 19:09:32 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'] 19:09:32 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 19:09:32 INFO - (blobuploader) - INFO - Open directory for files ... 19:09:32 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 19:09:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:09:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:33 INFO - (blobuploader) - INFO - Done attempting. 19:09:33 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 19:09:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:09:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:34 INFO - (blobuploader) - INFO - Done attempting. 19:09:34 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 19:09:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:09:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:09:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:09:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:09:42 INFO - (blobuploader) - INFO - Done attempting. 19:09:42 INFO - (blobuploader) - INFO - Iteration through files over. 19:09:42 INFO - Return code: 0 19:09:42 INFO - rmtree: C:\slave\test\build\uploaded_files.json 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 19:09:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/760d3c697929832b1ac9265e051d0bb48d8f940f15cc6cec1fd06bc10938103e97a06eb7efac5d0ea790d2dd7de82008a7be881f33ed72c9c4b37580348cee46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0b269f608f59d9568e0c106e059ac8ecba6bcc15b436e8f5e0b89a8d951b6aa6fe34efbf308d3ae2e90c148018e068e4b9e95b7a974cf22a9521625de57186b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15d7c82203cd3927a42dc01f04320e8825d53367e8cf698e4e70c600b97c4b0daf4e025e30c226716163ffff5357bfb6c4056c35c2366b2446d30715b7784db5"} 19:09:42 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 19:09:42 INFO - Writing to file C:\slave\test\properties\blobber_files 19:09:42 INFO - Contents: 19:09:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/760d3c697929832b1ac9265e051d0bb48d8f940f15cc6cec1fd06bc10938103e97a06eb7efac5d0ea790d2dd7de82008a7be881f33ed72c9c4b37580348cee46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0b269f608f59d9568e0c106e059ac8ecba6bcc15b436e8f5e0b89a8d951b6aa6fe34efbf308d3ae2e90c148018e068e4b9e95b7a974cf22a9521625de57186b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15d7c82203cd3927a42dc01f04320e8825d53367e8cf698e4e70c600b97c4b0daf4e025e30c226716163ffff5357bfb6c4056c35c2366b2446d30715b7784db5"} 19:09:42 INFO - Running post-run listener: copy_logs_to_upload_dir 19:09:42 INFO - Copying logs to upload dir... 19:09:42 INFO - mkdir: C:\slave\test\build\upload\logs 19:09:42 INFO - Copying logs to upload dir... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... 19:09:42 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1398.105000 ========= master_lag: 4.11 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 22 secs) (at 2016-09-21 19:09:46.881938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-09-21 19:09:46.894706) ========= '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-095 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-095 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-095 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/760d3c697929832b1ac9265e051d0bb48d8f940f15cc6cec1fd06bc10938103e97a06eb7efac5d0ea790d2dd7de82008a7be881f33ed72c9c4b37580348cee46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0b269f608f59d9568e0c106e059ac8ecba6bcc15b436e8f5e0b89a8d951b6aa6fe34efbf308d3ae2e90c148018e068e4b9e95b7a974cf22a9521625de57186b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15d7c82203cd3927a42dc01f04320e8825d53367e8cf698e4e70c600b97c4b0daf4e025e30c226716163ffff5357bfb6c4056c35c2366b2446d30715b7784db5"} build_url:https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/IDby6EF-Sl6_oaWqPaPQhQ/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/760d3c697929832b1ac9265e051d0bb48d8f940f15cc6cec1fd06bc10938103e97a06eb7efac5d0ea790d2dd7de82008a7be881f33ed72c9c4b37580348cee46", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c0b269f608f59d9568e0c106e059ac8ecba6bcc15b436e8f5e0b89a8d951b6aa6fe34efbf308d3ae2e90c148018e068e4b9e95b7a974cf22a9521625de57186b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15d7c82203cd3927a42dc01f04320e8825d53367e8cf698e4e70c600b97c4b0daf4e025e30c226716163ffff5357bfb6c4056c35c2366b2446d30715b7784db5"}' ========= master_lag: 8.54 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-09-21 19:09:55.537702) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 19:09:55.538079) ========= '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-095 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-095 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-095 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.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 19:09:55.816582) ========= ========= Started reboot slave lost (results: 0, elapsed: 23 secs) (at 2016-09-21 19:09:55.823058) ========= ========= Finished reboot slave lost (results: 0, elapsed: 23 secs) (at 2016-09-21 19:10:19.806562) ========= ========= Total master_lag: 12.97 =========