builder: fx-team_win7_ix_test-dromaeojs slave: t-w732-ix-148 starttime: 1474963296.63 results: success (0) buildid: 20160926225151 builduid: 4e32834389aa4042ae41d5fcb7e223f1 revision: 4b5a9f8b52d493e4b867b666433fe2aae254c100 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:36.634185) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:36.635075) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:36.635366) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:36.916554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:01:36.916827) ========= '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-148 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-148 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-148 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 --01:01:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 01:01:38 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.224000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:01:38.159551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-27 01:01:38.159939) ========= '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-148 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-148 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-148 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=15.609000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-27 01:01:53.777721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 01:01:53.778295) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 4b5a9f8b52d493e4b867b666433fe2aae254c100 --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 4b5a9f8b52d493e4b867b666433fe2aae254c100 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-148 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-148 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-148 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-27 01:01:54,049 truncating revision to first 12 chars 2016-09-27 01:01:54,049 Setting DEBUG logging. 2016-09-27 01:01:54,049 attempt 1/10 2016-09-27 01:01:54,049 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4b5a9f8b52d4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 01:01:55,637 unpacking tar archive at: fx-team-4b5a9f8b52d4/testing/mozharness/ 2016-09-27 01:01:55,782 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.298000 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 01:01:56.266491) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:56.266902) ========= script_repo_revision: 4b5a9f8b52d493e4b867b666433fe2aae254c100 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:56.267381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:56.267695) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 01:01:56.279555) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2016-09-27 01:01:56.280264) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-148 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-148 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-148 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 01:01:56 INFO - MultiFileLogger online at 20160927 01:01:56 in C:\slave\test 01:01:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 01:01:56 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 01:01:56 INFO - {'append_to_log': False, 01:01:56 INFO - 'base_work_dir': 'C:\\slave\\test', 01:01:56 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:01:56 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 01:01:56 INFO - 'branch': 'Fx-Team-Non-PGO', 01:01:56 INFO - 'buildbot_json_path': 'buildprops.json', 01:01:56 INFO - 'config_files': ('talos/windows_config.py',), 01:01:56 INFO - 'default_actions': ('clobber', 01:01:56 INFO - 'read-buildbot-config', 01:01:56 INFO - 'download-and-extract', 01:01:56 INFO - 'populate-webroot', 01:01:56 INFO - 'create-virtualenv', 01:01:56 INFO - 'install', 01:01:56 INFO - 'run-tests'), 01:01:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:01:56 INFO - 'download_minidump_stackwalk': True, 01:01:56 INFO - 'download_symbols': 'ondemand', 01:01:56 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 01:01:56 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 01:01:56 INFO - 'hg': 'c:/mozilla-build/hg/hg', 01:01:56 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 01:01:56 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 01:01:56 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 01:01:56 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 01:01:56 INFO - 'C:/mozilla-build/tooltool.py'), 01:01:56 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 01:01:56 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 01:01:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:01:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:01:56 INFO - 'installer_path': 'installer.exe', 01:01:56 INFO - 'log_level': 'info', 01:01:56 INFO - 'log_name': 'talos', 01:01:56 INFO - 'log_to_console': True, 01:01:56 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 01:01:56 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 01:01:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 01:01:56 INFO - 'opt_config_files': (), 01:01:56 INFO - 'pip_index': False, 01:01:56 INFO - 'sps_profile': False, 01:01:56 INFO - 'sps_profile_interval': 0, 01:01:56 INFO - 'suite': 'dromaeojs', 01:01:56 INFO - 'system_bits': '32', 01:01:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:01:56 INFO - 'title': 'T-W732-IX-148', 01:01:56 INFO - 'use_talos_json': True, 01:01:56 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 01:01:56 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 01:01:56 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 01:01:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:01:56 INFO - 'work_dir': 'build'} 01:01:56 INFO - [mozharness: 2016-09-27 08:01:56.794000Z] Running clobber step. 01:01:56 INFO - Running pre-action listener: _resource_record_pre_action 01:01:56 INFO - Running main action method: clobber 01:01:56 INFO - rmtree: C:\slave\test\build 01:01:56 INFO - Using _rmtree_windows ... 01:01:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 01:04:30 INFO - Running post-action listener: _resource_record_post_action 01:04:30 INFO - [mozharness: 2016-09-27 08:04:30.364000Z] Finished clobber step (success) 01:04:30 INFO - [mozharness: 2016-09-27 08:04:30.364000Z] Running read-buildbot-config step. 01:04:30 INFO - Running pre-action listener: _resource_record_pre_action 01:04:30 INFO - Running main action method: read_buildbot_config 01:04:30 INFO - Using buildbot properties: 01:04:30 INFO - { 01:04:30 INFO - "project": "", 01:04:30 INFO - "product": "firefox", 01:04:30 INFO - "script_repo_revision": "production", 01:04:30 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 01:04:30 INFO - "repository": "", 01:04:30 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs", 01:04:30 INFO - "buildid": "20160926225151", 01:04:30 INFO - "pgo_build": "False", 01:04:30 INFO - "basedir": "C:\\slave\\test", 01:04:30 INFO - "buildnumber": 15, 01:04:30 INFO - "slavename": "t-w732-ix-148", 01:04:30 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 01:04:30 INFO - "platform": "win7_ix", 01:04:30 INFO - "scheduler": "tests-fx-team-win32-talos", 01:04:30 INFO - "branch": "fx-team", 01:04:30 INFO - "repo_path": "integration/fx-team", 01:04:30 INFO - "revision": "4b5a9f8b52d493e4b867b666433fe2aae254c100", 01:04:30 INFO - "stage_platform": "win32", 01:04:30 INFO - "builduid": "4e32834389aa4042ae41d5fcb7e223f1", 01:04:30 INFO - "slavebuilddir": "test" 01:04:30 INFO - } 01:04:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 01:04:30 INFO - Running post-action listener: _resource_record_post_action 01:04:30 INFO - [mozharness: 2016-09-27 08:04:30.368000Z] Finished read-buildbot-config step (success) 01:04:30 INFO - [mozharness: 2016-09-27 08:04:30.368000Z] Running download-and-extract step. 01:04:30 INFO - Running pre-action listener: _resource_record_pre_action 01:04:30 INFO - Running main action method: download_and_extract 01:04:30 INFO - mkdir: C:\slave\test\build\tests 01:04:30 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')]} 01:04:30 INFO - trying https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 01:04:30 INFO - Downloading https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/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 01:04:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/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 01:04:31 INFO - Downloaded 1274 bytes. 01:04:31 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 01:04:31 INFO - Using the following test package requirements: 01:04:31 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:04:31 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 01:04:31 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 01:04:31 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'jsshell-win32.zip'], 01:04:31 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 01:04:31 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:04:31 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 01:04:31 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 01:04:31 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 01:04:31 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:04:31 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 01:04:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 01:04:31 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:04:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:04:31 INFO - Fetch https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:04:32 INFO - Expected file size: 17320945 01:05:05 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 01:05:05 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:05:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:05:05 INFO - Fetch https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:05:06 INFO - Expected file size: 17320945 01:05:20 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 01:05:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 01:05:20 INFO - Fetch https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 01:05:21 INFO - Expected file size: 14414057 01:05:30 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')]} 01:05:30 INFO - trying https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:05:30 INFO - Downloading https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 01:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/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 01:05:33 INFO - Downloaded 53500597 bytes. 01:05:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:05:33 INFO - mkdir: C:\slave\test\properties 01:05:33 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 01:05:33 INFO - Writing to file C:\slave\test\properties\build_url 01:05:33 INFO - Contents: 01:05:33 INFO - build_url:https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:05:33 INFO - Running post-action listener: _resource_record_post_action 01:05:33 INFO - Running post-action listener: set_extra_try_arguments 01:05:33 INFO - [mozharness: 2016-09-27 08:05:33.894000Z] Finished download-and-extract step (success) 01:05:33 INFO - [mozharness: 2016-09-27 08:05:33.894000Z] Running populate-webroot step. 01:05:33 INFO - Running pre-action listener: _resource_record_pre_action 01:05:33 INFO - Running main action method: populate_webroot 01:05:33 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 01:05:33 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 01:05:33 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 01:05:33 INFO - u'--tppagecycles', 01:05:33 INFO - u'7'], 01:05:33 INFO - u'tests': [u'tsvgm']}}, 01:05:33 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 01:05:33 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 01:05:33 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 01:05:33 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 01:05:33 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:05:33 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:05:33 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'damp', u'tps']}, 01:05:33 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'tests': [u'damp', u'tps']}, 01:05:33 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'dromaeo_dom']}, 01:05:33 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 01:05:33 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'basic_compositor_video']}, 01:05:33 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 01:05:33 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'other-e10s': {u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'a11yr', 01:05:33 INFO - u'ts_paint', 01:05:33 INFO - u'tpaint', 01:05:33 INFO - u'sessionrestore', 01:05:33 INFO - u'sessionrestore_no_auto_restore', 01:05:33 INFO - u'tabpaint']}, 01:05:33 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'tsvgx', 01:05:33 INFO - u'tsvgr_opacity', 01:05:33 INFO - u'tart', 01:05:33 INFO - u'tscrollx', 01:05:33 INFO - u'cart']}, 01:05:33 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 01:05:33 INFO - u'tsvgr_opacity', 01:05:33 INFO - u'tart', 01:05:33 INFO - u'tscrollx', 01:05:33 INFO - u'cart']}, 01:05:33 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'talos_options': [u'--disable-e10s'], 01:05:33 INFO - u'tests': [u'tp5o']}, 01:05:33 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'tests': [u'tp5o']}, 01:05:33 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'talos_options': [u'--disable-e10s', 01:05:33 INFO - u'--xperf_path', 01:05:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:05:33 INFO - u'tests': [u'tp5n']}, 01:05:33 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:05:33 INFO - u'talos_options': [u'--xperf_path', 01:05:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:05:33 INFO - u'tests': [u'tp5n']}}, 01:05:33 INFO - u'talos.zip': {u'path': u'', 01:05:33 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:05:33 INFO - Running post-action listener: _resource_record_post_action 01:05:33 INFO - [mozharness: 2016-09-27 08:05:33.908000Z] Finished populate-webroot step (success) 01:05:33 INFO - [mozharness: 2016-09-27 08:05:33.908000Z] Running create-virtualenv step. 01:05:33 INFO - Running pre-action listener: _resource_record_pre_action 01:05:33 INFO - Running main action method: create_virtualenv 01:05:33 INFO - Creating virtualenv C:\slave\test\build/venv 01:05:33 INFO - mkdir: C:\slave\test\build\venv\Scripts 01:05:33 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 01:05:33 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'! 01:05:33 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 01:05:33 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 01:05:43 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 01:05:44 INFO - Installing distribute......................................................................................................................................................................................done. 01:05:46 INFO - Return code: 0 01:05:46 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 01:05:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 01:05:46 INFO - Reading from file tmpfile_stdout 01:05:46 INFO - Output received: 01:05:46 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 01:05:46 INFO - Using _rmtree_windows ... 01:05:46 INFO - Using _rmtree_windows ... 01:05:46 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 01:05:46 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')]} 01:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05:46 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')]} 01:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05:46 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05:47 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 01:05:47 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 01:05:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:47 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:47 INFO - 'DCLOCATION': 'SCL3', 01:05:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:47 INFO - 'HOMEDRIVE': 'C:', 01:05:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:47 INFO - 'KTS_VERSION': '1.19c', 01:05:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:47 INFO - 'MOZ_NO_REMOTE': '1', 01:05:47 INFO - 'NO_EM_RESTART': '1', 01:05:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:47 INFO - 'OS': 'Windows_NT', 01:05:47 INFO - 'OURDRIVE': 'C:', 01:05:47 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;', 01:05:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:47 INFO - 'PROCESSOR_LEVEL': '6', 01:05:47 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:47 INFO - 'PROMPT': '$P$G', 01:05:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:47 INFO - 'PWD': 'C:\\slave\\test', 01:05:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:47 INFO - 'SYSTEMDRIVE': 'C:', 01:05:47 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:47 INFO - 'TEST1': 'testie', 01:05:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:47 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:47 INFO - 'USERNAME': 'cltbld', 01:05:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:47 INFO - 'WINDIR': 'C:\\windows', 01:05:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:05:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:05:47 INFO - Downloading/unpacking pip>=1.5 01:05:51 INFO - Running setup.py egg_info for package pip 01:05:51 INFO - warning: no files found matching 'pip\cacert.pem' 01:05:51 INFO - warning: no files found matching '*.html' under directory 'docs' 01:05:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:05:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:05:51 INFO - Installing collected packages: pip 01:05:51 INFO - Found existing installation: pip 0.8.2 01:05:51 INFO - Uninstalling pip: 01:05:51 INFO - Successfully uninstalled pip 01:05:51 INFO - Running setup.py install for pip 01:05:51 INFO - warning: no files found matching 'pip\cacert.pem' 01:05:51 INFO - warning: no files found matching '*.html' under directory 'docs' 01:05:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:05:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:05:51 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 01:05:51 INFO - Successfully installed pip 01:05:51 INFO - Cleaning up... 01:05:51 INFO - Return code: 0 01:05:51 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 01:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05:51 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05:51 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 01:05:51 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 01:05:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:51 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:51 INFO - 'DCLOCATION': 'SCL3', 01:05:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:51 INFO - 'HOMEDRIVE': 'C:', 01:05:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:51 INFO - 'KTS_VERSION': '1.19c', 01:05:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:51 INFO - 'MOZ_NO_REMOTE': '1', 01:05:51 INFO - 'NO_EM_RESTART': '1', 01:05:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:51 INFO - 'OS': 'Windows_NT', 01:05:51 INFO - 'OURDRIVE': 'C:', 01:05:51 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;', 01:05:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:51 INFO - 'PROCESSOR_LEVEL': '6', 01:05:51 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:51 INFO - 'PROMPT': '$P$G', 01:05:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:51 INFO - 'PWD': 'C:\\slave\\test', 01:05:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:51 INFO - 'SYSTEMDRIVE': 'C:', 01:05:51 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:51 INFO - 'TEST1': 'testie', 01:05:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:51 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:51 INFO - 'USERNAME': 'cltbld', 01:05:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:51 INFO - 'WINDIR': 'C:\\windows', 01:05:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:05:53 INFO - Downloading/unpacking psutil>=3.1.1 01:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:05:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:05:54 INFO - Installing collected packages: psutil 01:05:54 INFO - Successfully installed psutil 01:05:54 INFO - Cleaning up... 01:05:54 INFO - Return code: 0 01:05:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 01:05:54 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')]} 01:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05:54 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')]} 01:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05:54 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05:54 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 01:05:54 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 01:05:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:54 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:54 INFO - 'DCLOCATION': 'SCL3', 01:05:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:54 INFO - 'HOMEDRIVE': 'C:', 01:05:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:54 INFO - 'KTS_VERSION': '1.19c', 01:05:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:54 INFO - 'MOZ_NO_REMOTE': '1', 01:05:54 INFO - 'NO_EM_RESTART': '1', 01:05:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:54 INFO - 'OS': 'Windows_NT', 01:05:54 INFO - 'OURDRIVE': 'C:', 01:05:54 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;', 01:05:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:54 INFO - 'PROCESSOR_LEVEL': '6', 01:05:54 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:54 INFO - 'PROMPT': '$P$G', 01:05:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:54 INFO - 'PWD': 'C:\\slave\\test', 01:05:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:54 INFO - 'SYSTEMDRIVE': 'C:', 01:05:54 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:54 INFO - 'TEST1': 'testie', 01:05:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:54 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:54 INFO - 'USERNAME': 'cltbld', 01:05:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:54 INFO - 'WINDIR': 'C:\\windows', 01:05:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:05:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:05:54 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 01:05:54 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 01:05:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:05:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 01:05:56 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) 01:05:56 INFO - Installing collected packages: mozsystemmonitor 01:05:56 INFO - Running setup.py install for mozsystemmonitor 01:05:56 INFO - Successfully installed mozsystemmonitor 01:05:56 INFO - Cleaning up... 01:05:56 INFO - Return code: 0 01:05:56 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 01:05: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')]} 01:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05: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')]} 01:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05: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', '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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05: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', 'jsonschema==2.5.1'] in C:\slave\test\build 01:05: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 jsonschema==2.5.1 01:05:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:56 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:56 INFO - 'DCLOCATION': 'SCL3', 01:05:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:56 INFO - 'HOMEDRIVE': 'C:', 01:05:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:56 INFO - 'KTS_VERSION': '1.19c', 01:05:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:56 INFO - 'MOZ_NO_REMOTE': '1', 01:05:56 INFO - 'NO_EM_RESTART': '1', 01:05:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:56 INFO - 'OS': 'Windows_NT', 01:05:56 INFO - 'OURDRIVE': 'C:', 01:05: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;', 01:05:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:56 INFO - 'PROCESSOR_LEVEL': '6', 01:05:56 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:56 INFO - 'PROMPT': '$P$G', 01:05:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:56 INFO - 'PWD': 'C:\\slave\\test', 01:05:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:56 INFO - 'SYSTEMDRIVE': 'C:', 01:05:56 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:56 INFO - 'TEST1': 'testie', 01:05:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:56 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:56 INFO - 'USERNAME': 'cltbld', 01:05:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:56 INFO - 'WINDIR': 'C:\\windows', 01:05:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:05:56 INFO - Downloading/unpacking jsonschema==2.5.1 01:05:56 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 01:05:56 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 01:05:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:05:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:05:57 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 01:05:57 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 01:05:57 INFO - Downloading functools32-3.2.3-2.tar.gz 01:05:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 01:05:58 INFO - warning: no files found matching '*.txt' 01:05:58 INFO - no previously-included directories found matching 'build' 01:05:58 INFO - no previously-included directories found matching 'dist' 01:05:58 INFO - no previously-included directories found matching '.git*' 01:05:58 INFO - Installing collected packages: jsonschema, functools32 01:05:58 INFO - Running setup.py install for functools32 01:05:58 INFO - warning: no files found matching '*.txt' 01:05:58 INFO - no previously-included directories found matching 'build' 01:05:58 INFO - no previously-included directories found matching 'dist' 01:05:58 INFO - no previously-included directories found matching '.git*' 01:05:59 INFO - Successfully installed jsonschema functools32 01:05:59 INFO - Cleaning up... 01:05:59 INFO - Return code: 0 01:05:59 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 01:05:59 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')]} 01:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05:59 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')]} 01:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05:59 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05:59 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 01:05:59 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 01:05:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:59 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:59 INFO - 'DCLOCATION': 'SCL3', 01:05:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:59 INFO - 'HOMEDRIVE': 'C:', 01:05:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:59 INFO - 'KTS_VERSION': '1.19c', 01:05:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:59 INFO - 'MOZ_NO_REMOTE': '1', 01:05:59 INFO - 'NO_EM_RESTART': '1', 01:05:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:59 INFO - 'OS': 'Windows_NT', 01:05:59 INFO - 'OURDRIVE': 'C:', 01:05:59 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;', 01:05:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:59 INFO - 'PROCESSOR_LEVEL': '6', 01:05:59 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:59 INFO - 'PROMPT': '$P$G', 01:05:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:59 INFO - 'PWD': 'C:\\slave\\test', 01:05:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:59 INFO - 'SYSTEMDRIVE': 'C:', 01:05:59 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:59 INFO - 'TEST1': 'testie', 01:05:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:59 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:59 INFO - 'USERNAME': 'cltbld', 01:05:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:59 INFO - 'WINDIR': 'C:\\windows', 01:05:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 01:05:59 INFO - Cleaning up... 01:05:59 INFO - Return code: 0 01:05:59 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 01:05:59 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')]} 01:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:05:59 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')]} 01:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:05:59 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:05:59 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 01:05:59 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 01:05:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:05:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:05:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:05:59 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:05:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:05:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:05:59 INFO - 'DCLOCATION': 'SCL3', 01:05:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:05:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:05:59 INFO - 'HOMEDRIVE': 'C:', 01:05:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:05:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:05:59 INFO - 'KTS_VERSION': '1.19c', 01:05:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:05:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:05:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:05:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:05:59 INFO - 'MOZ_NO_REMOTE': '1', 01:05:59 INFO - 'NO_EM_RESTART': '1', 01:05:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:05:59 INFO - 'OS': 'Windows_NT', 01:05:59 INFO - 'OURDRIVE': 'C:', 01:05:59 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;', 01:05:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:05:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:05:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:05:59 INFO - 'PROCESSOR_LEVEL': '6', 01:05:59 INFO - 'PROCESSOR_REVISION': '1e05', 01:05:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:05:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:05:59 INFO - 'PROMPT': '$P$G', 01:05:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:05:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:05:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:05:59 INFO - 'PWD': 'C:\\slave\\test', 01:05:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:05:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:05:59 INFO - 'SYSTEMDRIVE': 'C:', 01:05:59 INFO - 'SYSTEMROOT': 'C:\\windows', 01:05:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:59 INFO - 'TEST1': 'testie', 01:05:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:05:59 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:05:59 INFO - 'USERNAME': 'cltbld', 01:05:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:05:59 INFO - 'WINDIR': 'C:\\windows', 01:05:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:05:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:06:00 INFO - Downloading/unpacking blobuploader==1.2.4 01:06:00 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 01:06:00 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 01:06:01 INFO - Downloading blobuploader-1.2.4.tar.gz 01:06:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 01:06:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:06:01 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 01:06:01 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 01:06:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 01:06:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:06:01 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 01:06:01 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 01:06:01 INFO - Downloading docopt-0.6.1.tar.gz 01:06:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 01:06:02 INFO - Installing collected packages: blobuploader, requests, docopt 01:06:02 INFO - Running setup.py install for blobuploader 01:06:02 INFO - Running setup.py install for requests 01:06:03 INFO - Running setup.py install for docopt 01:06:03 INFO - Successfully installed blobuploader requests docopt 01:06:03 INFO - Cleaning up... 01:06:04 INFO - Return code: 0 01:06:04 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:06:04 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:06:04 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 01:06:04 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 01:06:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:06:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:06:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:06:04 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:06:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:06:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:06:04 INFO - 'DCLOCATION': 'SCL3', 01:06:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:06:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:06:04 INFO - 'HOMEDRIVE': 'C:', 01:06:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:06:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:06:04 INFO - 'KTS_VERSION': '1.19c', 01:06:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:06:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:06:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:06:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:06:04 INFO - 'MOZ_NO_REMOTE': '1', 01:06:04 INFO - 'NO_EM_RESTART': '1', 01:06:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:06:04 INFO - 'OS': 'Windows_NT', 01:06:04 INFO - 'OURDRIVE': 'C:', 01:06:04 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;', 01:06:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:06:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:06:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:06:04 INFO - 'PROCESSOR_LEVEL': '6', 01:06:04 INFO - 'PROCESSOR_REVISION': '1e05', 01:06:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:06:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:06:04 INFO - 'PROMPT': '$P$G', 01:06:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:06:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:06:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:06:04 INFO - 'PWD': 'C:\\slave\\test', 01:06:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:06:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:06:04 INFO - 'SYSTEMDRIVE': 'C:', 01:06:04 INFO - 'SYSTEMROOT': 'C:\\windows', 01:06:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:04 INFO - 'TEST1': 'testie', 01:06:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:04 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:06:04 INFO - 'USERNAME': 'cltbld', 01:06:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:06:04 INFO - 'WINDIR': 'C:\\windows', 01:06:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:06:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-di4dub-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_limyp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_eyad6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tsldbg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oofz8q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:06:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:06:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yggkbm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:06:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:06:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tm8vg_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:06:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:06:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9z1e22-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:06:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:06:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z6feza-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:06:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:06:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-idb0mk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-utjvrc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kwkklx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9_l42p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gtdjkt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7jh_sy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rin0ci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:06:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:06:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ub4tpi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:06:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:06:08 INFO - Running setup.py install for manifestparser 01:06:08 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 01:06:08 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 01:06:08 INFO - Running setup.py install for mozcrash 01:06:09 INFO - Running setup.py install for mozdebug 01:06:09 INFO - Running setup.py install for mozdevice 01:06:09 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 01:06:09 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 01:06:09 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 01:06:09 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 01:06:10 INFO - Running setup.py install for mozfile 01:06:10 INFO - Running setup.py install for mozhttpd 01:06:10 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 01:06:10 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 01:06:10 INFO - Running setup.py install for mozinfo 01:06:11 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Running setup.py install for mozInstall 01:06:11 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 01:06:11 INFO - Running setup.py install for mozleak 01:06:12 INFO - Running setup.py install for mozlog 01:06:12 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 01:06:12 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 01:06:12 INFO - Running setup.py install for moznetwork 01:06:13 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 01:06:13 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 01:06:13 INFO - Running setup.py install for mozprocess 01:06:13 INFO - Running setup.py install for mozprofile 01:06:14 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Running setup.py install for mozrunner 01:06:14 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 01:06:14 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 01:06:15 INFO - Running setup.py install for mozscreenshot 01:06:15 INFO - Running setup.py install for moztest 01:06:15 INFO - Running setup.py install for mozversion 01:06:16 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 01:06:16 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 01:06:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:06:16 INFO - Cleaning up... 01:06:16 INFO - Return code: 0 01:06:16 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:06:16 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')]} 01:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:06:16 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')]} 01:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:06:16 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:06:16 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 01:06:16 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 01:06:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:06:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:06:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:06:16 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:06:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:06:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:06:16 INFO - 'DCLOCATION': 'SCL3', 01:06:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:06:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:06:16 INFO - 'HOMEDRIVE': 'C:', 01:06:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:06:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:06:16 INFO - 'KTS_VERSION': '1.19c', 01:06:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:06:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:06:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:06:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:06:16 INFO - 'MOZ_NO_REMOTE': '1', 01:06:16 INFO - 'NO_EM_RESTART': '1', 01:06:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:06:16 INFO - 'OS': 'Windows_NT', 01:06:16 INFO - 'OURDRIVE': 'C:', 01:06:16 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;', 01:06:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:06:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:06:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:06:16 INFO - 'PROCESSOR_LEVEL': '6', 01:06:16 INFO - 'PROCESSOR_REVISION': '1e05', 01:06:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:06:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:06:16 INFO - 'PROMPT': '$P$G', 01:06:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:06:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:06:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:06:16 INFO - 'PWD': 'C:\\slave\\test', 01:06:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:06:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:06:16 INFO - 'SYSTEMDRIVE': 'C:', 01:06:16 INFO - 'SYSTEMROOT': 'C:\\windows', 01:06:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:16 INFO - 'TEST1': 'testie', 01:06:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:16 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:06:16 INFO - 'USERNAME': 'cltbld', 01:06:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:06:16 INFO - 'WINDIR': 'C:\\windows', 01:06:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:06:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:06:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:06:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-elp5d6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:06:16 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)) 01:06:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:06:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fowobf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:06:16 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)) 01:06:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:06:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqrkzo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xeq6yx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w1wrs6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zsdojk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-whqtro-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gizpzz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fkyret-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:06:17 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)) 01:06:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:06:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-otidcl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-65hqdw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dor4r_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owd60g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p8d0ks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8dsqes-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t88lcm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:06:18 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)) 01:06:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:06:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ulfzgx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:06:19 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)) 01:06:19 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)) 01:06:19 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)) 01:06:19 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)) 01:06:19 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)) 01:06:19 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)) 01:06:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 01:06:19 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 01:06:19 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 01:06:20 INFO - Downloading blessings-1.6.tar.gz 01:06:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 01:06:20 INFO - Installing collected packages: blessings 01:06:20 INFO - Running setup.py install for blessings 01:06:21 INFO - Successfully installed blessings 01:06:21 INFO - Cleaning up... 01:06:23 INFO - Return code: 0 01:06:23 INFO - Done creating virtualenv C:\slave\test\build/venv. 01:06:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:06:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:06:24 INFO - Reading from file tmpfile_stdout 01:06:24 INFO - Using _rmtree_windows ... 01:06:24 INFO - Using _rmtree_windows ... 01:06:24 INFO - Current package versions: 01:06:24 INFO - blessings == 1.6 01:06:24 INFO - blobuploader == 1.2.4 01:06:24 INFO - docopt == 0.6.1 01:06:24 INFO - functools32 == 3.2.3-2 01:06:24 INFO - jsonschema == 2.5.1 01:06:24 INFO - manifestparser == 1.1 01:06:24 INFO - mozInstall == 1.12 01:06:24 INFO - mozcrash == 0.17 01:06:24 INFO - mozdebug == 0.1 01:06:24 INFO - mozdevice == 0.48 01:06:24 INFO - mozfile == 1.2 01:06:24 INFO - mozhttpd == 0.7 01:06:24 INFO - mozinfo == 0.9 01:06:24 INFO - mozleak == 0.1 01:06:24 INFO - mozlog == 3.4 01:06:24 INFO - moznetwork == 0.27 01:06:24 INFO - mozprocess == 0.23 01:06:24 INFO - mozprofile == 0.28 01:06:24 INFO - mozrunner == 6.12 01:06:24 INFO - mozscreenshot == 0.1 01:06:24 INFO - mozsystemmonitor == 0.3 01:06:24 INFO - moztest == 0.7 01:06:24 INFO - mozversion == 1.4 01:06:24 INFO - psutil == 3.1.1 01:06:24 INFO - requests == 1.2.3 01:06:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:06:24 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 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:06:24 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 01:06:24 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 01:06:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:06:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:06:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:06:24 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:06:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:06:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:06:24 INFO - 'DCLOCATION': 'SCL3', 01:06:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:06:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:06:24 INFO - 'HOMEDRIVE': 'C:', 01:06:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:06:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:06:24 INFO - 'KTS_VERSION': '1.19c', 01:06:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:06:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:06:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:06:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:06:24 INFO - 'MOZ_NO_REMOTE': '1', 01:06:24 INFO - 'NO_EM_RESTART': '1', 01:06:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:06:24 INFO - 'OS': 'Windows_NT', 01:06:24 INFO - 'OURDRIVE': 'C:', 01:06:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:06:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:06:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:06:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:06:24 INFO - 'PROCESSOR_LEVEL': '6', 01:06:24 INFO - 'PROCESSOR_REVISION': '1e05', 01:06:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:06:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:06:24 INFO - 'PROMPT': '$P$G', 01:06:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:06:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:06:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:06:24 INFO - 'PWD': 'C:\\slave\\test', 01:06:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:06:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:06:24 INFO - 'SYSTEMDRIVE': 'C:', 01:06:24 INFO - 'SYSTEMROOT': 'C:\\windows', 01:06:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:24 INFO - 'TEST1': 'testie', 01:06:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:24 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:06:24 INFO - 'USERNAME': 'cltbld', 01:06:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:06:24 INFO - 'WINDIR': 'C:\\windows', 01:06:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:06:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 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)) 01:06:24 INFO - Cleaning up... 01:06:24 INFO - Return code: 0 01:06:24 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 01:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:06:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E7E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-148', 'USERDOMAIN': 'T-W732-IX-148', '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-148', '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 01:06:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 01:06:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:06:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:06:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:06:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:06:24 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:06:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:06:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:06:24 INFO - 'DCLOCATION': 'SCL3', 01:06:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:06:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:06:24 INFO - 'HOMEDRIVE': 'C:', 01:06:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:06:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:06:24 INFO - 'KTS_VERSION': '1.19c', 01:06:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:06:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:06:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:06:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:06:24 INFO - 'MOZ_NO_REMOTE': '1', 01:06:24 INFO - 'NO_EM_RESTART': '1', 01:06:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:06:24 INFO - 'OS': 'Windows_NT', 01:06:24 INFO - 'OURDRIVE': 'C:', 01:06:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:06:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:06:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:06:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:06:24 INFO - 'PROCESSOR_LEVEL': '6', 01:06:24 INFO - 'PROCESSOR_REVISION': '1e05', 01:06:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:06:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:06:24 INFO - 'PROMPT': '$P$G', 01:06:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:06:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:06:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:06:24 INFO - 'PWD': 'C:\\slave\\test', 01:06:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:06:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:06:24 INFO - 'SYSTEMDRIVE': 'C:', 01:06:24 INFO - 'SYSTEMROOT': 'C:\\windows', 01:06:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:24 INFO - 'TEST1': 'testie', 01:06:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:24 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:06:24 INFO - 'USERNAME': 'cltbld', 01:06:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:06:24 INFO - 'WINDIR': 'C:\\windows', 01:06:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:06:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:06:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 01:06:24 INFO - Cleaning up... 01:06:24 INFO - Return code: 0 01:06:24 INFO - Running post-action listener: _resource_record_post_action 01:06:24 INFO - Running post-action listener: _start_resource_monitoring 01:06:24 INFO - Starting resource monitoring. 01:06:25 INFO - [mozharness: 2016-09-27 08:06:25.059000Z] Finished create-virtualenv step (success) 01:06:25 INFO - [mozharness: 2016-09-27 08:06:25.059000Z] Running install step. 01:06:25 INFO - Running pre-action listener: _resource_record_pre_action 01:06:25 INFO - Running main action method: install 01:06:25 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:06:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:06:25 INFO - Reading from file tmpfile_stdout 01:06:25 INFO - Using _rmtree_windows ... 01:06:25 INFO - Using _rmtree_windows ... 01:06:25 INFO - Detecting whether we're running mozinstall >=1.0... 01:06:25 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 01:06:25 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 01:06:25 INFO - Reading from file tmpfile_stdout 01:06:25 INFO - Output received: 01:06:25 INFO - Usage: mozinstall-script.py [options] installer 01:06:25 INFO - Options: 01:06:25 INFO - -h, --help show this help message and exit 01:06:25 INFO - -d DEST, --destination=DEST 01:06:25 INFO - Directory to install application into. [default: 01:06:25 INFO - "C:\slave\test"] 01:06:25 INFO - --app=APP Application being installed. [default: firefox] 01:06:25 INFO - Using _rmtree_windows ... 01:06:25 INFO - Using _rmtree_windows ... 01:06:25 INFO - mkdir: C:\slave\test\build\application 01:06:25 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'] 01:06:25 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 01:06:27 INFO - Reading from file tmpfile_stdout 01:06:27 INFO - Output received: 01:06:27 INFO - C:\slave\test\build\application\firefox\firefox.exe 01:06:27 INFO - Using _rmtree_windows ... 01:06:27 INFO - Using _rmtree_windows ... 01:06:27 INFO - Running post-action listener: _resource_record_post_action 01:06:27 INFO - [mozharness: 2016-09-27 08:06:27.745000Z] Finished install step (success) 01:06:27 INFO - [mozharness: 2016-09-27 08:06:27.745000Z] Running run-tests step. 01:06:27 INFO - Running pre-action listener: _resource_record_pre_action 01:06:27 INFO - Running main action method: run_tests 01:06:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 01:06:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 01:06:27 INFO - Python 2.7.3 01:06:27 INFO - Return code: 0 01:06:27 INFO - grabbing minidump binary from tooltool 01:06: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')]} 01:06:27 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 0x018F4E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F5E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 01:06:27 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 01:06:27 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 01:06:27 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 01:06:27 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 01:06:28 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmphdvfkz 01:06:28 INFO - INFO - File integrity verified, renaming tmphdvfkz to win32-minidump_stackwalk.exe 01:06:28 INFO - Return code: 0 01:06:28 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 01:06:28 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 01:06:28 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 01:06:28 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 01:06:28 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 01:06:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-148', '--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 01:06:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-148 --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 01:06:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:06:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:06:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:06:28 INFO - 'COMPUTERNAME': 'T-W732-IX-148', 01:06:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:06:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:06:28 INFO - 'DCLOCATION': 'SCL3', 01:06:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:06:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:06:28 INFO - 'HOMEDRIVE': 'C:', 01:06:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:06:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:06:28 INFO - 'KTS_VERSION': '1.19c', 01:06:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:06:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-148', 01:06:28 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:06:28 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 01:06:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:06:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:06:28 INFO - 'MOZ_NO_REMOTE': '1', 01:06:28 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:06:28 INFO - 'NO_EM_RESTART': '1', 01:06:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:06:28 INFO - 'OS': 'Windows_NT', 01:06:28 INFO - 'OURDRIVE': 'C:', 01:06:28 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;', 01:06:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:06:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:06:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:06:28 INFO - 'PROCESSOR_LEVEL': '6', 01:06:28 INFO - 'PROCESSOR_REVISION': '1e05', 01:06:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:06:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:06:28 INFO - 'PROMPT': '$P$G', 01:06:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:06:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:06:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:06:28 INFO - 'PWD': 'C:\\slave\\test', 01:06:28 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 01:06:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:06:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:06:28 INFO - 'SYSTEMDRIVE': 'C:', 01:06:28 INFO - 'SYSTEMROOT': 'C:\\windows', 01:06:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:28 INFO - 'TEST1': 'testie', 01:06:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:06:28 INFO - 'USERDOMAIN': 'T-W732-IX-148', 01:06:28 INFO - 'USERNAME': 'cltbld', 01:06:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:06:28 INFO - 'WINDIR': 'C:\\windows', 01:06:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:06:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:06:28 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-148', '--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 01:06:29 INFO - mozversion application_buildid: 20160926225151 01:06:29 INFO - mozversion application_changeset: 4b5a9f8b52d493e4b867b666433fe2aae254c100 01:06:29 INFO - mozversion application_display_name: Nightly 01:06:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:06:29 INFO - mozversion application_name: Firefox 01:06:29 INFO - mozversion application_remotingname: firefox 01:06:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:06:29 INFO - mozversion application_vendor: Mozilla 01:06:29 INFO - mozversion application_version: 52.0a1 01:06:29 INFO - mozversion platform_buildid: 20160926225151 01:06:29 INFO - mozversion platform_changeset: 4b5a9f8b52d493e4b867b666433fe2aae254c100 01:06:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:06:29 INFO - mozversion platform_version: 52.0a1 01:06:29 INFO - using testdate: 1474963589 01:06:29 INFO - actual date: 1474963589 01:06:29 INFO - starting webserver on 'localhost:49890' 01:06:29 INFO - SUITE-START | Running 2 tests 01:06:29 INFO - TEST-START | dromaeo_css 01:06:29 INFO - operating with platform_type : w7_ 01:06:29 INFO - Initialising browser for dromaeo_css test... 01:06:30 INFO - TEST-INFO | started process 3032 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp6o4_uz\profile http://localhost:49890/getInfo.html) 01:06:37 INFO - PROCESS | 3032 | __metrics Screen width/height:1600/1200 01:06:37 INFO - PROCESS | 3032 | colorDepth:24 01:06:37 INFO - PROCESS | 3032 | Browser inner width/height: 1010/674 01:06:37 INFO - PROCESS | 3032 | __metrics 01:06:38 INFO - PROCESS | 3032 | 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" 01:06:38 INFO - PROCESS | 3032 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:06:39 INFO - TEST-INFO | 3032: exit 0 01:06:39 INFO - Browser initialized. 01:06:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:06:39 INFO - TEST-INFO | started process 1184 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp6o4_uz\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:06:39 INFO - PROCESS | 1184 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 01:06:50 INFO - PROCESS | 1184 | RSS: Main: 138985472 01:06:50 INFO - PROCESS | 1184 | 01:07:42 INFO - PROCESS | 1184 | 1474963662544 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] 01:09:55 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49890/tests/dromaeo/cssquery-ext.html) 01:09:56 INFO - PROCESS | 1184 | RSS: Main: 186204160 01:09:56 INFO - PROCESS | 1184 | 01:12:59 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-ext.html (next: http://localhost:49890/tests/dromaeo/cssquery-jquery.html) 01:13:00 INFO - PROCESS | 1184 | RSS: Main: 203378688 01:13:00 INFO - PROCESS | 1184 | 01:16:03 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49890/tests/dromaeo/cssquery-mootools.html) 01:16:03 INFO - PROCESS | 1184 | RSS: Main: 146333696 01:16:03 INFO - PROCESS | 1184 | 01:19:05 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49890/tests/dromaeo/cssquery-prototype.html) 01:19:06 INFO - PROCESS | 1184 | RSS: Main: 145485824 01:19:06 INFO - PROCESS | 1184 | 01:22:09 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49890/tests/dromaeo/cssquery-yui.html) 01:22:10 INFO - PROCESS | 1184 | RSS: Main: 137449472 01:22:10 INFO - PROCESS | 1184 | 01:25:12 INFO - PROCESS | 1184 | Cycle 1(1): loaded http://localhost:49890/tests/dromaeo/cssquery-yui.html (next: http://localhost:49890/tests/dromaeo/cssquery-dojo.html) 01:25:13 INFO - PROCESS | 1184 | RSS: Main: 188252160 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | __start_tp_report 01:25:13 INFO - PROCESS | 1184 | _x_x_mozilla_page_load 01:25:13 INFO - PROCESS | 1184 | _x_x_mozilla_page_load_details 01:25:13 INFO - PROCESS | 1184 | |i|pagename|runs| 01:25:13 INFO - PROCESS | 1184 | |0;dojo.html;2344.43733503959;2357.481354102097;2398.4603464220554;2408.1812183857496;2413.9155129570468;3961.9500832279396;4099.077707515811;4116.897077573062;4159.376093585964;4178.394132850739;4020.0753826619894;4071.5928407159267;4087.591240875911;4145.792710364477;4173.520045194803;3460.7058400036026;3593.281343731251;3602.189507360849;3615.0781550704532;3617.1318883468043;3480.7215422766244;3499.212677147647;3622.6920711739535;3630.564332280117;3679.7240206984447;3530.2409981853766;3642.3261696586083;3674.3202507536193;3685.9078523037006;3686.926261474769;3493.12671832042;3505.2113274513285;3531.311394278117;3584.982075089634;3600.6219346968455;3572.6070132285427;3626.492291079251;3633.7638053526703;3639.4540818877117;3641.6540428659237;3987.1029018470904;4009.959900400988;4093.672506199497;4113.732607380541;4113.9794301028605;4165.937510937339;4200.726952748061;4244.72409293395;4273.807678654468;4320.783960801948;4260.190563792869;4341.500727416356;4342.283523218665;4349.217140914634;4353.62994145498;3829.9042523937123;3984.183242435294;4020.437138800541;4064.9837540614844;4068.3083875741195;4284.421603083544;4322.827086916488;4360.127974405132;4429.623482004002;4441.222786012435;4158.438610787565;4272.273713468718;4276.401303817468;4292.720973136736;4296.205202037633;4198.958010419857;4261.573842615714;4346.304591265382;4355.847545335898;4363;4160.54234034256;4216.620504154641;4234.724742891703;4298.634616057608;4314;4185.539590645027;4221.240176768211;4258.190943720683;4278.572142785696;4306.4796245888365;3045.710657487535;3097.597312349425;3099.1167517257923;3103.05356866158;3106.891258805931;2793.2458236276093;2835.8723857426467;2846.331112188634;2847.843368614746;2854.785891058179;3931.312020396419;3969.226000929849;4002.3196056670436;4038.273110840077;4064.248114098901;4315.180115777992;4388.758618275961;4391.077873646506;4392.450943632046;4399.560043995639;4021.738586991836;4064.7764372959773;4082.101937573729;4141.047559061432;4141.79291035447;3596;3657.104009517685;3661.8169091544783;3685.7972811495624;3736.5889752127246;4429.136318417877;4438.112377524443;4486.394336764495;4502;4511.503734589127;4314.460692413449;4391.670624703096;4395.230834603995;4397.714148580332;4410.42664453619;4354.956450435455;4398.978005109954;4423.668224883211;4487.282034874404;4502.099580083931;4197.034682023091;4209.389638502339;4225.830966761295;4250.149970006073;4287.485501739687;4237.766922819274;4247.299195632686;4263.722858014343;4313.84901528445;4316.525182230015;4100.91798164041;4111.568285329998;4164.646005089601;4188.874333769992;4222.493300803801;4060.7563546187325;4083.3466645336603;4109.054917368962;4124.91750164989;4131.8967025824595;4050.554439011765;4139.151473947773;4151.833926643021;4155.355919832431;4155.771432571238;4165.4584903962295;4186.916261674811;4245.957540424556;4262.637675797592;4264.978675106605;4049.2711311962953;4091.977005748563;4116.094459218967;4137.379393091061;4205.474315710536 01:25:13 INFO - PROCESS | 1184 | |1;ext.html;520.3963402453154;528.725062967257;532.9946700532994;549.1981706708208;574.0786038408357;25041.12356067538;36718.44922326164;36919.26161476769;37462.625373746254;40255.59744402555;38426;38590;38777.22445551087;38856.41715374272;38859.41710874339;6415.1981002374705;6591.209054913417;7523.360514356286;7592.810179745496;7744.457887947846;9424.293178011641;9587.568559414844;9675.951620241889;10006.249531285146;10023.248256380764;9674.90325096747;9706.417614943091;9766.658166964158;9771.071748183911;9787;6469.223693156804;6565.146530950994;10036.29745917786;10086.49567521621;10105.595776168944;9089.545522723904;9693.612255509772;9728.562214700356;9861.556229969598;10103.393796372242;35015.12462188466;35591.64408355935;35800.64199357999;35838.64161358379;35842.64157358445;2695.4743824954144;2721.034032918325;2747.8763455644544;2776.763975062122;2814.493391189585;25480.108196213045;25672;26248.343791405176;26676;26731.866340668174;3126.6873312668777;3191.4414977378865;3217.404780115686;3226.370857682753;3286.5891763529557;220652;221518;224527;224632;224798.99999999837;46866.765666172134;46945;47051.76474117608;47152.29271560929;47222.291665625045;26000.739992599832;27175.592366114524;27188.864055679594;48214;48936.26595601069;9537.904620953841;9721.513924303756;9836.163926066365;9913.355631883904;9947.253955953332;6094.116353128771;6196.225471816023;6244.594101383395;6325.936740632626;7390.5196162249285;13966.930165349311;15262.923685381502;15978.440754573534;16006.439774607832;16511.422100226435;6373.490120790419;6386.552941294158;6472.126262954535;6475.287718350977;6528.477721782246;747.7270796159471;833.8790875323044;882.2500874256958;918.1002617434951;998.6255154317131;24612.753872461406;25787.226383208534;25858.35354116162;26053.218403447925;26126.34684132912;847.4576271186446;896.3904544909524;922.5387306346827;935.4340623922429;969.5733877094056;13315.334233288297;13616.18302901829;13777.862221377858;13845.376958036912;13957.79063314051;320980;331241;332170;332205;332605;38105.047373815876;41885.79057104695;44483.55516444794;44918;45099.54900451019;956.5934477847027;1052.2266134391216;1092.595739576346;1094.9069329106956;1096.040964156363;25174.874125629256;26361;26427.207183784518;26621.068262610715;26718.332041699112;37832.810835945646;38418.615813842625;38463.807680961414;38465;38596.22807543889;253845;259776;261011;306139;309147;34474.82762586171;34649.82675086609;34955.47566786551;35047.824760876036;35082;37478.812605937885;38458.80770596129;38504;38866;39339.60660393474;32081.35837283288;33040.50439243461;33046.17384565405;34028.65971340255;34514;32610.836945815117;32862;32885.342293153524;33456.498152527245;34097.48853767146;31825;32659.34681306313;32678.183045424055;32694.182645434055;32707.836460817543;27490.587641185797;29708.851455742584;30294;30465.238369040064;30618.540721888745;21594.2442014535;23626.88186559125;23754.52490950206;23823.285301440985;23837.880810595838 01:25:13 INFO - PROCESS | 1184 | |2;jquery.html;291.46807077084327;305.9174023013786;314.21759818053056;316.1495576898143;326.77452557735177;11317.094632429407;12082.57710980116;15050.999999999985;15225.238738063108;15525.53423397297;14639.487617933375;15250.618734531614;15254.542363729079;15279.465218717347;15757.60605984851;5725.656460612356;6018.3078945921125;6039.245094363205;6051.878962420749;6064.818055458321;3854.691624670034;4118.855840045599;4369.956300437003;5985.5810093293485;6034.788782392618;7182.892256616155;7321.231270716578;7337.816554586125;7339;7342.265773422641;6871;6886.48351373649;7072.04527388801;7266.672999714999;7312.1956584775635;6682.899756503608;6905.654717264117;6908.861822763567;6909.2745261747295;6911.101556797635;21422;21613.67579486309;21613.891930540405;21767.23814666479;22241.332760017227;1741.1990484377202;2075;2448.7796098351055;2467.087177744228;2549.31168584483;13096;13627.114237574526;14027.929860350734;14034.859651403458;14805.703885922221;1684.3010150787409;1760.5334586334504;1776.7423723560141;1838.090145378033;1868.831805137544;71947;73977.63011184963;74347.62826185835;76229.27872982861;77499;15273.854460915254;15289.15909624959;15520.361538538249;15574.844251557566;15602.29789659468;21739;22072.11711531553;25013.499730005296;25868.353291167496;25945.61081583778;6508.544401891822;6675.833104172338;6777.254502004874;6851.3491218334175;6898.689558969859;5413.350397952192;5414.7834086636885;5417.268668729671;5460.34475862899;5562.9443705563235;7404.703811847574;7766.106897706723;7813.453058285979;7978.720744774021;8004.439689221698;3162.7311678507585;3185.649578546358;3229.3218424131196;3240.238543942172;3281.0812972367776;755.2145768400802;793.1790596732354;875.8511053120985;889.479654402169;916.6745805239177;15170.165640889856;16003.119828409317;16601.252943617565;16653.583660408585;16918.830811691972;766.3294617209941;772.5866661336158;802.8635132027453;827.6397824831205;832.0556168748514;8947.910520894839;9145.405548639317;9307.53462326881;9413.294002949806;9500.477473738873;288764;300596;302525;304030;306097;75326;75842;77414.612926935;77535;78055.21944780479;821.6302663801314;824.04410883375;825.3974598543098;825.4304529874488;828.6436832162228;14126.293685315899;14364;14454.638634034232;14558.781618275947;14880.255987200813;27930.301742456602;30122.698773012868;35651.82174089113;37757.622423775414;39189;48228;48756;48935.26597101118;49242.507574923795;50480.24279635735;6432.620150087712;6851.554648947803;6873.759418420531;6907.102076730194;6981.127359080115;10045.095941365313;10401.219908507044;10607;10705.8929410708;10847.891521084688;8724.563771811258;9368.812623747623;9373;9528.904710953078;9738.853917191105;9452.621895124117;9562.426254424758;9625.855612165964;9731.756706082404;9811.754706132404;9496.572654230438;9505.382150160218;9577.281703872099;9670.081342272473;9828.950855245677;6517.022446633043;6573.276939536551;6695.196576410862;6835.213950395768;6923.307669233036;6717;9084.18242358191;9086.591103400231;9108.726738197865;9179.77050573741 01:25:13 INFO - PROCESS | 1184 | |3;mootools.html;1176.3001014396436;1203.2900588652697;1239.4918083585728;1518.400231908396;1533.869621082208;6743.898841517375;7148.356647901688;7383.372413344865;7390.852182956338;7405.407567394609;5823;6134.386561343864;6932.7920162395085;7123.786286411416;7154.105736782903;1801.6666916620402;1822.5079228608267;1838.8253115953962;1847.5750577367214;1852.907354632263;1859.8047205043479;1884.8115188481179;1893.7064754963003;1907.656621808074;1908.9236430542762;1624.7400415933453;1663.1019249605258;1678.026744488194;1681.7561453589221;1822.3803906671728;1884.5853912139376;1894.1571000904603;1903.2196799312217;1903.5907279934743;1906.65680177568;1897.2411035585737;1905.3236101184018;1907.7806052304024;1926.3450426854797;1935.9612807743765;6913.446924246048;6914.55055421396;6973.616451095188;7071.434285257167;7092.219855815856;3977.1449138435087;3998.900027499307;4101.097758493157;4103.4870641169855;4103.671706263492;2579.6775403074616;2585.250277419546;2604.309857887661;2622.3050891513763;2675.705672376037;839.345310657688;841;847.7499137754377;866.7659731872423;869.1091631078195;9226.907730922672;9244.306676999186;9247.861282080774;9281.118293762082;9281.814363712689;3516.3143187078276;3584.408572585546;3682.263547290499;3682.5949145593963;3682.705383569308;7971.880421793678;7995.920040799634;8006.239407256301;8011.479253848598;8026.9598652007535;4095.8566450174103;4098.815553300049;4160.563140870226;4172.874813755592;4175.728577642443;3797.7911214883447;3807.1053302473897;3808.1622043150464;3808.21931504041;3818.236352729465;2935.823850568973;3159.952600710991;3187.6334221564352;3199.8720051198156;3208.5508028875975;994.2394068537575;1029.0378496106164;1042.937423754562;1043.2019505078622;1043.3583346242006;731.3417923868561;764.1823249123385;767.1063211358816;777.3547955197173;792.9444938854225;4356.3683265926265;4390;4511.05267893746;4525.479569849444;4622.560856718606;325.9054874086488;327.15267457285745;331.30756718458537;345.1560933517576;347.39206388819565;3082.5992620959582;3128.23358277219;3133.6239651242;3135.1378370947714;3192.8722851085695;8270.751877443687;8616.48301101936;8643.654253829776;8684.088170742107;8718.17177368149;8658.307335413152;8666.436681615674;8676.696315628922;8681.2186903179;8685.522296273639;6378.904316435164;6456.51576131792;6634.071230027704;6642.269350371455;6643.402093811484;4275.4014437978085;4411.602955733999;4411.911761764811;4509.594136527729;4523.0275490769645;7906.802329941797;7997.120316765035;8057.5165490070785;8095.2714255717265;8102.513849169069;7931.603419829101;8193.09875913661;8238.134995825592;8243.134470880712;8277.130901255523;5970.641761494324;6070.423309785563;6104.4505994460715;6109.20580324555;6118.990366589463;6736.090627765189;6834.589924604341;6855.108835851307;6864.279250678607;6877.2091209509545;6019.819405417845;6083.330833608388;6092.482139018233;6166.1983942087245;6210;5906.852328691817;5932.614380065282;6006.069059295816;6049.334573196857;6050.667213303311;5205.687658740488;5209.973950130225;5375.731213439391;5377.489138531833;5377.731113444391;3867.5358956925347;3932.567417584121;3941.4087886817206;4037.8990525237105;4151.605597468236;3913.1977944521873;4036.3340048891605;4059.6549293309217;4125.793710314412;4147.2534943709215 01:25:13 INFO - PROCESS | 1184 | |4;prototype.html;221.18493351997898;240.68229936483837;242.2454055616753;246.87162675408777;247.4728827597218;4217.894552636186;4340.590972010533;4430.490493593238;4472.821087156518;4481.731096134235;4376.934345984812;4385.671074669397;4392.428984232045;4395.714278571899;4409.7354158750495;3224.25842056326;3244.286257023457;3253.398121347558;3258.2668899497544;3258.7230085442648;3228.8385580720987;3246.594175728034;3262.748703394662;3263.7108342204815;3264.05342450689;3220.8389580520998;3234.9353012939846;3235.9838200808963;3237.6924192201704;3237.838108094586;3098.798578092417;3200.5039218921106;3226.161198088514;3230.741540676752;3231.482962725976;2768.5016696994535;2807.4665813495576;2822.5342818434933;2825.9858700706363;2827.4486475137355;4172.77049762263;4252.553481884389;4276.486821581399;4278.893027674302;4282.400463935051;3708.796016219107;3767.397216445383;3768;3771.0949372150485;3786.9810650946843;4167.6874234432535;4497.415336006331;4501.437320334958;4521.728696278234;4563.220344065817;3051.420230156263;3068.3556453144865;3070.7082827131385;3074.7693922955646;3077.307605788702;4693.624510039189;4724.71651700899;4725;4732.384789977315;4740.146773580754;2942.3673910109;3145.2294187924103;3147.937041259162;3151.9684803151677;3168.936621267562;3141.4659507883716;3152.3064925716762;3186.394585028837;3197.8081315121167;3201;3711.2391959648207;3713.8885833425043;3714.7399682022;3726.12436077527;3736.159364143046;4563.338315944169;4570.794314255866;4571.634269258451;4574.359589657451;4587.8623641290815;1096.166913145999;1101.4437708956925;1109.3011402816173;1110.6279396402135;1113.465536542448;688.6591137386926;692.3042342445809;709.4537206351081;709.9574025558483;711.7900219435279;4295.5060168080445;4348.739075655408;4352.6735494837385;4378.605925466723;4384.8246070157475;4584.4957054723955;4597.057603191338;4601.056783359403;4670.486246512881;4674.672772905864;4340.891477713019;4357.346398040319;4365.80353884076;4366.323219900857;4375.956240437618;2372.8339016268696;2433.2943446400345;2448.485817978209;2473.6289556566658;2479.3429741118616;4762.64280178988;4801.567858892717;4808.076769292241;4823.204171311764;4828.782704778293;4784.40194975628;4788.61691064721;4793.209120495149;4794.304825800239;4802.327674125625;4752.049590081998;4811.927821082617;4820.758962051954;4826.7827947742435;4834.830780922792;4503.279475283939;4696.530346965276;4714.858554243378;4749.572538471554;4772.164871147605;4576.496585375673;4609.746463944515;4650.116477869194;4665.813367465264;4670.6263498919325;4863.148948933852;4870;4871.318015477765;4875.561199492063;4880.731559764247;3104.689531046877;3118.875244990175;3139.168120448129;3140.811551306929;3158.7788854780865;4472.79872405736;4564.17433026792;4584.541545845389;4716.0096379760635;4727.479977202436;4469.932951005672;4503.842365517322;4523.751193684379;4535.433070866142;4536.6824322297125;4527.637788976808;4531.252343363308;4535.115652447741;4535.319702044588;4542.818867094646;4386;4401.493828209669;4446.777661116996;4456.6657500688225;4466.798994045341;4299.720518166309;4508.549145085465;4557.156925968707;4577.198990176772;4590.2426099693175;4412.889677758082;4429.446319210098;4469.642428605769;4505.7296562206375;4513.187626227311 01:25:13 INFO - PROCESS | 1184 | |5;yui.html;586.3872253495097;587.4742105815295;604.480147073517;624.3569123802482;680.4080450008494;12594.370281485923;13384.397702103404;13880.652983675413;14017.018808683371;14061.086029408081;12343;12395.938020309908;12556.623301300975;12578.685532861684;12642.873571264285;642.2902692524763;654.2149420695162;661.1273119482262;663.4692246203042;665.7004348043375;508.41786154852673;520.9739513024333;624.6033768556954;637.7417146055855;644.6583310845236;681.6830173969104;683.7470136049668;693.4868197533839;699.9405050570707;701.6071000239856;687.151368060445;696.5263620737896;696.6516741629185;697.4106879686657;698.3435570563654;391.42851436902157;398.07646260675244;492.3132230538379;504.6399952129014;508.77359574988776;12357.443915023756;12702;12723.936380318131;12767.361631918367;12786.552470663575;11617.76764464706;11693.037816927412;11738.721664254032;11775.823362649568;12433.254004759743;11391;11481.540738370455;11568.421578921021;11582.594609188722;11604.535818567247;3945.447637330776;4047.736897101679;4054.0675644601906;4104;4179.832806687728;59291.40708592945;62570.68714656398;63761.68119159421;64890.675546622435;65441.672791636214;9610.759731006781;9649.276304277208;9671.468069256118;9767.316287859923;10054.497275136215;16899.66200676004;17936.9103154486;17942.372016979603;17959.910200448914;17970.460886173434;6371.649559274284;6374.58565193261;6382.585131966407;6429.742810287629;6510.121133646991;3501.439769636846;3516.0330908999717;3523.083998160497;3576.517170182044;3647.8358473868207;2559.0915225094823;2585.108137692493;2651.4034342272835;2654.508915850574;2659.7739192168497;437.4204179462225;453.1865301783307;455.6377679744611;457.5493139257828;460.2727690249398;492.1141944499886;509.95410413063;549.686678593206;552.320645605907;556.4268803132779;7282.271772822782;7283.562986220843;7299.5255308404785;7563.243675632392;7777.533347999138;469.45777626841056;500.417014178478;512.4081685653002;541.1720068295514;545.885364073541;3707;3984.2828290907337;4018.598140185958;4026.4564283821896;4152.1695660867945;69177;91911;93137.534312328;94948;95315;63859.36140638627;80659;80684.59657701792;81573;82295.588522057;47268.05463890703;47298.05403891972;47404.525954740704;48100;58179.41820581825;7693.538387696756;7710.190430004769;7809.3362064225175;7923.603819808871;8040.959795200986;43934.78032609817;44493;45349.319760204286;45478.317825231985;46482;62158.37841621526;63621.0456843157;63896.68051659712;64033.679831600544;65123.67438162779;9040.547972601475;9094.499802510925;9192.724218273463;9711.951440242754;9712.75718107053;12026.518939242333;12184.634460966185;12188.329641869783;12311.69220769488;12516.93741531323;11393.145514086642;11423.771524569296;11622;11628.127890408085;11705.824412633987;11013;11061.612505469;11229.550817967516;11288.94355528217;11450.599229026944;9850.211983041196;10696.144308455461;10879.510422031186;11073.280236784583;11121.888781112086;9811.656592019244;9889.604415823287;9953.227501631181;10112.696619101438;10130.544125514221;9687.9031209687;10071.395716257048;10090.106298445395;10102.191824653863;10238.385696858213 01:25:13 INFO - PROCESS | 1184 | __end_tp_report 01:25:13 INFO - PROCESS | 1184 | __start_cc_report 01:25:13 INFO - PROCESS | 1184 | _x_x_mozilla_cycle_collect,926 01:25:13 INFO - PROCESS | 1184 | __end_cc_report 01:25:13 INFO - PROCESS | 1184 | __startTimestamp1474964713175__endTimestamp 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | ------- Summary: start ------- 01:25:13 INFO - PROCESS | 1184 | Number of tests: 6 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#0] dojo.html Cycles:165 Average:3976.01 Median:4141.05 stddev:473.73 (11.4%) stddev-sans-first:457.56 01:25:13 INFO - PROCESS | 1184 | Values: 2344.4 2357.5 2398.5 2408.2 2413.9 3962.0 4099.1 4116.9 4159.4 4178.4 4020.1 4071.6 4087.6 4145.8 4173.5 3460.7 3593.3 3602.2 3615.1 3617.1 3480.7 3499.2 3622.7 3630.6 3679.7 3530.2 3642.3 3674.3 3685.9 3686.9 3493.1 3505.2 3531.3 3585.0 3600.6 3572.6 3626.5 3633.8 3639.5 3641.7 3987.1 4010.0 4093.7 4113.7 4114.0 4165.9 4200.7 4244.7 4273.8 4320.8 4260.2 4341.5 4342.3 4349.2 4353.6 3829.9 3984.2 4020.4 4065.0 4068.3 4284.4 4322.8 4360.1 4429.6 4441.2 4158.4 4272.3 4276.4 4292.7 4296.2 4199.0 4261.6 4346.3 4355.8 4363.0 4160.5 4216.6 4234.7 4298.6 4314.0 4185.5 4221.2 4258.2 4278.6 4306.5 3045.7 3097.6 3099.1 3103.1 3106.9 2793.2 2835.9 2846.3 2847.8 2854.8 3931.3 3969.2 4002.3 4038.3 4064.2 4315.2 4388.8 4391.1 4392.5 4399.6 4021.7 4064.8 4082.1 4141.0 4141.8 3596.0 3657.1 3661.8 3685.8 3736.6 4429.1 4438.1 4486.4 4502.0 4511.5 4314.5 4391.7 4395.2 4397.7 4410.4 4355.0 4399.0 4423.7 4487.3 4502.1 4197.0 4209.4 4225.8 4250.1 4287.5 4237.8 4247.3 4263.7 4313.8 4316.5 4100.9 4111.6 4164.6 4188.9 4222.5 4060.8 4083.3 4109.1 4124.9 4131.9 4050.6 4139.2 4151.8 4155.4 4155.8 4165.5 4186.9 4246.0 4262.6 4265.0 4049.3 4092.0 4116.1 4137.4 4205.5 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#1] ext.html Cycles:180 Average:42064.09 Median:34802.65 stddev:73659.55 (211.6%) stddev-sans-first:73800.14 01:25:13 INFO - PROCESS | 1184 | Values: 520.4 528.7 533.0 549.2 574.1 25041.1 36718.4 36919.3 37462.6 40255.6 38426.0 38590.0 38777.2 38856.4 38859.4 6415.2 6591.2 7523.4 7592.8 7744.5 9424.3 9587.6 9676.0 10006.2 10023.2 9674.9 9706.4 9766.7 9771.1 9787.0 6469.2 6565.1 10036.3 10086.5 10105.6 9089.5 9693.6 9728.6 9861.6 10103.4 35015.1 35591.6 35800.6 35838.6 35842.6 2695.5 2721.0 2747.9 2776.8 2814.5 25480.1 25672.0 26248.3 26676.0 26731.9 3126.7 3191.4 3217.4 3226.4 3286.6 220652.0 221518.0 224527.0 224632.0 224799.0 46866.8 46945.0 47051.8 47152.3 47222.3 26000.7 27175.6 27188.9 48214.0 48936.3 9537.9 9721.5 9836.2 9913.4 9947.3 6094.1 6196.2 6244.6 6325.9 7390.5 13966.9 15262.9 15978.4 16006.4 16511.4 6373.5 6386.6 6472.1 6475.3 6528.5 747.7 833.9 882.3 918.1 998.6 24612.8 25787.2 25858.4 26053.2 26126.3 847.5 896.4 922.5 935.4 969.6 13315.3 13616.2 13777.9 13845.4 13957.8 320980.0 331241.0 332170.0 332205.0 332605.0 38105.0 41885.8 44483.6 44918.0 45099.5 956.6 1052.2 1092.6 1094.9 1096.0 25174.9 26361.0 26427.2 26621.1 26718.3 37832.8 38418.6 38463.8 38465.0 38596.2 253845.0 259776.0 261011.0 306139.0 309147.0 34474.8 34649.8 34955.5 35047.8 35082.0 37478.8 38458.8 38504.0 38866.0 39339.6 32081.4 33040.5 33046.2 34028.7 34514.0 32610.8 32862.0 32885.3 33456.5 34097.5 31825.0 32659.3 32678.2 32694.2 32707.8 27490.6 29708.9 30294.0 30465.2 30618.5 21594.2 23626.9 23754.5 23823.3 23837.9 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#2] jquery.html Cycles:180 Average:22306.16 Median:6026.55 stddev:50379.76 (836.0%) stddev-sans-first:50493.97 01:25:13 INFO - PROCESS | 1184 | Values: 291.5 305.9 314.2 316.1 326.8 11317.1 12082.6 15051.0 15225.2 15525.5 14639.5 15250.6 15254.5 15279.5 15757.6 5725.7 6018.3 6039.2 6051.9 6064.8 3854.7 4118.9 4370.0 5985.6 6034.8 7182.9 7321.2 7337.8 7339.0 7342.3 6871.0 6886.5 7072.0 7266.7 7312.2 6682.9 6905.7 6908.9 6909.3 6911.1 21422.0 21613.7 21613.9 21767.2 22241.3 1741.2 2075.0 2448.8 2467.1 2549.3 13096.0 13627.1 14027.9 14034.9 14805.7 1684.3 1760.5 1776.7 1838.1 1868.8 71947.0 73977.6 74347.6 76229.3 77499.0 15273.9 15289.2 15520.4 15574.8 15602.3 21739.0 22072.1 25013.5 25868.4 25945.6 6508.5 6675.8 6777.3 6851.3 6898.7 5413.4 5414.8 5417.3 5460.3 5562.9 7404.7 7766.1 7813.5 7978.7 8004.4 3162.7 3185.6 3229.3 3240.2 3281.1 755.2 793.2 875.9 889.5 916.7 15170.2 16003.1 16601.3 16653.6 16918.8 766.3 772.6 802.9 827.6 832.1 8947.9 9145.4 9307.5 9413.3 9500.5 288764.0 300596.0 302525.0 304030.0 306097.0 75326.0 75842.0 77414.6 77535.0 78055.2 821.6 824.0 825.4 825.4 828.6 14126.3 14364.0 14454.6 14558.8 14880.3 27930.3 30122.7 35651.8 37757.6 39189.0 48228.0 48756.0 48935.3 49242.5 50480.2 6432.6 6851.6 6873.8 6907.1 6981.1 10045.1 10401.2 10607.0 10705.9 10847.9 8724.6 9368.8 9373.0 9528.9 9738.9 9452.6 9562.4 9625.9 9731.8 9811.8 9496.6 9505.4 9577.3 9670.1 9829.0 6517.0 6573.3 6695.2 6835.2 6923.3 6717.0 9084.2 9086.6 9108.7 9179.8 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#3] mootools.html Cycles:180 Average:4540.62 Median:4517.04 stddev:2602.24 (57.6%) stddev-sans-first:2597.26 01:25:13 INFO - PROCESS | 1184 | Values: 1176.3 1203.3 1239.5 1518.4 1533.9 6743.9 7148.4 7383.4 7390.9 7405.4 5823.0 6134.4 6932.8 7123.8 7154.1 1801.7 1822.5 1838.8 1847.6 1852.9 1859.8 1884.8 1893.7 1907.7 1908.9 1624.7 1663.1 1678.0 1681.8 1822.4 1884.6 1894.2 1903.2 1903.6 1906.7 1897.2 1905.3 1907.8 1926.3 1936.0 6913.4 6914.6 6973.6 7071.4 7092.2 3977.1 3998.9 4101.1 4103.5 4103.7 2579.7 2585.3 2604.3 2622.3 2675.7 839.3 841.0 847.7 866.8 869.1 9226.9 9244.3 9247.9 9281.1 9281.8 3516.3 3584.4 3682.3 3682.6 3682.7 7971.9 7995.9 8006.2 8011.5 8027.0 4095.9 4098.8 4160.6 4172.9 4175.7 3797.8 3807.1 3808.2 3808.2 3818.2 2935.8 3160.0 3187.6 3199.9 3208.6 994.2 1029.0 1042.9 1043.2 1043.4 731.3 764.2 767.1 777.4 792.9 4356.4 4390.0 4511.1 4525.5 4622.6 325.9 327.2 331.3 345.2 347.4 3082.6 3128.2 3133.6 3135.1 3192.9 8270.8 8616.5 8643.7 8684.1 8718.2 8658.3 8666.4 8676.7 8681.2 8685.5 6378.9 6456.5 6634.1 6642.3 6643.4 4275.4 4411.6 4411.9 4509.6 4523.0 7906.8 7997.1 8057.5 8095.3 8102.5 7931.6 8193.1 8238.1 8243.1 8277.1 5970.6 6070.4 6104.5 6109.2 6119.0 6736.1 6834.6 6855.1 6864.3 6877.2 6019.8 6083.3 6092.5 6166.2 6210.0 5906.9 5932.6 6006.1 6049.3 6050.7 5205.7 5210.0 5375.7 5377.5 5377.7 3867.5 3932.6 3941.4 4037.9 4151.6 3913.2 4036.3 4059.7 4125.8 4147.3 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#4] prototype.html Cycles:180 Average:3777.41 Median:4385.84 stddev:1161.96 (26.5%) stddev-sans-first:1134.14 01:25:13 INFO - PROCESS | 1184 | Values: 221.2 240.7 242.2 246.9 247.5 4217.9 4340.6 4430.5 4472.8 4481.7 4376.9 4385.7 4392.4 4395.7 4409.7 3224.3 3244.3 3253.4 3258.3 3258.7 3228.8 3246.6 3262.7 3263.7 3264.1 3220.8 3234.9 3236.0 3237.7 3237.8 3098.8 3200.5 3226.2 3230.7 3231.5 2768.5 2807.5 2822.5 2826.0 2827.4 4172.8 4252.6 4276.5 4278.9 4282.4 3708.8 3767.4 3768.0 3771.1 3787.0 4167.7 4497.4 4501.4 4521.7 4563.2 3051.4 3068.4 3070.7 3074.8 3077.3 4693.6 4724.7 4725.0 4732.4 4740.1 2942.4 3145.2 3147.9 3152.0 3168.9 3141.5 3152.3 3186.4 3197.8 3201.0 3711.2 3713.9 3714.7 3726.1 3736.2 4563.3 4570.8 4571.6 4574.4 4587.9 1096.2 1101.4 1109.3 1110.6 1113.5 688.7 692.3 709.5 710.0 711.8 4295.5 4348.7 4352.7 4378.6 4384.8 4584.5 4597.1 4601.1 4670.5 4674.7 4340.9 4357.3 4365.8 4366.3 4376.0 2372.8 2433.3 2448.5 2473.6 2479.3 4762.6 4801.6 4808.1 4823.2 4828.8 4784.4 4788.6 4793.2 4794.3 4802.3 4752.0 4811.9 4820.8 4826.8 4834.8 4503.3 4696.5 4714.9 4749.6 4772.2 4576.5 4609.7 4650.1 4665.8 4670.6 4863.1 4870.0 4871.3 4875.6 4880.7 3104.7 3118.9 3139.2 3140.8 3158.8 4472.8 4564.2 4584.5 4716.0 4727.5 4469.9 4503.8 4523.8 4535.4 4536.7 4527.6 4531.3 4535.1 4535.3 4542.8 4386.0 4401.5 4446.8 4456.7 4466.8 4299.7 4508.5 4557.2 4577.2 4590.2 4412.9 4429.4 4469.6 4505.7 4513.2 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - PROCESS | 1184 | [#5] yui.html Cycles:180 Average:16508.68 Median:47351.29 stddev:23093.38 (48.8%) stddev-sans-first:23127.21 01:25:13 INFO - PROCESS | 1184 | Values: 586.4 587.5 604.5 624.4 680.4 12594.4 13384.4 13880.7 14017.0 14061.1 12343.0 12395.9 12556.6 12578.7 12642.9 642.3 654.2 661.1 663.5 665.7 508.4 521.0 624.6 637.7 644.7 681.7 683.7 693.5 699.9 701.6 687.2 696.5 696.7 697.4 698.3 391.4 398.1 492.3 504.6 508.8 12357.4 12702.0 12723.9 12767.4 12786.6 11617.8 11693.0 11738.7 11775.8 12433.3 11391.0 11481.5 11568.4 11582.6 11604.5 3945.4 4047.7 4054.1 4104.0 4179.8 59291.4 62570.7 63761.7 64890.7 65441.7 9610.8 9649.3 9671.5 9767.3 10054.5 16899.7 17936.9 17942.4 17959.9 17970.5 6371.6 6374.6 6382.6 6429.7 6510.1 3501.4 3516.0 3523.1 3576.5 3647.8 2559.1 2585.1 2651.4 2654.5 2659.8 437.4 453.2 455.6 457.5 460.3 492.1 510.0 549.7 552.3 556.4 7282.3 7283.6 7299.5 7563.2 7777.5 469.5 500.4 512.4 541.2 545.9 3707.0 3984.3 4018.6 4026.5 4152.2 69177.0 91911.0 93137.5 94948.0 95315.0 63859.4 80659.0 80684.6 81573.0 82295.6 47268.1 47298.1 47404.5 48100.0 58179.4 7693.5 7710.2 7809.3 7923.6 8041.0 43934.8 44493.0 45349.3 45478.3 46482.0 62158.4 63621.0 63896.7 64033.7 65123.7 9040.5 9094.5 9192.7 9712.0 9712.8 12026.5 12184.6 12188.3 12311.7 12516.9 11393.1 11423.8 11622.0 11628.1 11705.8 11013.0 11061.6 11229.6 11288.9 11450.6 9850.2 10696.1 10879.5 11073.3 11121.9 9811.7 9889.6 9953.2 10112.7 10130.5 9687.9 10071.4 10090.1 10102.2 10238.4 01:25:13 INFO - PROCESS | 1184 | -------- Summary: end -------- 01:25:13 INFO - PROCESS | 1184 | 01:25:13 INFO - TEST-INFO | 1184: exit 0 01:25:14 INFO - TEST-OK | dromaeo_css | took 1124635ms 01:25:14 INFO - TEST-START | kraken 01:25:14 INFO - operating with platform_type : w7_ 01:25:14 INFO - Initialising browser for kraken test... 01:25:14 INFO - TEST-INFO | started process 3952 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpm8hmit\profile http://localhost:49890/getInfo.html) 01:25:19 INFO - PROCESS | 3952 | __metrics Screen width/height:1600/1200 01:25:19 INFO - PROCESS | 3952 | colorDepth:24 01:25:19 INFO - PROCESS | 3952 | Browser inner width/height: 1010/674 01:25:19 INFO - PROCESS | 3952 | __metrics 01:25:20 INFO - PROCESS | 3952 | 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" 01:25:20 INFO - PROCESS | 3952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:25:20 INFO - PROCESS | 3952 | console.error: 01:25:20 INFO - PROCESS | 3952 | Message: Error: Connection closed before committing the transaction. 01:25:20 INFO - PROCESS | 3952 | Stack: 01:25:20 INFO - PROCESS | 3952 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 01:25:20 INFO - PROCESS | 3952 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 01:25:20 INFO - PROCESS | 3952 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 01:25:20 INFO - PROCESS | 3952 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 01:25:20 INFO - PROCESS | 3952 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 01:25:20 INFO - PROCESS | 3952 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 01:25:20 INFO - PROCESS | 3952 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 01:25:20 INFO - PROCESS | 3952 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 01:25:20 INFO - PROCESS | 3952 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 01:25:20 INFO - PROCESS | 3952 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 01:25:20 INFO - PROCESS | 3952 | Barrier/this.client.addBlocker/promise13.1% 01:26:21 INFO - TinderboxPrint: I/O read bytes / time
81,585,664 / 1,135,110 01:26:21 INFO - TinderboxPrint: I/O write bytes / time
476,665,856 / 3,611,900 01:26:21 INFO - TinderboxPrint: CPU idle
8,304.9 (86.9%) 01:26:21 INFO - TinderboxPrint: CPU user
1,221.2 (12.8%) 01:26:21 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 34857984; Read time: 0; Write time: 236310 01:26:21 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 81585664; Write bytes: 403027456; Read time: 1135110; Write time: 3336710 01:26:21 INFO - Running post-run listener: _upload_blobber_files 01:26:21 INFO - Blob upload gear active. 01:26:21 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 01:26:21 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 01:26:21 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-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 01:26:21 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-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 01:26:22 INFO - (blobuploader) - INFO - Open directory for files ... 01:26:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 01:26:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:26:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:26:23 INFO - (blobuploader) - INFO - Done attempting. 01:26:23 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 01:26:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:26:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:26:24 INFO - (blobuploader) - INFO - Done attempting. 01:26:24 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 01:26:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:26:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:26:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:26:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:26:32 INFO - (blobuploader) - INFO - Done attempting. 01:26:32 INFO - (blobuploader) - INFO - Iteration through files over. 01:26:32 INFO - Return code: 0 01:26:32 INFO - rmtree: C:\slave\test\build\uploaded_files.json 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 01:26:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e66ce1f15e5117bd4fab71522d45854e40304bbf0d0d2bbc523a51c77bc559cfab81baaa21bdfda612093b9f8858a5104ef125cf8161513ed5061706acd803d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7016576452fc0de4a48c2b940dfdc699e6f8646d356b580ff1c61969d66d4f63ddc2bf45ce21a90743af768e9e90ddea3480fcf8310a2bce7c765bbf992180f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0297d2417f696db4d1e73ef28428c12badeff7a7e7f5a270a81bee93606c24eb4e5201c931bc9a7caa06646dbc0f0c5511f217ef9da4644aacb30f35b01ae100"} 01:26:32 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 01:26:32 INFO - Writing to file C:\slave\test\properties\blobber_files 01:26:32 INFO - Contents: 01:26:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6e66ce1f15e5117bd4fab71522d45854e40304bbf0d0d2bbc523a51c77bc559cfab81baaa21bdfda612093b9f8858a5104ef125cf8161513ed5061706acd803d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7016576452fc0de4a48c2b940dfdc699e6f8646d356b580ff1c61969d66d4f63ddc2bf45ce21a90743af768e9e90ddea3480fcf8310a2bce7c765bbf992180f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0297d2417f696db4d1e73ef28428c12badeff7a7e7f5a270a81bee93606c24eb4e5201c931bc9a7caa06646dbc0f0c5511f217ef9da4644aacb30f35b01ae100"} 01:26:32 INFO - Running post-run listener: copy_logs_to_upload_dir 01:26:32 INFO - Copying logs to upload dir... 01:26:32 INFO - mkdir: C:\slave\test\build\upload\logs 01:26:32 INFO - Copying logs to upload dir... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... 01:26:32 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1476.504000 ========= master_lag: -0.12 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 36 secs) (at 2016-09-27 01:26:32.659459) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:32.660291) ========= '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-148 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-148 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-148 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-Non-PGO/sha512/6e66ce1f15e5117bd4fab71522d45854e40304bbf0d0d2bbc523a51c77bc559cfab81baaa21bdfda612093b9f8858a5104ef125cf8161513ed5061706acd803d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7016576452fc0de4a48c2b940dfdc699e6f8646d356b580ff1c61969d66d4f63ddc2bf45ce21a90743af768e9e90ddea3480fcf8310a2bce7c765bbf992180f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0297d2417f696db4d1e73ef28428c12badeff7a7e7f5a270a81bee93606c24eb4e5201c931bc9a7caa06646dbc0f0c5511f217ef9da4644aacb30f35b01ae100"} build_url:https://queue.taskcluster.net/v1/task/NgLEHPZsQaGuqKwd4tJ_rQ/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/NgLEHPZsQaGuqKwd4tJ_rQ/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-Non-PGO/sha512/6e66ce1f15e5117bd4fab71522d45854e40304bbf0d0d2bbc523a51c77bc559cfab81baaa21bdfda612093b9f8858a5104ef125cf8161513ed5061706acd803d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7016576452fc0de4a48c2b940dfdc699e6f8646d356b580ff1c61969d66d4f63ddc2bf45ce21a90743af768e9e90ddea3480fcf8310a2bce7c765bbf992180f4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0297d2417f696db4d1e73ef28428c12badeff7a7e7f5a270a81bee93606c24eb4e5201c931bc9a7caa06646dbc0f0c5511f217ef9da4644aacb30f35b01ae100"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:32.779357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:32.780113) ========= '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-148 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-148 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-148 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:32.891967) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:32.892295) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 01:26:33.459156) ========= ========= Total master_lag: 0.12 =========