builder: mozilla-central_win7_ix_test-dromaeojs slave: t-w732-ix-125 starttime: 1477933149.18 results: success (0) buildid: 20161031083622 builduid: 4e00c69b79b9468ebd92ebea407fa8cd revision: a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.175455) ========= master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.176013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.176332) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.194667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.195099) ========= '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-125 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-125 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-125 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 --09:59:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 7.72 MB/s 09:59:09 (7.72 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.468000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:09.674578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-31 09:59:09.674935) ========= '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-125 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-125 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-125 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=16.603000 ========= master_lag: 1.33 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-31 09:59:27.604110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 09:59:27.604458) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-125 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-125 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-125 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-10-31 09:59:27,753 truncating revision to first 12 chars 2016-10-31 09:59:27,753 Setting DEBUG logging. 2016-10-31 09:59:27,753 attempt 1/10 2016-10-31 09:59:27,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a10837dc8cdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 09:59:28,411 unpacking tar archive at: mozilla-central-a10837dc8cdf/testing/mozharness/ 2016-10-31 09:59:28,542 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.505000 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-31 09:59:29.185780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:29.186173) ========= script_repo_revision: a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:29.186578) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:29.186857) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 09:59:29.197105) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-10-31 09:59:29.197425) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-125 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-125 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-125 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 09:59:29 INFO - MultiFileLogger online at 20161031 09:59:29 in C:\slave\test 09:59:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:59:29 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 09:59:29 INFO - {'append_to_log': False, 09:59:29 INFO - 'base_work_dir': 'C:\\slave\\test', 09:59:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:59:29 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 09:59:29 INFO - 'branch': 'Firefox-Non-PGO', 09:59:29 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:29 INFO - 'config_files': ('talos/windows_config.py',), 09:59:29 INFO - 'default_actions': ('clobber', 09:59:29 INFO - 'read-buildbot-config', 09:59:29 INFO - 'download-and-extract', 09:59:29 INFO - 'populate-webroot', 09:59:29 INFO - 'create-virtualenv', 09:59:29 INFO - 'install', 09:59:29 INFO - 'run-tests'), 09:59:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:29 INFO - 'download_minidump_stackwalk': True, 09:59:29 INFO - 'download_symbols': 'ondemand', 09:59:29 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 09:59:29 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 09:59:29 INFO - 'hg': 'c:/mozilla-build/hg/hg', 09:59:29 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 09:59:29 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 09:59:29 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 09:59:29 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 09:59:29 INFO - 'C:/mozilla-build/tooltool.py'), 09:59:29 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 09:59:29 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 09:59:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:29 INFO - 'installer_path': 'installer.exe', 09:59:29 INFO - 'log_level': 'info', 09:59:29 INFO - 'log_name': 'talos', 09:59:29 INFO - 'log_to_console': True, 09:59:29 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 09:59:29 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 09:59:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 09:59:29 INFO - 'opt_config_files': (), 09:59:29 INFO - 'pip_index': False, 09:59:29 INFO - 'sps_profile': False, 09:59:29 INFO - 'sps_profile_interval': 0, 09:59:29 INFO - 'suite': 'dromaeojs', 09:59:29 INFO - 'system_bits': '32', 09:59:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:29 INFO - 'title': 'T-W732-IX-125', 09:59:29 INFO - 'use_talos_json': True, 09:59:29 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 09:59:29 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 09:59:29 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 09:59:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:29 INFO - 'work_dir': 'build'} 09:59:29 INFO - [mozharness: 2016-10-31 16:59:29.584000Z] Running clobber step. 09:59:29 INFO - Running pre-action listener: _resource_record_pre_action 09:59:29 INFO - Running main action method: clobber 09:59:29 INFO - rmtree: C:\slave\test\build 09:59:29 INFO - Using _rmtree_windows ... 09:59:29 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 10:00:58 INFO - Running post-action listener: _resource_record_post_action 10:00:58 INFO - [mozharness: 2016-10-31 17:00:58.758000Z] Finished clobber step (success) 10:00:58 INFO - [mozharness: 2016-10-31 17:00:58.758000Z] Running read-buildbot-config step. 10:00:58 INFO - Running pre-action listener: _resource_record_pre_action 10:00:58 INFO - Running main action method: read_buildbot_config 10:00:58 INFO - Using buildbot properties: 10:00:58 INFO - { 10:00:58 INFO - "project": "", 10:00:58 INFO - "product": "firefox", 10:00:58 INFO - "script_repo_revision": "production", 10:00:58 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 10:00:58 INFO - "repository": "", 10:00:58 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 10:00:58 INFO - "buildid": "20161031083622", 10:00:58 INFO - "pgo_build": "False", 10:00:58 INFO - "basedir": "C:\\slave\\test", 10:00:58 INFO - "buildnumber": 20, 10:00:58 INFO - "slavename": "t-w732-ix-125", 10:00:58 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/", 10:00:58 INFO - "platform": "win7_ix", 10:00:58 INFO - "scheduler": "tests-mozilla-central-win32-talos", 10:00:58 INFO - "branch": "mozilla-central", 10:00:58 INFO - "repo_path": "mozilla-central", 10:00:58 INFO - "revision": "a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b", 10:00:58 INFO - "stage_platform": "win32", 10:00:58 INFO - "builduid": "4e00c69b79b9468ebd92ebea407fa8cd", 10:00:58 INFO - "slavebuilddir": "test" 10:00:58 INFO - } 10:00:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 10:00:58 INFO - Running post-action listener: _resource_record_post_action 10:00:58 INFO - [mozharness: 2016-10-31 17:00:58.762000Z] Finished read-buildbot-config step (success) 10:00:58 INFO - [mozharness: 2016-10-31 17:00:58.762000Z] Running download-and-extract step. 10:00:58 INFO - Running pre-action listener: _resource_record_pre_action 10:00:58 INFO - Running main action method: download_and_extract 10:00:58 INFO - mkdir: C:\slave\test\build\tests 10:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:58 INFO - trying https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 10:00:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/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 10:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/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 10:00:59 INFO - Downloaded 1274 bytes. 10:00:59 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 10:00:59 INFO - Using the following test package requirements: 10:00:59 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:00:59 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 10:00:59 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 10:00:59 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'jsshell-win32.zip'], 10:00:59 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 10:00:59 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:00:59 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 10:00:59 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 10:00:59 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 10:00:59 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:00:59 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 10:00:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 10:00:59 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:00:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:00:59 INFO - Fetch https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:01:00 INFO - Http code: 200 10:01:00 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 10:01:00 INFO - x-amz-cf-id: W0asa9LZ84AE2MNZtRaOD_IQmDCa8JvCUkLDi32564jPF5PmT9F5Fg== 10:01:00 INFO - x-amz-version-id: SbysmoSdO9eD2OslbOZmZY1.J.UYZJJP 10:01:02 INFO - Expected file size: 17148906 10:01:02 INFO - Obtained file size: 17148906 10:01:37 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 10:01:37 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:01:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:01:37 INFO - Fetch https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:01:37 INFO - Http code: 200 10:01:37 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 10:01:37 INFO - x-amz-cf-id: YZnAtIFEcn8lnVO0fpahDQ-TAB80-OFRX9k8MPFHFxUZX7rOxovaTQ== 10:01:37 INFO - x-amz-version-id: SbysmoSdO9eD2OslbOZmZY1.J.UYZJJP 10:01:39 INFO - Expected file size: 17148906 10:01:39 INFO - Obtained file size: 17148906 10:01:49 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 10:01:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 10:01:49 INFO - Fetch https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 10:01:49 INFO - Http code: 200 10:01:49 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 10:01:49 INFO - x-amz-cf-id: RFQPetOIyfza2yix3fEVXzjoTfVWIUafzDL1-S3Vx-XHMRpv67fjkQ== 10:01:49 INFO - x-amz-version-id: GUivhIJBQMgKdR1RVf0kXeIpp0XHowpP 10:01:50 INFO - Expected file size: 14409038 10:01:50 INFO - Obtained file size: 14409038 10:01: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')]} 10:01:51 INFO - trying https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:01:51 INFO - Downloading https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 10:01:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/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 10:01:53 INFO - Downloaded 53057568 bytes. 10:01:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:01:53 INFO - mkdir: C:\slave\test\properties 10:01:53 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 10:01:53 INFO - Writing to file C:\slave\test\properties\build_url 10:01:53 INFO - Contents: 10:01:53 INFO - build_url:https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:01:54 INFO - Running post-action listener: _resource_record_post_action 10:01:54 INFO - Running post-action listener: set_extra_try_arguments 10:01:54 INFO - [mozharness: 2016-10-31 17:01:54.381000Z] Finished download-and-extract step (success) 10:01:54 INFO - [mozharness: 2016-10-31 17:01:54.381000Z] Running populate-webroot step. 10:01:54 INFO - Running pre-action listener: _resource_record_pre_action 10:01:54 INFO - Running main action method: populate_webroot 10:01:54 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 10:01:54 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 10:01:54 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 10:01:54 INFO - u'--tppagecycles', 10:01:54 INFO - u'7'], 10:01:54 INFO - u'tests': [u'tsvgm']}}, 10:01:54 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 10:01:54 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 10:01:54 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 10:01:54 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 10:01:54 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:01:54 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:01:54 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'damp', u'tps']}, 10:01:54 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'tests': [u'damp', u'tps']}, 10:01:54 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'dromaeo_dom']}, 10:01:54 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 10:01:54 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'basic_compositor_video']}, 10:01:54 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 10:01:54 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'other-e10s': {u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'a11yr', 10:01:54 INFO - u'ts_paint', 10:01:54 INFO - u'tpaint', 10:01:54 INFO - u'sessionrestore', 10:01:54 INFO - u'sessionrestore_no_auto_restore', 10:01:54 INFO - u'tabpaint']}, 10:01:54 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'tsvgx', 10:01:54 INFO - u'tsvgr_opacity', 10:01:54 INFO - u'tart', 10:01:54 INFO - u'tscrollx', 10:01:54 INFO - u'cart']}, 10:01:54 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 10:01:54 INFO - u'tsvgr_opacity', 10:01:54 INFO - u'tart', 10:01:54 INFO - u'tscrollx', 10:01:54 INFO - u'cart']}, 10:01:54 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'talos_options': [u'--disable-e10s'], 10:01:54 INFO - u'tests': [u'tp5o']}, 10:01:54 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'tests': [u'tp5o']}, 10:01:54 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'talos_options': [u'--disable-e10s', 10:01:54 INFO - u'--xperf_path', 10:01:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:54 INFO - u'tests': [u'tp5n']}, 10:01:54 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:01:54 INFO - u'talos_options': [u'--xperf_path', 10:01:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:54 INFO - u'tests': [u'tp5n']}}, 10:01:54 INFO - u'talos.zip': {u'path': u'', 10:01:54 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:01:54 INFO - Running post-action listener: _resource_record_post_action 10:01:54 INFO - [mozharness: 2016-10-31 17:01:54.409000Z] Finished populate-webroot step (success) 10:01:54 INFO - [mozharness: 2016-10-31 17:01:54.409000Z] Running create-virtualenv step. 10:01:54 INFO - Running pre-action listener: _resource_record_pre_action 10:01:54 INFO - Running main action method: create_virtualenv 10:01:54 INFO - Creating virtualenv C:\slave\test\build/venv 10:01:54 INFO - mkdir: C:\slave\test\build\venv\Scripts 10:01:54 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 10:01:54 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'! 10:01:54 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 10:01:54 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 10:02:11 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 10:02:15 INFO - Installing distribute......................................................................................................................................................................................done. 10:02:16 INFO - Return code: 0 10:02:16 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 10:02:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 10:02:17 INFO - Reading from file tmpfile_stdout 10:02:17 INFO - Output received: 10:02:17 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 10:02:17 INFO - Using _rmtree_windows ... 10:02:17 INFO - Using _rmtree_windows ... 10:02:17 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 10:02:17 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')]} 10:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:17 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')]} 10:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:17 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:17 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 10:02:17 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 10:02:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:17 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:17 INFO - 'DCLOCATION': 'SCL3', 10:02:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:17 INFO - 'HOMEDRIVE': 'C:', 10:02:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:17 INFO - 'KTS_VERSION': '1.19c', 10:02:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:17 INFO - 'MOZ_NO_REMOTE': '1', 10:02:17 INFO - 'NO_EM_RESTART': '1', 10:02:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:17 INFO - 'OS': 'Windows_NT', 10:02:17 INFO - 'OURDRIVE': 'C:', 10:02:17 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;', 10:02:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:17 INFO - 'PROCESSOR_LEVEL': '6', 10:02:17 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:17 INFO - 'PROMPT': '$P$G', 10:02:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:17 INFO - 'PWD': 'C:\\slave\\test', 10:02:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:17 INFO - 'SYSTEMDRIVE': 'C:', 10:02:17 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:17 INFO - 'TEST1': 'testie', 10:02:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:17 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:17 INFO - 'USERNAME': 'cltbld', 10:02:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:17 INFO - 'WINDIR': 'C:\\windows', 10:02:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:21 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:02:21 INFO - Downloading/unpacking pip>=1.5 10:02:25 INFO - Running setup.py egg_info for package pip 10:02:25 INFO - warning: no files found matching 'pip\cacert.pem' 10:02:25 INFO - warning: no files found matching '*.html' under directory 'docs' 10:02:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:02:25 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:02:25 INFO - Installing collected packages: pip 10:02:25 INFO - Found existing installation: pip 0.8.2 10:02:25 INFO - Uninstalling pip: 10:02:25 INFO - Successfully uninstalled pip 10:02:25 INFO - Running setup.py install for pip 10:02:25 INFO - warning: no files found matching 'pip\cacert.pem' 10:02:25 INFO - warning: no files found matching '*.html' under directory 'docs' 10:02:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:02:25 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:02:25 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 10:02:25 INFO - Successfully installed pip 10:02:25 INFO - Cleaning up... 10:02:25 INFO - Return code: 0 10:02:25 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 10:02:25 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')]} 10:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:25 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')]} 10:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:25 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:25 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 10:02:25 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 10:02:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:25 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:25 INFO - 'DCLOCATION': 'SCL3', 10:02:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:25 INFO - 'HOMEDRIVE': 'C:', 10:02:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:25 INFO - 'KTS_VERSION': '1.19c', 10:02:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:25 INFO - 'MOZ_NO_REMOTE': '1', 10:02:25 INFO - 'NO_EM_RESTART': '1', 10:02:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:25 INFO - 'OS': 'Windows_NT', 10:02:25 INFO - 'OURDRIVE': 'C:', 10:02:25 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;', 10:02:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:25 INFO - 'PROCESSOR_LEVEL': '6', 10:02:25 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:25 INFO - 'PROMPT': '$P$G', 10:02:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:25 INFO - 'PWD': 'C:\\slave\\test', 10:02:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:25 INFO - 'SYSTEMDRIVE': 'C:', 10:02:25 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:25 INFO - 'TEST1': 'testie', 10:02:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:25 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:25 INFO - 'USERNAME': 'cltbld', 10:02:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:25 INFO - 'WINDIR': 'C:\\windows', 10:02:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:27 INFO - Downloading/unpacking psutil>=3.1.1 10:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:28 INFO - Installing collected packages: psutil 10:02:28 INFO - Successfully installed psutil 10:02:28 INFO - Cleaning up... 10:02:28 INFO - Return code: 0 10:02:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 10:02:28 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')]} 10:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:28 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')]} 10:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:28 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 10:02:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:02:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:29 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:29 INFO - 'DCLOCATION': 'SCL3', 10:02:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:29 INFO - 'HOMEDRIVE': 'C:', 10:02:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:29 INFO - 'KTS_VERSION': '1.19c', 10:02:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:29 INFO - 'MOZ_NO_REMOTE': '1', 10:02:29 INFO - 'NO_EM_RESTART': '1', 10:02:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:29 INFO - 'OS': 'Windows_NT', 10:02:29 INFO - 'OURDRIVE': 'C:', 10:02:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:02:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:29 INFO - 'PROCESSOR_LEVEL': '6', 10:02:29 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:29 INFO - 'PROMPT': '$P$G', 10:02:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:29 INFO - 'PWD': 'C:\\slave\\test', 10:02:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:29 INFO - 'SYSTEMDRIVE': 'C:', 10:02:29 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:29 INFO - 'TEST1': 'testie', 10:02:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:29 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:29 INFO - 'USERNAME': 'cltbld', 10:02:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:29 INFO - 'WINDIR': 'C:\\windows', 10:02:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:02:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 10:02:33 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) 10:02:33 INFO - Installing collected packages: mozsystemmonitor 10:02:33 INFO - Running setup.py install for mozsystemmonitor 10:02:33 INFO - Successfully installed mozsystemmonitor 10:02:33 INFO - Cleaning up... 10:02:33 INFO - Return code: 0 10:02:33 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 10:02:33 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')]} 10:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:33 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')]} 10:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:33 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:33 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 10:02:33 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 10:02:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:33 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:33 INFO - 'DCLOCATION': 'SCL3', 10:02:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:33 INFO - 'HOMEDRIVE': 'C:', 10:02:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:33 INFO - 'KTS_VERSION': '1.19c', 10:02:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:33 INFO - 'MOZ_NO_REMOTE': '1', 10:02:33 INFO - 'NO_EM_RESTART': '1', 10:02:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:33 INFO - 'OS': 'Windows_NT', 10:02:33 INFO - 'OURDRIVE': 'C:', 10:02:33 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;', 10:02:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:33 INFO - 'PROCESSOR_LEVEL': '6', 10:02:33 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:33 INFO - 'PROMPT': '$P$G', 10:02:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:33 INFO - 'PWD': 'C:\\slave\\test', 10:02:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:33 INFO - 'SYSTEMDRIVE': 'C:', 10:02:33 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:33 INFO - 'TEST1': 'testie', 10:02:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:33 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:33 INFO - 'USERNAME': 'cltbld', 10:02:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:33 INFO - 'WINDIR': 'C:\\windows', 10:02:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:34 INFO - Downloading/unpacking jsonschema==2.5.1 10:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:02:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:35 INFO - Downloading functools32-3.2.3-2.tar.gz 10:02:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 10:02:35 INFO - warning: no files found matching '*.txt' 10:02:35 INFO - no previously-included directories found matching 'build' 10:02:35 INFO - no previously-included directories found matching 'dist' 10:02:35 INFO - no previously-included directories found matching '.git*' 10:02:35 INFO - Installing collected packages: jsonschema, functools32 10:02:35 INFO - Running setup.py install for functools32 10:02:35 INFO - warning: no files found matching '*.txt' 10:02:35 INFO - no previously-included directories found matching 'build' 10:02:35 INFO - no previously-included directories found matching 'dist' 10:02:35 INFO - no previously-included directories found matching '.git*' 10:02:35 INFO - Successfully installed jsonschema functools32 10:02:35 INFO - Cleaning up... 10:02:36 INFO - Return code: 0 10:02:36 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 10:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 10:02:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:02:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:36 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:36 INFO - 'DCLOCATION': 'SCL3', 10:02:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:36 INFO - 'HOMEDRIVE': 'C:', 10:02:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:36 INFO - 'KTS_VERSION': '1.19c', 10:02:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:36 INFO - 'MOZ_NO_REMOTE': '1', 10:02:36 INFO - 'NO_EM_RESTART': '1', 10:02:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:36 INFO - 'OS': 'Windows_NT', 10:02:36 INFO - 'OURDRIVE': 'C:', 10:02:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:02:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:36 INFO - 'PROCESSOR_LEVEL': '6', 10:02:36 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:36 INFO - 'PROMPT': '$P$G', 10:02:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:36 INFO - 'PWD': 'C:\\slave\\test', 10:02:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:36 INFO - 'SYSTEMDRIVE': 'C:', 10:02:36 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:36 INFO - 'TEST1': 'testie', 10:02:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:36 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:36 INFO - 'USERNAME': 'cltbld', 10:02:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:36 INFO - 'WINDIR': 'C:\\windows', 10:02:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 10:02:37 INFO - Cleaning up... 10:02:37 INFO - Return code: 0 10:02:37 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 10:02:37 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')]} 10:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:37 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')]} 10:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:37 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:37 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 10:02:37 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 10:02:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:37 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:37 INFO - 'DCLOCATION': 'SCL3', 10:02:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:37 INFO - 'HOMEDRIVE': 'C:', 10:02:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:37 INFO - 'KTS_VERSION': '1.19c', 10:02:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:37 INFO - 'MOZ_NO_REMOTE': '1', 10:02:37 INFO - 'NO_EM_RESTART': '1', 10:02:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:37 INFO - 'OS': 'Windows_NT', 10:02:37 INFO - 'OURDRIVE': 'C:', 10:02:37 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;', 10:02:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:37 INFO - 'PROCESSOR_LEVEL': '6', 10:02:37 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:37 INFO - 'PROMPT': '$P$G', 10:02:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:37 INFO - 'PWD': 'C:\\slave\\test', 10:02:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:37 INFO - 'SYSTEMDRIVE': 'C:', 10:02:37 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:37 INFO - 'TEST1': 'testie', 10:02:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:37 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:37 INFO - 'USERNAME': 'cltbld', 10:02:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:37 INFO - 'WINDIR': 'C:\\windows', 10:02:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:37 INFO - Downloading/unpacking blobuploader==1.2.4 10:02:37 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 10:02:37 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 10:02:38 INFO - Downloading blobuploader-1.2.4.tar.gz 10:02:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 10:02:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:02:38 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 10:02:38 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 10:02:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 10:02:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:02:39 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 10:02:39 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 10:02:39 INFO - Downloading docopt-0.6.1.tar.gz 10:02:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 10:02:39 INFO - Installing collected packages: blobuploader, requests, docopt 10:02:39 INFO - Running setup.py install for blobuploader 10:02:39 INFO - Running setup.py install for requests 10:02:40 INFO - Running setup.py install for docopt 10:02:40 INFO - Successfully installed blobuploader requests docopt 10:02:40 INFO - Cleaning up... 10:02:41 INFO - Return code: 0 10:02:41 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:02:41 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')]} 10:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:41 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')]} 10:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:41 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:41 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 10:02:41 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 10:02:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:41 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:41 INFO - 'DCLOCATION': 'SCL3', 10:02:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:41 INFO - 'HOMEDRIVE': 'C:', 10:02:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:41 INFO - 'KTS_VERSION': '1.19c', 10:02:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:41 INFO - 'MOZ_NO_REMOTE': '1', 10:02:41 INFO - 'NO_EM_RESTART': '1', 10:02:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:41 INFO - 'OS': 'Windows_NT', 10:02:41 INFO - 'OURDRIVE': 'C:', 10:02:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:02:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:41 INFO - 'PROCESSOR_LEVEL': '6', 10:02:41 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:41 INFO - 'PROMPT': '$P$G', 10:02:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:41 INFO - 'PWD': 'C:\\slave\\test', 10:02:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:41 INFO - 'SYSTEMDRIVE': 'C:', 10:02:41 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:41 INFO - 'TEST1': 'testie', 10:02:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:41 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:41 INFO - 'USERNAME': 'cltbld', 10:02:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:41 INFO - 'WINDIR': 'C:\\windows', 10:02:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:02:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uwgsxf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crwe7s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ts4o8n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3nyo6c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pnghl_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gyb4o7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jjepiq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cwrwy7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:02:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:02:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tcspnl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-paamez-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqvp7p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-io_lr_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xsavec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e2igzx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rlshya-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ny0hz3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:02:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:02:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vgtjbn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:02:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:02:44 INFO - Running setup.py install for manifestparser 10:02:44 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 10:02:44 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 10:02:44 INFO - Running setup.py install for mozcrash 10:02:44 INFO - Running setup.py install for mozdebug 10:02:45 INFO - Running setup.py install for mozdevice 10:02:45 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 10:02:45 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 10:02:45 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 10:02:45 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 10:02:45 INFO - Running setup.py install for mozfile 10:02:46 INFO - Running setup.py install for mozhttpd 10:02:46 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 10:02:46 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 10:02:46 INFO - Running setup.py install for mozinfo 10:02:46 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 10:02:46 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Running setup.py install for mozInstall 10:02:47 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 10:02:47 INFO - Running setup.py install for mozleak 10:02:47 INFO - Running setup.py install for mozlog 10:02:48 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 10:02:48 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 10:02:49 INFO - Running setup.py install for moznetwork 10:02:49 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 10:02:49 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 10:02:49 INFO - Running setup.py install for mozprocess 10:02:49 INFO - Running setup.py install for mozprofile 10:02:50 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Running setup.py install for mozrunner 10:02:50 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 10:02:50 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 10:02:51 INFO - Running setup.py install for mozscreenshot 10:02:51 INFO - Running setup.py install for moztest 10:02:51 INFO - Running setup.py install for mozversion 10:02:52 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 10:02:52 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 10:02:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:02:52 INFO - Cleaning up... 10:02:52 INFO - Return code: 0 10:02:52 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:02:52 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')]} 10:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:52 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')]} 10:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:52 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:02:52 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 10:02:52 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 10:02:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:02:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:02:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:02:52 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:02:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:02:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:02:52 INFO - 'DCLOCATION': 'SCL3', 10:02:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:02:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:02:52 INFO - 'HOMEDRIVE': 'C:', 10:02:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:02:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:02:52 INFO - 'KTS_VERSION': '1.19c', 10:02:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:02:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:02:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:02:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:52 INFO - 'MOZ_NO_REMOTE': '1', 10:02:52 INFO - 'NO_EM_RESTART': '1', 10:02:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:02:52 INFO - 'OS': 'Windows_NT', 10:02:52 INFO - 'OURDRIVE': 'C:', 10:02:52 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;', 10:02:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:02:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:02:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:02:52 INFO - 'PROCESSOR_LEVEL': '6', 10:02:52 INFO - 'PROCESSOR_REVISION': '1e05', 10:02:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:02:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:02:52 INFO - 'PROMPT': '$P$G', 10:02:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:02:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:02:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:02:52 INFO - 'PWD': 'C:\\slave\\test', 10:02:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:02:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:02:52 INFO - 'SYSTEMDRIVE': 'C:', 10:02:52 INFO - 'SYSTEMROOT': 'C:\\windows', 10:02:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:52 INFO - 'TEST1': 'testie', 10:02:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:02:52 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:02:52 INFO - 'USERNAME': 'cltbld', 10:02:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:02:52 INFO - 'WINDIR': 'C:\\windows', 10:02:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:02:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:02:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mdtang-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ec1pjz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ooetng-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xxmxup-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c7qrxz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fsyoac-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vz9u_b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6cflew-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:02:53 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)) 10:02:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:02:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-urzgb1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ojhoqm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zrhyyg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xvdycb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fsje6c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1b9rsw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:02:54 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)) 10:02:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:02:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fas2hu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:02:55 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)) 10:02:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:02:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gz_yvg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:02:55 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)) 10:02:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:02:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vsgln0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:02:55 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)) 10:02:55 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)) 10:02:55 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)) 10:02:55 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)) 10:02:55 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)) 10:02:55 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)) 10:02:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 10:02:55 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 10:02:55 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 10:02:56 INFO - Downloading blessings-1.6.tar.gz 10:02:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 10:02:56 INFO - Installing collected packages: blessings 10:02:56 INFO - Running setup.py install for blessings 10:02:57 INFO - Successfully installed blessings 10:02:57 INFO - Cleaning up... 10:02:59 INFO - Return code: 0 10:02:59 INFO - Done creating virtualenv C:\slave\test\build/venv. 10:02:59 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:02:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:03:00 INFO - Reading from file tmpfile_stdout 10:03:00 INFO - Using _rmtree_windows ... 10:03:00 INFO - Using _rmtree_windows ... 10:03:00 INFO - Current package versions: 10:03:00 INFO - blessings == 1.6 10:03:00 INFO - blobuploader == 1.2.4 10:03:00 INFO - docopt == 0.6.1 10:03:00 INFO - functools32 == 3.2.3-2 10:03:00 INFO - jsonschema == 2.5.1 10:03:00 INFO - manifestparser == 1.1 10:03:00 INFO - mozInstall == 1.12 10:03:00 INFO - mozcrash == 0.17 10:03:00 INFO - mozdebug == 0.1 10:03:00 INFO - mozdevice == 0.48 10:03:00 INFO - mozfile == 1.2 10:03:00 INFO - mozhttpd == 0.7 10:03:00 INFO - mozinfo == 0.9 10:03:00 INFO - mozleak == 0.1 10:03:00 INFO - mozlog == 3.4 10:03:00 INFO - moznetwork == 0.27 10:03:00 INFO - mozprocess == 0.23 10:03:00 INFO - mozprofile == 0.28 10:03:00 INFO - mozrunner == 6.12 10:03:00 INFO - mozscreenshot == 0.1 10:03:00 INFO - mozsystemmonitor == 0.3 10:03:00 INFO - moztest == 0.7 10:03:00 INFO - mozversion == 1.4 10:03:00 INFO - psutil == 3.1.1 10:03:00 INFO - requests == 1.2.3 10:03:00 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:00 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:03:00 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 10:03:00 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 10:03:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:03:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:03:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:03:00 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:03:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:03:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:03:00 INFO - 'DCLOCATION': 'SCL3', 10:03:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:03:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:03:00 INFO - 'HOMEDRIVE': 'C:', 10:03:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:03:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:03:00 INFO - 'KTS_VERSION': '1.19c', 10:03:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:03:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:03:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:03:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:00 INFO - 'MOZ_NO_REMOTE': '1', 10:03:00 INFO - 'NO_EM_RESTART': '1', 10:03:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:03:00 INFO - 'OS': 'Windows_NT', 10:03:00 INFO - 'OURDRIVE': 'C:', 10:03:00 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;', 10:03:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:03:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:03:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:03:00 INFO - 'PROCESSOR_LEVEL': '6', 10:03:00 INFO - 'PROCESSOR_REVISION': '1e05', 10:03:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:03:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:03:00 INFO - 'PROMPT': '$P$G', 10:03:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:03:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:03:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:03:00 INFO - 'PWD': 'C:\\slave\\test', 10:03:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:03:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:03:00 INFO - 'SYSTEMDRIVE': 'C:', 10:03:00 INFO - 'SYSTEMROOT': 'C:\\windows', 10:03:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:00 INFO - 'TEST1': 'testie', 10:03:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:00 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:03:00 INFO - 'USERNAME': 'cltbld', 10:03:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:03:00 INFO - 'WINDIR': 'C:\\windows', 10:03:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:03:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 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)) 10:03:00 INFO - Cleaning up... 10:03:00 INFO - Return code: 0 10:03:00 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 10:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:03:00 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 0x017B0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01857E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-125', 'USERDOMAIN': 'T-W732-IX-125', '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-125', '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 10:03:00 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 10:03:00 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 10:03:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:03:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:03:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:03:00 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:03:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:03:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:03:00 INFO - 'DCLOCATION': 'SCL3', 10:03:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:03:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:03:00 INFO - 'HOMEDRIVE': 'C:', 10:03:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:03:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:03:00 INFO - 'KTS_VERSION': '1.19c', 10:03:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:03:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:03:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:03:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:00 INFO - 'MOZ_NO_REMOTE': '1', 10:03:00 INFO - 'NO_EM_RESTART': '1', 10:03:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:03:00 INFO - 'OS': 'Windows_NT', 10:03:00 INFO - 'OURDRIVE': 'C:', 10:03:00 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;', 10:03:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:03:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:03:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:03:00 INFO - 'PROCESSOR_LEVEL': '6', 10:03:00 INFO - 'PROCESSOR_REVISION': '1e05', 10:03:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:03:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:03:00 INFO - 'PROMPT': '$P$G', 10:03:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:03:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:03:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:03:00 INFO - 'PWD': 'C:\\slave\\test', 10:03:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:03:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:03:00 INFO - 'SYSTEMDRIVE': 'C:', 10:03:00 INFO - 'SYSTEMROOT': 'C:\\windows', 10:03:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:00 INFO - 'TEST1': 'testie', 10:03:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:00 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:03:00 INFO - 'USERNAME': 'cltbld', 10:03:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:03:00 INFO - 'WINDIR': 'C:\\windows', 10:03:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:03:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:03:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 10:03:00 INFO - Cleaning up... 10:03:00 INFO - Return code: 0 10:03:00 INFO - Running post-action listener: _resource_record_post_action 10:03:00 INFO - Running post-action listener: _start_resource_monitoring 10:03:00 INFO - Starting resource monitoring. 10:03:01 INFO - [mozharness: 2016-10-31 17:03:01.031000Z] Finished create-virtualenv step (success) 10:03:01 INFO - [mozharness: 2016-10-31 17:03:01.032000Z] Running install step. 10:03:01 INFO - Running pre-action listener: _resource_record_pre_action 10:03:01 INFO - Running main action method: install 10:03:01 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:03:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:03:01 INFO - Reading from file tmpfile_stdout 10:03:01 INFO - Using _rmtree_windows ... 10:03:01 INFO - Using _rmtree_windows ... 10:03:01 INFO - Detecting whether we're running mozinstall >=1.0... 10:03:01 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 10:03:01 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 10:03:01 INFO - Reading from file tmpfile_stdout 10:03:01 INFO - Output received: 10:03:01 INFO - Usage: mozinstall-script.py [options] installer 10:03:01 INFO - Options: 10:03:01 INFO - -h, --help show this help message and exit 10:03:01 INFO - -d DEST, --destination=DEST 10:03:01 INFO - Directory to install application into. [default: 10:03:01 INFO - "C:\slave\test"] 10:03:01 INFO - --app=APP Application being installed. [default: firefox] 10:03:01 INFO - Using _rmtree_windows ... 10:03:01 INFO - Using _rmtree_windows ... 10:03:01 INFO - mkdir: C:\slave\test\build\application 10:03:01 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'] 10:03:01 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 10:03:03 INFO - Reading from file tmpfile_stdout 10:03:03 INFO - Output received: 10:03:03 INFO - C:\slave\test\build\application\firefox\firefox.exe 10:03:03 INFO - Using _rmtree_windows ... 10:03:03 INFO - Using _rmtree_windows ... 10:03:03 INFO - Running post-action listener: _resource_record_post_action 10:03:03 INFO - [mozharness: 2016-10-31 17:03:03.638000Z] Finished install step (success) 10:03:03 INFO - [mozharness: 2016-10-31 17:03:03.639000Z] Running run-tests step. 10:03:03 INFO - Running pre-action listener: _resource_record_pre_action 10:03:03 INFO - Running main action method: run_tests 10:03:03 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 10:03:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 10:03:03 INFO - Python 2.7.3 10:03:03 INFO - Return code: 0 10:03:03 INFO - grabbing minidump binary from tooltool 10:03:03 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')]} 10:03:03 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 0x01864E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 10:03:03 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 10:03:03 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 10:03:03 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 10:03:03 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 10:03:04 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpll9_zl 10:03:04 INFO - INFO - File integrity verified, renaming tmpll9_zl to win32-minidump_stackwalk.exe 10:03:04 INFO - Return code: 0 10:03:04 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 10:03:04 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 10:03:04 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 10:03:04 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 10:03:04 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 10:03:04 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-125', '--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 10:03:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-125 --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 10:03:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:03:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:03:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:03:04 INFO - 'COMPUTERNAME': 'T-W732-IX-125', 10:03:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:03:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:03:04 INFO - 'DCLOCATION': 'SCL3', 10:03:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:03:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:03:04 INFO - 'HOMEDRIVE': 'C:', 10:03:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:03:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:03:04 INFO - 'KTS_VERSION': '1.19c', 10:03:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:03:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-125', 10:03:04 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:03:04 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 10:03:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:03:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:03:04 INFO - 'MOZ_NO_REMOTE': '1', 10:03:04 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:03:04 INFO - 'NO_EM_RESTART': '1', 10:03:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:03:04 INFO - 'OS': 'Windows_NT', 10:03:04 INFO - 'OURDRIVE': 'C:', 10:03: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;', 10:03:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:03:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:03:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:03:04 INFO - 'PROCESSOR_LEVEL': '6', 10:03:04 INFO - 'PROCESSOR_REVISION': '1e05', 10:03:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:03:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:03:04 INFO - 'PROMPT': '$P$G', 10:03:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:03:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:03:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:03:04 INFO - 'PWD': 'C:\\slave\\test', 10:03:04 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 10:03:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:03:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:03:04 INFO - 'SYSTEMDRIVE': 'C:', 10:03:04 INFO - 'SYSTEMROOT': 'C:\\windows', 10:03:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:04 INFO - 'TEST1': 'testie', 10:03:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:03:04 INFO - 'USERDOMAIN': 'T-W732-IX-125', 10:03:04 INFO - 'USERNAME': 'cltbld', 10:03:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:03:04 INFO - 'WINDIR': 'C:\\windows', 10:03:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:03:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:03:04 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-125', '--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 10:03:05 INFO - mozversion application_buildid: 20161031083622 10:03:05 INFO - mozversion application_changeset: a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b 10:03:05 INFO - mozversion application_display_name: Nightly 10:03:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:03:05 INFO - mozversion application_name: Firefox 10:03:05 INFO - mozversion application_remotingname: firefox 10:03:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:03:05 INFO - mozversion application_vendor: Mozilla 10:03:05 INFO - mozversion application_version: 52.0a1 10:03:05 INFO - mozversion platform_buildid: 20161031083622 10:03:05 INFO - mozversion platform_changeset: a10837dc8cdfaf4d0ebcf9c6c9f11970e064353b 10:03:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:03:05 INFO - mozversion platform_version: 52.0a1 10:03:05 INFO - using testdate: 1477933385 10:03:05 INFO - actual date: 1477933385 10:03:05 INFO - starting webserver on 'localhost:49592' 10:03:05 INFO - SUITE-START | Running 2 tests 10:03:05 INFO - TEST-START | dromaeo_css 10:03:05 INFO - operating with platform_type : w7_ 10:03:05 INFO - Initialising browser for dromaeo_css test... 10:03:05 INFO - TEST-INFO | started process 1696 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpobkrgn\profile http://localhost:49592/getInfo.html) 10:03:13 INFO - PROCESS | 1696 | __metrics Screen width/height:1600/1200 10:03:13 INFO - PROCESS | 1696 | colorDepth:24 10:03:13 INFO - PROCESS | 1696 | Browser inner width/height: 1010/674 10:03:13 INFO - PROCESS | 1696 | __metrics 10:03:14 INFO - PROCESS | 1696 | 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" 10:03:14 INFO - PROCESS | 1696 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:03:15 INFO - TEST-INFO | 1696: exit 0 10:03:15 INFO - Browser initialized. 10:03:15 INFO - Running cycle 1/1 for dromaeo_css test... 10:03:15 INFO - TEST-INFO | started process 3648 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpobkrgn\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:16 INFO - PROCESS | 3648 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 10:03:27 INFO - PROCESS | 3648 | RSS: Main: 140693504 10:03:27 INFO - PROCESS | 3648 | 10:04:19 INFO - PROCESS | 3648 | 1477933459197 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] 10:06:33 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49592/tests/dromaeo/cssquery-ext.html) 10:06:35 INFO - PROCESS | 3648 | RSS: Main: 189894656 10:06:35 INFO - PROCESS | 3648 | 10:09:37 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-ext.html (next: http://localhost:49592/tests/dromaeo/cssquery-jquery.html) 10:09:38 INFO - PROCESS | 3648 | RSS: Main: 205213696 10:09:38 INFO - PROCESS | 3648 | 10:12:40 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49592/tests/dromaeo/cssquery-mootools.html) 10:12:41 INFO - PROCESS | 3648 | RSS: Main: 167325696 10:12:41 INFO - PROCESS | 3648 | 10:15:43 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49592/tests/dromaeo/cssquery-prototype.html) 10:15:44 INFO - PROCESS | 3648 | RSS: Main: 136962048 10:15:44 INFO - PROCESS | 3648 | 10:18:47 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49592/tests/dromaeo/cssquery-yui.html) 10:18:48 INFO - PROCESS | 3648 | RSS: Main: 145661952 10:18:48 INFO - PROCESS | 3648 | 10:21:50 INFO - PROCESS | 3648 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-yui.html (next: http://localhost:49592/tests/dromaeo/cssquery-dojo.html) 10:21:51 INFO - PROCESS | 3648 | RSS: Main: 190926848 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | __start_tp_report 10:21:51 INFO - PROCESS | 3648 | _x_x_mozilla_page_load 10:21:51 INFO - PROCESS | 3648 | _x_x_mozilla_page_load_details 10:21:51 INFO - PROCESS | 3648 | |i|pagename|runs| 10:21:51 INFO - PROCESS | 3648 | |0;dojo.html;2289.2216646340244;2314.039673535483;2330.6970093887794;2338.356951838244;2339.637356209786;3890.416437534371;3918.9608103918954;3994.8002599870038;4004.9199016019697;4021.738586991847;3837.846486140551;3915.706322025845;3964.722469427134;4011.7592944423354;4024.4768180136616;3265.673432656727;3267.705906468417;3458.56767904012;3463.878764243252;3474.913127171822;3470.340635279289;3483.477478378251;3496.0560648624855;3498.3178280235365;3498.860045598173;3360.0255925781616;3477.3045390921916;3487.7558570900046;3504.842282097299;3550.5916819565823;3420.7434442416793;3479.860805567774;3558.3594952908466;3564.3940530109944;3571.607123216444;3390.593128824532;3411.761176717631;3462.515247865301;3476.3568739783223;3543.273628906068;3818.427235914607;3942.3692209246383;3962.4650672159178;3979.7214195006363;3985.4221137934837;4106.384042393635;4115.609017143366;4199.9370009449885;4211.852585159505;4217.40956266123;4096.91806163878;4109.404136400235;4128.855490057834;4214.7681877496725;4223.36649502574;3859.4982652255026;3887.2225554889137;3913.2369188008065;3931.1351502669368;3944.684425245973;4251.4685664291965;4253.957460425356;4336.111097225062;4341.044970106572;4350.521442641307;3967.821448034845;4084.4281800547947;4096.692748043909;4153.54311025787;4203.180379825904;3909.276783795007;3992.201559688074;4062.6749860011128;4067.7559346439307;4149.605787450188;4023.2959232134845;4150.5641907599265;4179.310413781692;4206.074663574009;4206.936895946563;4046.4941882264716;4085.8978525536504;4088.4889388826396;4149.398337241083;4182.665386769112;2932.3695405487924;2964.6442426908907;2990.0282408217417;2994.835284059354;2996.550517422404;2727.318170457386;2727.509048371312;2743.7530622244094;2758.9791777042624;2787.6097346371525;3926.0773718176206;3942.64516193544;3959.584243654375;3992.441058251847;4019.9397009044887;4078.4494093297026;4196.769177695256;4252.723572967747;4269.701120921506;4296.2266791977745;3777.792221427793;3796.544414670202;3825.3305671507374;4001.9799901000893;4006.779627120536;3526.8060256685617;3529.2412131391875;3555.4311310190246;3584.4802503636824;3588.8385022674042;4209.410682504391;4258.531561528292;4287.099709061007;4310.288802347577;4319.049809042006;4022.577629348876;4059.127287633173;4227.217964676544;4229.323308270662;4233.068724880522;4118.938215926703;4126.546079931145;4160.646345060703;4266.06146647737;4316.676249281254;3992.161646054245;4029.8313489087836;4061.6953728469894;4174.77038762871;4178.039051018222;4113.89715257109;4142.523609784793;4203.222403855297;4230.894227644333;4234.301340278819;3944.723869329119;3961.069148749984;3987.8803635890968;4101.446304748821;4109.157622687401;3801.752886062397;3991.720579559519;4008.1783234437453;4014.799260037045;4080.204360149861;3790.298794722985;3805.2389522096246;3955.4066889965584;4003.9990002499376;4022.4368588397065;3998.1603863187893;4052.2908491013363;4132.318167502329;4139.399787030803;4149.25313443583;3939.5666476688116;3952.0515076382035;3994.7203695742182;3995.860144894915;4088.407180958804 10:21:51 INFO - PROCESS | 3648 | |1;ext.html;526.4708967487675;536.2813829468513;540.4757385336225;551.3356405531335;553.239295968044;23556.411089722744;33409.331813363715;35161;35184.64815351846;37915;35766;35809.820950895206;36142.2771544569;36153.45769813455;36161.63838361622;6255.62466252024;6962.512624116314;7085.610291433969;7152.749653762133;7254.746083887065;6269.780557680482;9020.684276050339;9439.528023598827;9441.622335106587;9761.365511241747;9579.80840383192;9634;9642.084002019797;9663.371880827759;9678.274129440286;9245.352825302232;9411.105944935254;9720.61117555297;9731.221502279801;9765.900331428777;9241.584128714225;9417.57620907054;9485.430874147503;9486.241100711995;9507.762305942337;30444.54333185004;31255.84372078125;32395.676043239502;32673;33359.3328133436;2558.539462896678;2622.6721659792524;2657.5747880339245;2698.6221928929967;2817.7323154300307;24105.397365065837;25680.74319256802;25880.611790823154;26332.21003369883;26581.734182658118;2946.0867131189398;3077.7381273677906;3103.90688279352;3164.889228877001;3256.153400115987;206772;211222;212436.93781531148;213192;214030;44763.77618111954;45183.322250166275;45205.54794452079;45257.321140182925;45399.77300113544;25911.22266332013;45777.31334029992;47383.5261647386;47397.52602474;47406;9667.033296670417;10023.599056037678;10027.79944401108;10068.848967265498;10076.395416275047;6596.30738772422;6745.08941292929;7447.88828167578;7610.315071643578;7634.809129771689;15069.09585424856;15310.540683779505;15525.534233973;15678.216089195495;15763;6051.697415129226;6117.541184411187;6150.446459818638;6158.137860699506;6169.537284703665;723.3164659396924;776.2573023562027;800.8718605023284;807.9515229086273;902.9345372460429;22563.210287639853;24053.51892962131;24234.87882560576;24256.514869702507;24260.757392426203;820.8481430935296;837.4221786967095;848.7156802471118;853.4452605806151;868.7176667583061;11657.941710291394;12520.561780337644;12558;12563.874361256454;12608.558700445625;294704;296788;297489;297543;298005;33425;40962;41382.79308603438;41831.37252941208;41967.580324196366;920.3235621817959;1026.2764750850633;1054.8154073037342;1058.7088550648634;1064.4784055812595;22749.203777867697;24293.392665183514;24497.26508204757;24724.876375618005;25115.87442062778;31338.686613133577;34985.47521787226;35369.82315088408;35391.46912796362;35446.29107417889;247580;250941;251283.23075154252;297297;325438;31128.377432450772;32107.197320067186;32201;32209.35581288408;32346.191345216557;35702.46446303256;35848.283034339685;36360.454593181654;37065.44401833921;37179.62820371761;29380.265493362836;30550.388992219585;30964.84517577398;31264.531032034993;31931;31004.22489437692;31100;31450.21374465565;31500.842495787376;32090;30038.249043773205;30057.849710751307;30113.397732045676;30114.397712045196;30212;26796;27763.305917352227;27924.30189245285;28084.157475275773;28713.138605841857;21952.158372285106;22088.77911220867;22361.217357393063;22407.887960560092;22497.212597559006 10:21:51 INFO - PROCESS | 3648 | |2;jquery.html;252.8912567595933;271.6088832081802;272.23366224079217;275.108647980543;279.8782529599623;11611.129165312594;12155.878441215586;15105.546833594983;15447.382104715825;15678.372865085412;13782.517611883586;14511.129332240072;14609.926950365234;14637.634059148526;14639.41442342305;4614.261718125109;4855.150348688983;4856.271559266121;4857.902841943159;4860.659753817235;4591.839285625004;4802.327674125608;4857.319975203474;4858.611311095104;4876.073546026244;4642.141203877293;4889.364382630253;4891.266310053485;4892.168331383656;4893.681910675795;4575.771211439448;4831.178699621038;4872.805087796484;4880.511948805127;4884.560389564921;4470.9105817883465;4605.032943081957;4730.0776348612035;4756.28655701644;4780.617550595944;9903.603855845757;10056.547455364525;10128.240381971382;10135.391876487434;16064.518064458085;1543.7144128336295;1590.7772911792358;1964.1259639460445;2087.718158048659;2152.461884528868;7715.498492597963;7755.573443460607;7846.803829904241;7998.920010799876;8009.799755006114;1471.4555614422625;1521.6652336485956;1561.6174037360806;1566.9686606267924;1589.4357503086353;60753.999999999556;61930.380696192915;61943.38056619421;61949.380506194815;63294.683526582536;7360.96319518399;7924.286814186751;8048.4366094374;8081.272685458337;8085.676572937135;9205.81588368229;9699.6120155193;9735.123838854537;10123.240756943258;10569.524371403306;5478.58910581708;5487.341519017664;5510.752016159283;5570;5645.2096706461125;5134.28120063184;5246.580273578181;5289.576833853283;5393.7572809223675;5409.432009638931;3313.5361049453095;3376.6623337666038;3418.2479854431986;3453.4129198036394;3467.583889933174;1442.7475191841386;1482.8072350594568;1503.9172845493383;1506.5103841251637;1507.9773803392957;280.09948017125043;294.54345764065613;729.8649749796305;752.8042708895726;783.702193166593;7965.163657815961;8197.385196110195;8422.747317580483;8494.362922780816;8499.447535910145;277.5462119434698;296.7700032474849;311.08230719377923;316.35779367883237;320.11009393885035;4272.743635381825;4433.556644335606;4490.865274041784;4499.52754960731;4526.660500462479;281583;281613;281798;281938;285082;72259.63870180615;72591.2740872595;72735.27264727285;75410.24589754033;75966.6201668988;298.74158852592353;316.5441763860034;319.5590085681744;320.1212671217523;321.38936021559226;7778.688852445839;7880.448368614142;8009.8398032040195;13548.932255338661;14072.296385180496;31186.688133119285;35078;36047.459288111226;37046.44430333601;37158.628413715516;43294;48093;48134.759326203144;48296.5170348292;50000.99997999947;5506.752196151103;5576.804811831728;5773.6824474654295;5820.941790582209;5946.43508866657;10095.899041009496;10512.159027277952;10806.189535785004;10812.783744325227;11401.543938242377;8349.791255218668;8385.413021088709;8430.536320502431;8466.74599762008;8504;8741.431806932529;9023.413478124163;9157.679481218125;9257.35198536096;9261.16649501548;8145.307648849915;8282.088970213152;8290.834183316421;8391.664333426594;8550.401471896908;5663.348714897839;5690.459406356389;5808.5353171747;5814.592978491634;5947.672877991752;7926.524408535506;8063.27430531255;8316.708915187939;8325;8333.125021872616 10:21:51 INFO - PROCESS | 3648 | |3;mootools.html;1006.993006993007;1119.1997721629036;1125.8198688209884;1174.7298121432068;1296.2611311552407;4491.461024677039;4757.42910850698;5078.365204349457;5210.452902445246;5221.164613661815;4984.277279794428;5282.445343238962;5296.920546191801;5320.680759154454;5320.813771517997;1574.5197714697042;1592.625732952761;1599.9440369356228;1600.8959417637816;1614.7900772899507;1588.070978477592;1607.051839414745;1609.5815088077127;1611.2990848980671;1613.3707853936914;1515.4089904937084;1530.2960638106429;1593.9760903586455;1611.4843250159954;1612.4840051183626;1549.4034796603275;1549.5583758628793;1561.7891584636043;1566.9216539173053;1586.9920650396675;1571.1986886687757;1585.1044160049535;1586.0166696648037;1587.2936543238263;1589.9602509937345;5141.15170996785;5252.133397989327;5252.632315737862;5260.789568417297;5265.842024739264;2951.852407379644;3168.588083549147;3171.4291427543035;3202.782942481865;3207.0058281932675;2104.7895210478982;2145.656694928804;2145.9785402145935;2146.5384942237492;2147.7530084040377;611.4222060153157;680.6290571638419;687.9415249703782;689.2590465249826;689.4553302890711;5926.140709597127;6077.209962704865;6251.593646413014;6280.466160376373;6281.87436251277;2502.5370306493355;2680.5309070912513;2684.5302072137297;2687.9193624190916;2689.193242027384;5138.254953031789;5334.119870221448;5340.813071542555;5356.91964620531;5361.410244873061;3073.170244034143;3088.675689052662;3089.1350421881907;3089.6137982752157;3093.597832281834;2567.358160459885;2869.297022229567;2915.475214461417;2918.97081029187;2926.3708302715017;2059.598378316214;2226.73279206493;2318.5131122464136;2320.593896068215;2326.3715399220614;629.4366541945025;647.2976820150095;669.053289595227;683.9794806155824;684.2883401262632;535.5849216856889;592.2241863159276;684.6713577482736;693.7953303775398;710.3642240194996;3379.898603041913;3457.913552161216;3549.1304630365726;3554.182538016219;3566.019344680182;654.5876098058288;682.5768023825161;716.7061504783014;722.6820199112478;736.4991805572155;2448.804095672342;2507.9717315900393;2545.452727663524;2552.3746682063015;2656.043824223278;5696.3733989261145;5719.256496655408;5727.169560413717;5783.826485205451;5989.730462129215;5607.635503692247;6081.331053584102;6096.146539484475;6104.114903338991;6105.45050945417;4896.46138924718;5015.3730783652045;5026.472220416804;5031.345925029723;5037.899242015139;3670.1191713988983;3710.6474884885893;3718.721095917763;3725.385311423585;3766.0584853786554;5696.772129114789;5744.626599271034;5761.366249712444;5775.595708300379;5787.594868359343;5445.646033007842;5613.270274864241;5652.378238393691;5677.006523858226;5732.455416735404;4361.62926151268;4426.313921342197;4491.573300536444;4589.173948689135;4608.838690645811;4385.144896745104;4398.274284742982;4952.653314267966;4987.177115775857;5007.974960125176;4415.448068991376;4492.752898590609;4586.839460618862;4596.494385617646;4672.112298663243;4467.7319360838455;4567.862964111082;4581.74800385982;4710.92933606003;4741.217699079613;4213.241616509058;4275.893102672458;4286.8928276792085;4307.763073030888;4311.590398912098;3154.511050787132;3174.3968645957193;3178.8569514371447;3184.5541624173097;3196.2329041030453;3194.057752962928;3213.5661685672135;3242.3191129862994;3275.4104261232255;3304.73562115035 10:21:51 INFO - PROCESS | 3648 | |4;prototype.html;205.24674444787624;210.3521154843083;216.0719708850487;220.49286640726325;221.37130549240155;4377.606015458608;4405.515393306734;4416.492103408109;4449.688521803475;4467.7542735149555;4143.419921211025;4218.59923307286;4274.487061552611;4356.997890485182;4371.781410929449;3185.6655051219577;3189.681031896815;3191.6010498687665;3202.407554602394;3212.6626704196074;3142.4343618148723;3176.2218256527185;3190.234343757493;3193.712565869071;3205.0705295464286;2991.237234505209;3031.590735250746;3110.331278775063;3151.0148606377948;3198.344339410415;3139.293658926746;3142.6228852537843;3173.5239714042846;3184.8248346340943;3190.5373720810353;2727.836329820217;2766.8478233697138;2771.1547977866744;2779.346853489428;2782.819116757404;4228.302330115527;4246.2356775780345;4269.5910349584565;4273.1240095780295;4277;3686.898467873313;3702.5927148013698;3732.197577520846;3747.662710356081;3759.5488541374934;4271.337942618899;4485.663575231838;4494.348319493655;4518.5481451854885;4537.750423726694;2991.8055326403714;2994.3830331620898;2995.77173358924;2997.190758495216;3001.2346851552934;4569.771511424448;4681.133990211788;4696.436427628706;4709.9764501177615;4727.976360118178;2947.322115913352;2966.8664910078664;2971.6731159572428;2979.806312589718;2987.760979121665;2994.970050299513;2999.6700362960055;3007.503761879309;3010.1421094987822;3011.4278287125812;3580.9104772380897;3589.7846129231807;3594.281143771256;3608.711303095746;3609.9892030231576;4549.135664223787;4549.931751023737;4560.6807523473035;4580.312953057001;4580.587747102777;1002.7042022603349;1049.2759995603162;1059.9453543724044;1066.4934156275706;1067.6156583629886;643.996780016097;692.8233300508338;698.2110215456502;700.989485157723;710.7263703474097;4105.014796448891;4202.831886724497;4247.851325203603;4294.377315289328;4299.484061912528;4600.5629465200755;4617.238155704338;4634.917779676477;4646.629244372918;4651.4418269807165;4303.160883627663;4308.199670214386;4312.159128969884;4321.58944900234;4323.54602766705;2386.713594368652;2479.85120892747;2487.2911220302126;2494.4761600063825;2515.4717509323245;4837.95162048375;4848.393950756155;4853.684510506806;4855.393075865517;4863.270509423544;4796.904061918742;4817.253325734517;4847.563719265213;4851.757412129379;4858.46556878743;4812.302216178635;4850.733209673502;4858.246971719389;4860.73265970375;4888.706677599355;4487.685861989629;4646.326282689059;4678.298255261738;4714.198586240416;4741.454732705783;4611.538846115362;4620.722756634478;4623.283391074388;4625.699329543569;4627.613892827876;4823.734694591831;4829.396325459317;4830.516948305141;4836.927446088241;4852.369192005017;2943.9548960119705;3068.800527965675;3077.9230519236303;3082.599262095913;3083.167544762857;4464.789928985173;4638;4679.9064018718755;4691.08523837862;4695.9530404695515;4452.643788496847;4510.143072816154;4520.276755719069;4523.66072544567;4531.116432295671;4371.475422949266;4503.243734943309;4524.230880750215;4530.184566778012;4549.977250113729;4340.110277393063;4416.734995900256;4474.664400170065;4477.574630410106;4483.103379324213;4469.41897553316;4551.5676010778925;4616.8614941551805;4619.168549660959;4619.35329053926;4464.441944756905;4496.168208881368;4537.387452693844;4543.79552920126;4546.522615125365 10:21:51 INFO - PROCESS | 3648 | |5;yui.html;552.5607142321854;570.6119838509811;574.1961254244061;596.7642781101467;654.2868273581795;11432.142589305793;11787.882121178785;11993.760124797509;12225.877741222595;12545.68635784106;11404.828927566072;11502.769944601125;11529.308241505516;11556.595519156832;11586.304821710683;601.6390165900468;617.8270084376381;618.4155972605889;619.1826788638998;620.3455354600894;497.5099526965936;627.1909237084157;628.3182746719791;629.2511910826125;629.3769168523174;490.05174986401425;654.6006935769201;656.9146011018585;664.3622122762154;670.9463242940553;659.2813832922114;659.9868002639969;660.4386271669091;661.7154623511888;661.7915356662636;379.4005471355252;477.79216041022266;478.182308252888;478.32794923133054;478.86113027222063;9297.953510232406;9685.225181985425;9700.660476883346;9729.90270097297;9746.122848943629;11175.441227938652;11425.714357141054;11670.241434306743;11712.828717128305;11793.587224447103;10716;10847.349159050482;11015.83476247857;11097.1677124216;11153.330800152016;2977.702229777005;3058.816471011746;3113.517404802259;3115.314630781247;4017.5982401759884;47551;48792.51207487915;49896.501034989546;50330.24504632434;50791;8876.600552975131;8900.688475903313;8906.955465222632;8940.552972351357;8958.955205224063;14471.348789304506;14497.057691250035;15179.620509487351;15407.691846163014;15429.382824686887;5815.505682016991;5937.851553711192;6056.939430605637;6098.664573448503;6112.541559383064;3000.294930691286;3201.935961280761;3227.3383956288903;3279.0818570800216;3344.832758362072;2175.7280339957506;2246.2475070851447;2250.0437314141423;2251.921182758628;2255.4699645583273;373.0971050058864;373.40255591054097;378.6365089514097;379.26612005768925;379.7626483447845;452.7842742237104;492.36730800920645;496.9811885634435;546.3252882689875;581.1079992212019;7042.31668003644;7080.822979425556;7239.276072392824;7265.782026539212;7347.448941329421;507.14545990006957;514.3339375508725;518.9948100518947;545.7844151560112;548.5796061556194;3348.263382055944;3514.2093029068765;3545.4327307630656;3562.109472631842;3662.7069834413182;57030.429695702514;57078.42921570814;62070.37929620646;62952.37047629557;63246.68376658087;55155.17267240994;55425;57122.714386427804;57754;58118.41881581215;47393.52606473891;51358.48641513537;52390.47609523856;53945.730271348395;54343.72828135834;7337.3029562191505;7582.090149182133;7612.543247405173;7675.347595454449;7799.259070388304;36007;36862.262754745294;37551.87344379673;38718.03204919787;39735.403968939914;43095;49836.25245621391;50162.247566285805;50936;51355.48644513507;8834.955825220834;9350.392224505385;9695.321327507007;9717.076877696607;10109.949450252996;10806.945965270437;11093;11270.830937536108;11394.501161131024;11426.828597570877;9956.2532810041;10179.7455063624;10347.27569070158;10447.94776026115;10869.891301087204;10122.898771012196;10418.479076046318;10467.319624224401;10542.736431588964;10629.840552391566;9728.464934428674;9802.411855288705;9814.901850981685;9961.950190249003;10136.797264054825;8800.559972001502;9077.500737459502;9100.59047342858;9175;9183.612380515246;8412.411131220755;8503.1921967413;8578.313734901318;8861.955690221766;8902.59938302791 10:21:51 INFO - PROCESS | 3648 | __end_tp_report 10:21:51 INFO - PROCESS | 3648 | __start_cc_report 10:21:51 INFO - PROCESS | 3648 | _x_x_mozilla_cycle_collect,1204 10:21:51 INFO - PROCESS | 3648 | __end_cc_report 10:21:51 INFO - PROCESS | 3648 | __startTimestamp1477934511091__endTimestamp 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | ------- Summary: start ------- 10:21:51 INFO - PROCESS | 3648 | Number of tests: 6 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#0] dojo.html Cycles:165 Average:3839.40 Median:3994.80 stddev:455.87 (11.4%) stddev-sans-first:440.75 10:21:51 INFO - PROCESS | 3648 | Values: 2289.2 2314.0 2330.7 2338.4 2339.6 3890.4 3919.0 3994.8 4004.9 4021.7 3837.8 3915.7 3964.7 4011.8 4024.5 3265.7 3267.7 3458.6 3463.9 3474.9 3470.3 3483.5 3496.1 3498.3 3498.9 3360.0 3477.3 3487.8 3504.8 3550.6 3420.7 3479.9 3558.4 3564.4 3571.6 3390.6 3411.8 3462.5 3476.4 3543.3 3818.4 3942.4 3962.5 3979.7 3985.4 4106.4 4115.6 4199.9 4211.9 4217.4 4096.9 4109.4 4128.9 4214.8 4223.4 3859.5 3887.2 3913.2 3931.1 3944.7 4251.5 4254.0 4336.1 4341.0 4350.5 3967.8 4084.4 4096.7 4153.5 4203.2 3909.3 3992.2 4062.7 4067.8 4149.6 4023.3 4150.6 4179.3 4206.1 4206.9 4046.5 4085.9 4088.5 4149.4 4182.7 2932.4 2964.6 2990.0 2994.8 2996.6 2727.3 2727.5 2743.8 2759.0 2787.6 3926.1 3942.6 3959.6 3992.4 4019.9 4078.4 4196.8 4252.7 4269.7 4296.2 3777.8 3796.5 3825.3 4002.0 4006.8 3526.8 3529.2 3555.4 3584.5 3588.8 4209.4 4258.5 4287.1 4310.3 4319.0 4022.6 4059.1 4227.2 4229.3 4233.1 4118.9 4126.5 4160.6 4266.1 4316.7 3992.2 4029.8 4061.7 4174.8 4178.0 4113.9 4142.5 4203.2 4230.9 4234.3 3944.7 3961.1 3987.9 4101.4 4109.2 3801.8 3991.7 4008.2 4014.8 4080.2 3790.3 3805.2 3955.4 4004.0 4022.4 3998.2 4052.3 4132.3 4139.4 4149.3 3939.6 3952.1 3994.7 3995.9 4088.4 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#1] ext.html Cycles:180 Average:39879.81 Median:32370.93 stddev:69206.15 (213.8%) stddev-sans-first:69337.21 10:21:51 INFO - PROCESS | 3648 | Values: 526.5 536.3 540.5 551.3 553.2 23556.4 33409.3 35161.0 35184.6 37915.0 35766.0 35809.8 36142.3 36153.5 36161.6 6255.6 6962.5 7085.6 7152.7 7254.7 6269.8 9020.7 9439.5 9441.6 9761.4 9579.8 9634.0 9642.1 9663.4 9678.3 9245.4 9411.1 9720.6 9731.2 9765.9 9241.6 9417.6 9485.4 9486.2 9507.8 30444.5 31255.8 32395.7 32673.0 33359.3 2558.5 2622.7 2657.6 2698.6 2817.7 24105.4 25680.7 25880.6 26332.2 26581.7 2946.1 3077.7 3103.9 3164.9 3256.2 206772.0 211222.0 212436.9 213192.0 214030.0 44763.8 45183.3 45205.5 45257.3 45399.8 25911.2 45777.3 47383.5 47397.5 47406.0 9667.0 10023.6 10027.8 10068.8 10076.4 6596.3 6745.1 7447.9 7610.3 7634.8 15069.1 15310.5 15525.5 15678.2 15763.0 6051.7 6117.5 6150.4 6158.1 6169.5 723.3 776.3 800.9 808.0 902.9 22563.2 24053.5 24234.9 24256.5 24260.8 820.8 837.4 848.7 853.4 868.7 11657.9 12520.6 12558.0 12563.9 12608.6 294704.0 296788.0 297489.0 297543.0 298005.0 33425.0 40962.0 41382.8 41831.4 41967.6 920.3 1026.3 1054.8 1058.7 1064.5 22749.2 24293.4 24497.3 24724.9 25115.9 31338.7 34985.5 35369.8 35391.5 35446.3 247580.0 250941.0 251283.2 297297.0 325438.0 31128.4 32107.2 32201.0 32209.4 32346.2 35702.5 35848.3 36360.5 37065.4 37179.6 29380.3 30550.4 30964.8 31264.5 31931.0 31004.2 31100.0 31450.2 31500.8 32090.0 30038.2 30057.8 30113.4 30114.4 30212.0 26796.0 27763.3 27924.3 28084.2 28713.1 21952.2 22088.8 22361.2 22407.9 22497.2 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#2] jquery.html Cycles:180 Average:19171.63 Median:4855.71 stddev:47554.48 (979.4%) stddev-sans-first:47666.67 10:21:51 INFO - PROCESS | 3648 | Values: 252.9 271.6 272.2 275.1 279.9 11611.1 12155.9 15105.5 15447.4 15678.4 13782.5 14511.1 14609.9 14637.6 14639.4 4614.3 4855.2 4856.3 4857.9 4860.7 4591.8 4802.3 4857.3 4858.6 4876.1 4642.1 4889.4 4891.3 4892.2 4893.7 4575.8 4831.2 4872.8 4880.5 4884.6 4470.9 4605.0 4730.1 4756.3 4780.6 9903.6 10056.5 10128.2 10135.4 16064.5 1543.7 1590.8 1964.1 2087.7 2152.5 7715.5 7755.6 7846.8 7998.9 8009.8 1471.5 1521.7 1561.6 1567.0 1589.4 60754.0 61930.4 61943.4 61949.4 63294.7 7361.0 7924.3 8048.4 8081.3 8085.7 9205.8 9699.6 9735.1 10123.2 10569.5 5478.6 5487.3 5510.8 5570.0 5645.2 5134.3 5246.6 5289.6 5393.8 5409.4 3313.5 3376.7 3418.2 3453.4 3467.6 1442.7 1482.8 1503.9 1506.5 1508.0 280.1 294.5 729.9 752.8 783.7 7965.2 8197.4 8422.7 8494.4 8499.4 277.5 296.8 311.1 316.4 320.1 4272.7 4433.6 4490.9 4499.5 4526.7 281583.0 281613.0 281798.0 281938.0 285082.0 72259.6 72591.3 72735.3 75410.2 75966.6 298.7 316.5 319.6 320.1 321.4 7778.7 7880.4 8009.8 13548.9 14072.3 31186.7 35078.0 36047.5 37046.4 37158.6 43294.0 48093.0 48134.8 48296.5 50001.0 5506.8 5576.8 5773.7 5820.9 5946.4 10095.9 10512.2 10806.2 10812.8 11401.5 8349.8 8385.4 8430.5 8466.7 8504.0 8741.4 9023.4 9157.7 9257.4 9261.2 8145.3 8282.1 8290.8 8391.7 8550.4 5663.3 5690.5 5808.5 5814.6 5947.7 7926.5 8063.3 8316.7 8325.0 8333.1 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#3] mootools.html Cycles:180 Average:3382.84 Median:4373.39 stddev:1745.95 (39.9%) stddev-sans-first:1741.71 10:21:51 INFO - PROCESS | 3648 | Values: 1007.0 1119.2 1125.8 1174.7 1296.3 4491.5 4757.4 5078.4 5210.5 5221.2 4984.3 5282.4 5296.9 5320.7 5320.8 1574.5 1592.6 1599.9 1600.9 1614.8 1588.1 1607.1 1609.6 1611.3 1613.4 1515.4 1530.3 1594.0 1611.5 1612.5 1549.4 1549.6 1561.8 1566.9 1587.0 1571.2 1585.1 1586.0 1587.3 1590.0 5141.2 5252.1 5252.6 5260.8 5265.8 2951.9 3168.6 3171.4 3202.8 3207.0 2104.8 2145.7 2146.0 2146.5 2147.8 611.4 680.6 687.9 689.3 689.5 5926.1 6077.2 6251.6 6280.5 6281.9 2502.5 2680.5 2684.5 2687.9 2689.2 5138.3 5334.1 5340.8 5356.9 5361.4 3073.2 3088.7 3089.1 3089.6 3093.6 2567.4 2869.3 2915.5 2919.0 2926.4 2059.6 2226.7 2318.5 2320.6 2326.4 629.4 647.3 669.1 684.0 684.3 535.6 592.2 684.7 693.8 710.4 3379.9 3457.9 3549.1 3554.2 3566.0 654.6 682.6 716.7 722.7 736.5 2448.8 2508.0 2545.5 2552.4 2656.0 5696.4 5719.3 5727.2 5783.8 5989.7 5607.6 6081.3 6096.1 6104.1 6105.5 4896.5 5015.4 5026.5 5031.3 5037.9 3670.1 3710.6 3718.7 3725.4 3766.1 5696.8 5744.6 5761.4 5775.6 5787.6 5445.6 5613.3 5652.4 5677.0 5732.5 4361.6 4426.3 4491.6 4589.2 4608.8 4385.1 4398.3 4952.7 4987.2 5008.0 4415.4 4492.8 4586.8 4596.5 4672.1 4467.7 4567.9 4581.7 4710.9 4741.2 4213.2 4275.9 4286.9 4307.8 4311.6 3154.5 3174.4 3178.9 3184.6 3196.2 3194.1 3213.6 3242.3 3275.4 3304.7 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#4] prototype.html Cycles:180 Average:3745.23 Median:4364.24 stddev:1179.74 (27.0%) stddev-sans-first:1152.74 10:21:51 INFO - PROCESS | 3648 | Values: 205.2 210.4 216.1 220.5 221.4 4377.6 4405.5 4416.5 4449.7 4467.8 4143.4 4218.6 4274.5 4357.0 4371.8 3185.7 3189.7 3191.6 3202.4 3212.7 3142.4 3176.2 3190.2 3193.7 3205.1 2991.2 3031.6 3110.3 3151.0 3198.3 3139.3 3142.6 3173.5 3184.8 3190.5 2727.8 2766.8 2771.2 2779.3 2782.8 4228.3 4246.2 4269.6 4273.1 4277.0 3686.9 3702.6 3732.2 3747.7 3759.5 4271.3 4485.7 4494.3 4518.5 4537.8 2991.8 2994.4 2995.8 2997.2 3001.2 4569.8 4681.1 4696.4 4710.0 4728.0 2947.3 2966.9 2971.7 2979.8 2987.8 2995.0 2999.7 3007.5 3010.1 3011.4 3580.9 3589.8 3594.3 3608.7 3610.0 4549.1 4549.9 4560.7 4580.3 4580.6 1002.7 1049.3 1059.9 1066.5 1067.6 644.0 692.8 698.2 701.0 710.7 4105.0 4202.8 4247.9 4294.4 4299.5 4600.6 4617.2 4634.9 4646.6 4651.4 4303.2 4308.2 4312.2 4321.6 4323.5 2386.7 2479.9 2487.3 2494.5 2515.5 4838.0 4848.4 4853.7 4855.4 4863.3 4796.9 4817.3 4847.6 4851.8 4858.5 4812.3 4850.7 4858.2 4860.7 4888.7 4487.7 4646.3 4678.3 4714.2 4741.5 4611.5 4620.7 4623.3 4625.7 4627.6 4823.7 4829.4 4830.5 4836.9 4852.4 2944.0 3068.8 3077.9 3082.6 3083.2 4464.8 4638.0 4679.9 4691.1 4696.0 4452.6 4510.1 4520.3 4523.7 4531.1 4371.5 4503.2 4524.2 4530.2 4550.0 4340.1 4416.7 4474.7 4477.6 4483.1 4469.4 4551.6 4616.9 4619.2 4619.4 4464.4 4496.2 4537.4 4543.8 4546.5 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - PROCESS | 3648 | [#5] yui.html Cycles:180 Average:13670.23 Median:50863.50 stddev:17491.03 (34.4%) stddev-sans-first:17512.36 10:21:51 INFO - PROCESS | 3648 | Values: 552.6 570.6 574.2 596.8 654.3 11432.1 11787.9 11993.8 12225.9 12545.7 11404.8 11502.8 11529.3 11556.6 11586.3 601.6 617.8 618.4 619.2 620.3 497.5 627.2 628.3 629.3 629.4 490.1 654.6 656.9 664.4 670.9 659.3 660.0 660.4 661.7 661.8 379.4 477.8 478.2 478.3 478.9 9298.0 9685.2 9700.7 9729.9 9746.1 11175.4 11425.7 11670.2 11712.8 11793.6 10716.0 10847.3 11015.8 11097.2 11153.3 2977.7 3058.8 3113.5 3115.3 4017.6 47551.0 48792.5 49896.5 50330.2 50791.0 8876.6 8900.7 8907.0 8940.6 8959.0 14471.3 14497.1 15179.6 15407.7 15429.4 5815.5 5937.9 6056.9 6098.7 6112.5 3000.3 3201.9 3227.3 3279.1 3344.8 2175.7 2246.2 2250.0 2251.9 2255.5 373.1 373.4 378.6 379.3 379.8 452.8 492.4 497.0 546.3 581.1 7042.3 7080.8 7239.3 7265.8 7347.4 507.1 514.3 519.0 545.8 548.6 3348.3 3514.2 3545.4 3562.1 3662.7 57030.4 57078.4 62070.4 62952.4 63246.7 55155.2 55425.0 57122.7 57754.0 58118.4 47393.5 51358.5 52390.5 53945.7 54343.7 7337.3 7582.1 7612.5 7675.3 7799.3 36007.0 36862.3 37551.9 38718.0 39735.4 43095.0 49836.3 50162.2 50936.0 51355.5 8835.0 9350.4 9695.3 9717.1 10109.9 10806.9 11093.0 11270.8 11394.5 11426.8 9956.3 10179.7 10347.3 10447.9 10869.9 10122.9 10418.5 10467.3 10542.7 10629.8 9728.5 9802.4 9814.9 9962.0 10136.8 8800.6 9077.5 9100.6 9175.0 9183.6 8412.4 8503.2 8578.3 8862.0 8902.6 10:21:51 INFO - PROCESS | 3648 | -------- Summary: end -------- 10:21:51 INFO - PROCESS | 3648 | 10:21:51 INFO - TEST-INFO | 3648: exit 0 10:21:52 INFO - TEST-OK | dromaeo_css | took 1127205ms 10:21:52 INFO - TEST-START | kraken 10:21:52 INFO - operating with platform_type : w7_ 10:21:52 INFO - Initialising browser for kraken test... 10:21:52 INFO - TEST-INFO | started process 1080 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpammcyv\profile http://localhost:49592/getInfo.html) 10:21:57 INFO - PROCESS | 1080 | __metrics Screen width/height:1600/1200 10:21:57 INFO - PROCESS | 1080 | colorDepth:24 10:21:57 INFO - PROCESS | 1080 | Browser inner width/height: 1010/674 10:21:57 INFO - PROCESS | 1080 | __metrics 10:21:58 INFO - PROCESS | 1080 | 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" 10:21:58 INFO - PROCESS | 1080 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:21:58 INFO - PROCESS | 1080 | console.error: 10:21:58 INFO - PROCESS | 1080 | Message: Error: Connection closed before committing the transaction. 10:21:58 INFO - PROCESS | 1080 | Stack: 10:21:58 INFO - PROCESS | 1080 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 10:21:58 INFO - PROCESS | 1080 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 10:21:58 INFO - PROCESS | 1080 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 10:21:58 INFO - PROCESS | 1080 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 10:21:58 INFO - PROCESS | 1080 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 10:21:58 INFO - PROCESS | 1080 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 10:21:58 INFO - PROCESS | 1080 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 10:21:58 INFO - PROCESS | 1080 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:21:58 INFO - PROCESS | 1080 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 10:21:58 INFO - PROCESS | 1080 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:21:58 INFO - PROCESS | 1080 | Barrier/this.client.addBlocker/promise13.2% 10:22:58 INFO - TinderboxPrint: I/O read bytes / time
210,739,200 / 2,346,710 10:22:58 INFO - TinderboxPrint: I/O write bytes / time
489,775,104 / 3,438,310 10:22:58 INFO - TinderboxPrint: CPU idle
8,319.8 (86.8%) 10:22:58 INFO - TinderboxPrint: CPU user
1,224.4 (12.8%) 10:22:58 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35525120; Read time: 0; Write time: 44690 10:22:58 INFO - run-tests - Wall time: 1195s; CPU: 13.0%; Read bytes: 210739200; Write bytes: 411832320; Read time: 2346710; Write time: 3284480 10:22:59 INFO - Running post-run listener: _upload_blobber_files 10:22:59 INFO - Blob upload gear active. 10:22:59 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 10:22:59 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 10:22:59 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 10:22:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 10:23:00 INFO - (blobuploader) - INFO - Open directory for files ... 10:23:00 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 10:23:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:23:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:00 INFO - (blobuploader) - INFO - Done attempting. 10:23:00 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 10:23:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:23:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:02 INFO - (blobuploader) - INFO - Done attempting. 10:23:02 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 10:23:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:23:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:10 INFO - (blobuploader) - INFO - Done attempting. 10:23:10 INFO - (blobuploader) - INFO - Iteration through files over. 10:23:10 INFO - Return code: 0 10:23:10 INFO - rmtree: C:\slave\test\build\uploaded_files.json 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 10:23:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce7d03aae42baabf633b9d6a8b4ad308dd2694d7369bef9f28e51784f78aecb697941a12c7c7ad132f17be741d4285f67d0666f10ffbb393c431a38f724c3f6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/323aaa30f60f4e81d82b7fca8284e7bca3bd5054f96ffdf1a2ccda12a4ec7159a4afa2f8f22fe42fbe85fe7c6bd5ac425ebf64afb1266958c7ffb97a2d873ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/547d49b5f10e0dfed5e8ac118d3d75b9398dae2e846dea5c209a0a9200711b4016e3ffd49124a4ec1a32d7568ed13c0bc0c3fe2df0457309abc692dc90b51263"} 10:23:10 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 10:23:10 INFO - Writing to file C:\slave\test\properties\blobber_files 10:23:10 INFO - Contents: 10:23:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce7d03aae42baabf633b9d6a8b4ad308dd2694d7369bef9f28e51784f78aecb697941a12c7c7ad132f17be741d4285f67d0666f10ffbb393c431a38f724c3f6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/323aaa30f60f4e81d82b7fca8284e7bca3bd5054f96ffdf1a2ccda12a4ec7159a4afa2f8f22fe42fbe85fe7c6bd5ac425ebf64afb1266958c7ffb97a2d873ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/547d49b5f10e0dfed5e8ac118d3d75b9398dae2e846dea5c209a0a9200711b4016e3ffd49124a4ec1a32d7568ed13c0bc0c3fe2df0457309abc692dc90b51263"} 10:23:10 INFO - Running post-run listener: copy_logs_to_upload_dir 10:23:10 INFO - Copying logs to upload dir... 10:23:10 INFO - mkdir: C:\slave\test\build\upload\logs 10:23:10 INFO - Copying logs to upload dir... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... 10:23:10 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1421.380000 ========= master_lag: 0.39 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 41 secs) (at 2016-10-31 10:23:10.969604) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:10.970402) ========= '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-125 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-125 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-125 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/Firefox-Non-PGO/sha512/ce7d03aae42baabf633b9d6a8b4ad308dd2694d7369bef9f28e51784f78aecb697941a12c7c7ad132f17be741d4285f67d0666f10ffbb393c431a38f724c3f6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/323aaa30f60f4e81d82b7fca8284e7bca3bd5054f96ffdf1a2ccda12a4ec7159a4afa2f8f22fe42fbe85fe7c6bd5ac425ebf64afb1266958c7ffb97a2d873ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/547d49b5f10e0dfed5e8ac118d3d75b9398dae2e846dea5c209a0a9200711b4016e3ffd49124a4ec1a32d7568ed13c0bc0c3fe2df0457309abc692dc90b51263"} build_url:https://queue.taskcluster.net/v1/task/HrqgWAc9QA6BnnDeRDk4_w/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/HrqgWAc9QA6BnnDeRDk4_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ce7d03aae42baabf633b9d6a8b4ad308dd2694d7369bef9f28e51784f78aecb697941a12c7c7ad132f17be741d4285f67d0666f10ffbb393c431a38f724c3f6f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/323aaa30f60f4e81d82b7fca8284e7bca3bd5054f96ffdf1a2ccda12a4ec7159a4afa2f8f22fe42fbe85fe7c6bd5ac425ebf64afb1266958c7ffb97a2d873ba7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/547d49b5f10e0dfed5e8ac118d3d75b9398dae2e846dea5c209a0a9200711b4016e3ffd49124a4ec1a32d7568ed13c0bc0c3fe2df0457309abc692dc90b51263"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:11.086965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:11.087347) ========= '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-125 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-125 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-125 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:11.198699) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:11.199026) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-31 10:23:11.682038) ========= ========= Total master_lag: 1.83 =========