builder: mozilla-inbound_win7_ix_test-dromaeojs-e10s slave: t-w732-ix-009 starttime: 1478533416.43 results: success (0) buildid: 20161104184821 builduid: 69daeb60fcb74405962d066c483c9ef7 revision: b4ade2b0841c5825968f34752d86ee09507a0a9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:36.429670) ========= master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:36.430004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:36.430275) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:36.609370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:43:36.609913) ========= '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-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --07:43:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.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% 2.46 GB/s 07:43:37 (2.46 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.210000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:43:37.852100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-11-07 07:43:37.852430) ========= '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-009 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-009 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-009 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=8.001000 ========= master_lag: 3.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2016-11-07 07:43:48.913972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-07 07:43:48.914293) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b4ade2b0841c5825968f34752d86ee09507a0a9f --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev b4ade2b0841c5825968f34752d86ee09507a0a9f --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-07 07:43:49,390 truncating revision to first 12 chars 2016-11-07 07:43:49,390 Setting DEBUG logging. 2016-11-07 07:43:49,390 attempt 1/10 2016-11-07 07:43:49,390 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/b4ade2b0841c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-07 07:43:50,358 unpacking tar archive at: mozilla-inbound-b4ade2b0841c/testing/mozharness/ 2016-11-07 07:43:50,489 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.762000 ========= master_lag: 2.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-07 07:43:53.258388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:53.258659) ========= script_repo_revision: b4ade2b0841c5825968f34752d86ee09507a0a9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:53.259025) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:53.259291) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:43:53.408433) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-11-07 07:43:53.408713) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 07:43:53 INFO - MultiFileLogger online at 20161107 07:43:53 in C:\slave\test 07:43:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound-Non-PGO 07:43:53 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 07:43:53 INFO - {'append_to_log': False, 07:43:53 INFO - 'base_work_dir': 'C:\\slave\\test', 07:43:53 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO', 07:43:53 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 07:43:53 INFO - 'branch': 'Mozilla-Inbound-Non-PGO', 07:43:53 INFO - 'buildbot_json_path': 'buildprops.json', 07:43:53 INFO - 'config_files': ('talos/windows_config.py',), 07:43:53 INFO - 'default_actions': ('clobber', 07:43:53 INFO - 'read-buildbot-config', 07:43:53 INFO - 'download-and-extract', 07:43:53 INFO - 'populate-webroot', 07:43:53 INFO - 'create-virtualenv', 07:43:53 INFO - 'install', 07:43:53 INFO - 'run-tests'), 07:43:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:43:53 INFO - 'download_minidump_stackwalk': True, 07:43:53 INFO - 'download_symbols': 'ondemand', 07:43:53 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 07:43:53 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 07:43:53 INFO - 'hg': 'c:/mozilla-build/hg/hg', 07:43:53 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 07:43:53 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 07:43:53 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 07:43:53 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 07:43:53 INFO - 'C:/mozilla-build/tooltool.py'), 07:43:53 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 07:43:53 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 07:43:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:43:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:43:53 INFO - 'installer_path': 'installer.exe', 07:43:53 INFO - 'log_level': 'info', 07:43:53 INFO - 'log_name': 'talos', 07:43:53 INFO - 'log_to_console': True, 07:43:53 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 07:43:53 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 07:43:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 07:43:53 INFO - 'opt_config_files': (), 07:43:53 INFO - 'pip_index': False, 07:43:53 INFO - 'sps_profile': False, 07:43:53 INFO - 'sps_profile_interval': 0, 07:43:53 INFO - 'suite': 'dromaeojs-e10s', 07:43:53 INFO - 'system_bits': '32', 07:43:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:43:53 INFO - 'title': 'T-W732-IX-009', 07:43:53 INFO - 'use_talos_json': True, 07:43:53 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 07:43:53 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 07:43:53 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 07:43:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:43:53 INFO - 'work_dir': 'build'} 07:43:53 INFO - [mozharness: 2016-11-07 15:43:53.977000Z] Running clobber step. 07:43:53 INFO - Running pre-action listener: _resource_record_pre_action 07:43:53 INFO - Running main action method: clobber 07:43:53 INFO - rmtree: C:\slave\test\build 07:43:53 INFO - Using _rmtree_windows ... 07:43:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 07:44:09 INFO - Running post-action listener: _resource_record_post_action 07:44:09 INFO - [mozharness: 2016-11-07 15:44:09.701000Z] Finished clobber step (success) 07:44:09 INFO - [mozharness: 2016-11-07 15:44:09.701000Z] Running read-buildbot-config step. 07:44:09 INFO - Running pre-action listener: _resource_record_pre_action 07:44:09 INFO - Running main action method: read_buildbot_config 07:44:09 INFO - Using buildbot properties: 07:44:09 INFO - { 07:44:09 INFO - "project": "", 07:44:09 INFO - "product": "firefox", 07:44:09 INFO - "script_repo_revision": "production", 07:44:09 INFO - "builddir": "mozilla-inbound_win7_ix_test-dromaeojs-e10s", 07:44:09 INFO - "repository": "", 07:44:09 INFO - "buildername": "Windows 7 32-bit mozilla-inbound talos dromaeojs-e10s", 07:44:09 INFO - "buildid": "20161104184821", 07:44:09 INFO - "pgo_build": "False", 07:44:09 INFO - "basedir": "C:\\slave\\test", 07:44:09 INFO - "buildnumber": 436, 07:44:09 INFO - "slavename": "t-w732-ix-009", 07:44:09 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 07:44:09 INFO - "platform": "win7_ix", 07:44:09 INFO - "scheduler": "tests-mozilla-inbound-win32-talos-8-3600", 07:44:09 INFO - "branch": "mozilla-inbound", 07:44:09 INFO - "repo_path": "integration/mozilla-inbound", 07:44:09 INFO - "revision": "b4ade2b0841c5825968f34752d86ee09507a0a9f", 07:44:09 INFO - "stage_platform": "win32", 07:44:09 INFO - "builduid": "69daeb60fcb74405962d066c483c9ef7", 07:44:09 INFO - "slavebuilddir": "test" 07:44:09 INFO - } 07:44:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 07:44:09 INFO - Running post-action listener: _resource_record_post_action 07:44:09 INFO - [mozharness: 2016-11-07 15:44:09.705000Z] Finished read-buildbot-config step (success) 07:44:09 INFO - [mozharness: 2016-11-07 15:44:09.705000Z] Running download-and-extract step. 07:44:09 INFO - Running pre-action listener: _resource_record_pre_action 07:44:09 INFO - Running main action method: download_and_extract 07:44:09 INFO - mkdir: C:\slave\test\build\tests 07:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:09 INFO - trying https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 07:44:09 INFO - Downloading https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/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 07:44:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/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 07:44:10 INFO - Downloaded 1274 bytes. 07:44:10 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:44:10 INFO - Using the following test package requirements: 07:44:10 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:44:10 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 07:44:10 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 07:44:10 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'jsshell-win32.zip'], 07:44:10 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 07:44:10 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:44:10 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 07:44:10 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 07:44:10 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 07:44:10 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:44:10 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 07:44:10 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 07:44:10 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:44:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:44:10 INFO - Fetch https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:44:11 INFO - Http code: 200 07:44:11 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 07:44:11 INFO - x-amz-cf-id: Gbf8pCsC5e6uE4A2ikwudsN1u4UgzLlppnuKVm4bNJf76dMlVygiGA== 07:44:11 INFO - x-amz-version-id: MMuwh2LhHFmE9V6EykRG0QovC4nYo9Cl 07:44:12 INFO - Expected file size: 17055963 07:44:12 INFO - Obtained file size: 17055963 07:44:41 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 07:44:41 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:44:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:44:41 INFO - Fetch https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:44:42 INFO - Http code: 200 07:44:42 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 07:44:42 INFO - x-amz-cf-id: KbdtZAZSg48tYO-OZuiqQjX1OtrUSM2vIbRR5DCnvwNyywrWA_R8fA== 07:44:42 INFO - x-amz-version-id: MMuwh2LhHFmE9V6EykRG0QovC4nYo9Cl 07:44:43 INFO - Expected file size: 17055963 07:44:43 INFO - Obtained file size: 17055963 07:44:54 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 07:44:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 07:44:54 INFO - Fetch https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 07:44:54 INFO - Http code: 200 07:44:54 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 07:44:54 INFO - x-amz-cf-id: ck3fM0F6pCJ9jp8WnhNl2v9sBb-l6n1t3_bF21dDNtycD4v06cmrxQ== 07:44:54 INFO - x-amz-version-id: U.yuraIYjuBN7k3Qvb0qNqaDAhPEYjwl 07:44:54 INFO - Expected file size: 14409038 07:44:54 INFO - Obtained file size: 14409038 07:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:55 INFO - trying https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:44:55 INFO - Downloading https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 07:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/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 07:44:57 INFO - Downloaded 52935955 bytes. 07:44:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:44:57 INFO - mkdir: C:\slave\test\properties 07:44:57 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 07:44:57 INFO - Writing to file C:\slave\test\properties\build_url 07:44:57 INFO - Contents: 07:44:57 INFO - build_url:https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:44:58 INFO - Running post-action listener: _resource_record_post_action 07:44:58 INFO - Running post-action listener: set_extra_try_arguments 07:44:58 INFO - [mozharness: 2016-11-07 15:44:58.591000Z] Finished download-and-extract step (success) 07:44:58 INFO - [mozharness: 2016-11-07 15:44:58.591000Z] Running populate-webroot step. 07:44:58 INFO - Running pre-action listener: _resource_record_pre_action 07:44:58 INFO - Running main action method: populate_webroot 07:44:58 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 07:44:58 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 07:44:58 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 07:44:58 INFO - u'--tppagecycles', 07:44:58 INFO - u'7'], 07:44:58 INFO - u'tests': [u'tsvgm']}}, 07:44:58 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 07:44:58 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 07:44:58 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 07:44:58 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 07:44:58 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:44:58 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:44:58 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'damp', u'tps']}, 07:44:58 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'tests': [u'damp', u'tps']}, 07:44:58 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'dromaeo_dom']}, 07:44:58 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 07:44:58 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'basic_compositor_video']}, 07:44:58 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 07:44:58 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'other-e10s': {u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'a11yr', 07:44:58 INFO - u'ts_paint', 07:44:58 INFO - u'tpaint', 07:44:58 INFO - u'sessionrestore', 07:44:58 INFO - u'sessionrestore_no_auto_restore', 07:44:58 INFO - u'tabpaint']}, 07:44:58 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'tsvgx', 07:44:58 INFO - u'tsvgr_opacity', 07:44:58 INFO - u'tart', 07:44:58 INFO - u'tscrollx', 07:44:58 INFO - u'cart']}, 07:44:58 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 07:44:58 INFO - u'tsvgr_opacity', 07:44:58 INFO - u'tart', 07:44:58 INFO - u'tscrollx', 07:44:58 INFO - u'cart']}, 07:44:58 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'talos_options': [u'--disable-e10s'], 07:44:58 INFO - u'tests': [u'tp5o']}, 07:44:58 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'tests': [u'tp5o']}, 07:44:58 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'talos_options': [u'--disable-e10s', 07:44:58 INFO - u'--xperf_path', 07:44:58 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:58 INFO - u'tests': [u'tp5n']}, 07:44:58 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:44:58 INFO - u'talos_options': [u'--xperf_path', 07:44:58 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:44:58 INFO - u'tests': [u'tp5n']}}, 07:44:58 INFO - u'talos.zip': {u'path': u'', 07:44:58 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:44:58 INFO - Running post-action listener: _resource_record_post_action 07:44:58 INFO - [mozharness: 2016-11-07 15:44:58.619000Z] Finished populate-webroot step (success) 07:44:58 INFO - [mozharness: 2016-11-07 15:44:58.619000Z] Running create-virtualenv step. 07:44:58 INFO - Running pre-action listener: _resource_record_pre_action 07:44:58 INFO - Running main action method: create_virtualenv 07:44:58 INFO - Creating virtualenv C:\slave\test\build/venv 07:44:58 INFO - mkdir: C:\slave\test\build\venv\Scripts 07:44:58 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 07:44:58 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'! 07:44:58 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 07:44:58 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 07:45:03 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 07:45:09 INFO - Installing distribute......................................................................................................................................................................................done. 07:45:10 INFO - Return code: 0 07:45:10 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 07:45:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 07:45:11 INFO - Reading from file tmpfile_stdout 07:45:11 INFO - Output received: 07:45:11 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 07:45:11 INFO - Using _rmtree_windows ... 07:45:11 INFO - Using _rmtree_windows ... 07:45:11 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 07:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:11 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:11 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 07:45:11 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 07:45:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:11 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:11 INFO - 'DCLOCATION': 'SCL3', 07:45:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:11 INFO - 'HOMEDRIVE': 'C:', 07:45:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:11 INFO - 'KTS_VERSION': '1.19c', 07:45:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:11 INFO - 'MOZ_NO_REMOTE': '1', 07:45:11 INFO - 'NO_EM_RESTART': '1', 07:45:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:11 INFO - 'OS': 'Windows_NT', 07:45:11 INFO - 'OURDRIVE': 'C:', 07:45:11 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;', 07:45:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:11 INFO - 'PROCESSOR_LEVEL': '6', 07:45:11 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:11 INFO - 'PROMPT': '$P$G', 07:45:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:11 INFO - 'PWD': 'C:\\slave\\test', 07:45:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:11 INFO - 'SYSTEMDRIVE': 'C:', 07:45:11 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:11 INFO - 'TEST1': 'testie', 07:45:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:11 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:11 INFO - 'USERNAME': 'cltbld', 07:45:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:11 INFO - 'WINDIR': 'C:\\windows', 07:45:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:45:12 INFO - Downloading/unpacking pip>=1.5 07:45:16 INFO - Running setup.py egg_info for package pip 07:45:16 INFO - warning: no files found matching 'pip\cacert.pem' 07:45:16 INFO - warning: no files found matching '*.html' under directory 'docs' 07:45:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:45:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:45:16 INFO - Installing collected packages: pip 07:45:16 INFO - Found existing installation: pip 0.8.2 07:45:16 INFO - Uninstalling pip: 07:45:16 INFO - Successfully uninstalled pip 07:45:16 INFO - Running setup.py install for pip 07:45:16 INFO - warning: no files found matching 'pip\cacert.pem' 07:45:16 INFO - warning: no files found matching '*.html' under directory 'docs' 07:45:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:45:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:45:16 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 07:45:16 INFO - Successfully installed pip 07:45:16 INFO - Cleaning up... 07:45:16 INFO - Return code: 0 07:45:16 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 07:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:16 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:16 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 07:45:16 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 07:45:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:16 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:16 INFO - 'DCLOCATION': 'SCL3', 07:45:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:16 INFO - 'HOMEDRIVE': 'C:', 07:45:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:16 INFO - 'KTS_VERSION': '1.19c', 07:45:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:16 INFO - 'MOZ_NO_REMOTE': '1', 07:45:16 INFO - 'NO_EM_RESTART': '1', 07:45:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:16 INFO - 'OS': 'Windows_NT', 07:45:16 INFO - 'OURDRIVE': 'C:', 07:45:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:16 INFO - 'PROCESSOR_LEVEL': '6', 07:45:16 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:16 INFO - 'PROMPT': '$P$G', 07:45:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:16 INFO - 'PWD': 'C:\\slave\\test', 07:45:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:16 INFO - 'SYSTEMDRIVE': 'C:', 07:45:16 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:16 INFO - 'TEST1': 'testie', 07:45:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:16 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:16 INFO - 'USERNAME': 'cltbld', 07:45:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:16 INFO - 'WINDIR': 'C:\\windows', 07:45:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:18 INFO - Downloading/unpacking psutil>=3.1.1 07:45:18 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 07:45:18 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 07:45:19 INFO - Installing collected packages: psutil 07:45:19 INFO - Successfully installed psutil 07:45:19 INFO - Cleaning up... 07:45:19 INFO - Return code: 0 07:45:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 07:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 07:45:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:45:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:19 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:19 INFO - 'DCLOCATION': 'SCL3', 07:45:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:19 INFO - 'HOMEDRIVE': 'C:', 07:45:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:19 INFO - 'KTS_VERSION': '1.19c', 07:45:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:19 INFO - 'MOZ_NO_REMOTE': '1', 07:45:19 INFO - 'NO_EM_RESTART': '1', 07:45:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:19 INFO - 'OS': 'Windows_NT', 07:45:19 INFO - 'OURDRIVE': 'C:', 07:45:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:19 INFO - 'PROCESSOR_LEVEL': '6', 07:45:19 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:19 INFO - 'PROMPT': '$P$G', 07:45:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:19 INFO - 'PWD': 'C:\\slave\\test', 07:45:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:19 INFO - 'SYSTEMDRIVE': 'C:', 07:45:19 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:19 INFO - 'TEST1': 'testie', 07:45:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:19 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:19 INFO - 'USERNAME': 'cltbld', 07:45:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:19 INFO - 'WINDIR': 'C:\\windows', 07:45:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:45:20 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 07:45:20 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 07:45:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:45:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 07:45:21 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) 07:45:21 INFO - Installing collected packages: mozsystemmonitor 07:45:21 INFO - Running setup.py install for mozsystemmonitor 07:45:21 INFO - Successfully installed mozsystemmonitor 07:45:21 INFO - Cleaning up... 07:45:22 INFO - Return code: 0 07:45:22 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 07:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:22 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:22 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 07:45:22 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 07:45:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:22 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:22 INFO - 'DCLOCATION': 'SCL3', 07:45:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:22 INFO - 'HOMEDRIVE': 'C:', 07:45:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:22 INFO - 'KTS_VERSION': '1.19c', 07:45:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:22 INFO - 'MOZ_NO_REMOTE': '1', 07:45:22 INFO - 'NO_EM_RESTART': '1', 07:45:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:22 INFO - 'OS': 'Windows_NT', 07:45:22 INFO - 'OURDRIVE': 'C:', 07:45:22 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;', 07:45:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:22 INFO - 'PROCESSOR_LEVEL': '6', 07:45:22 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:22 INFO - 'PROMPT': '$P$G', 07:45:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:22 INFO - 'PWD': 'C:\\slave\\test', 07:45:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:22 INFO - 'SYSTEMDRIVE': 'C:', 07:45:22 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:22 INFO - 'TEST1': 'testie', 07:45:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:22 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:22 INFO - 'USERNAME': 'cltbld', 07:45:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:22 INFO - 'WINDIR': 'C:\\windows', 07:45:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:22 INFO - Downloading/unpacking jsonschema==2.5.1 07:45:22 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 07:45:22 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 07:45:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:45:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:45:23 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 07:45:23 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 07:45:23 INFO - Downloading functools32-3.2.3-2.tar.gz 07:45:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 07:45:23 INFO - warning: no files found matching '*.txt' 07:45:23 INFO - no previously-included directories found matching 'build' 07:45:23 INFO - no previously-included directories found matching 'dist' 07:45:23 INFO - no previously-included directories found matching '.git*' 07:45:23 INFO - Installing collected packages: jsonschema, functools32 07:45:23 INFO - Running setup.py install for functools32 07:45:23 INFO - warning: no files found matching '*.txt' 07:45:23 INFO - no previously-included directories found matching 'build' 07:45:23 INFO - no previously-included directories found matching 'dist' 07:45:23 INFO - no previously-included directories found matching '.git*' 07:45:23 INFO - Successfully installed jsonschema functools32 07:45:23 INFO - Cleaning up... 07:45:24 INFO - Return code: 0 07:45:24 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 07:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 07:45:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:45:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:24 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:24 INFO - 'DCLOCATION': 'SCL3', 07:45:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:24 INFO - 'HOMEDRIVE': 'C:', 07:45:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:24 INFO - 'KTS_VERSION': '1.19c', 07:45:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:24 INFO - 'MOZ_NO_REMOTE': '1', 07:45:24 INFO - 'NO_EM_RESTART': '1', 07:45:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:24 INFO - 'OS': 'Windows_NT', 07:45:24 INFO - 'OURDRIVE': 'C:', 07:45:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:24 INFO - 'PROCESSOR_LEVEL': '6', 07:45:24 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:24 INFO - 'PROMPT': '$P$G', 07:45:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:24 INFO - 'PWD': 'C:\\slave\\test', 07:45:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:24 INFO - 'SYSTEMDRIVE': 'C:', 07:45:24 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:24 INFO - 'TEST1': 'testie', 07:45:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:24 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:24 INFO - 'USERNAME': 'cltbld', 07:45:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:24 INFO - 'WINDIR': 'C:\\windows', 07:45:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 07:45:25 INFO - Cleaning up... 07:45:25 INFO - Return code: 0 07:45:25 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 07:45: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')]} 07:45:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45: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')]} 07:45:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45: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', '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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45: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', 'blobuploader==1.2.4'] in C:\slave\test\build 07:45: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 blobuploader==1.2.4 07:45:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:25 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:25 INFO - 'DCLOCATION': 'SCL3', 07:45:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:25 INFO - 'HOMEDRIVE': 'C:', 07:45:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:25 INFO - 'KTS_VERSION': '1.19c', 07:45:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:25 INFO - 'MOZ_NO_REMOTE': '1', 07:45:25 INFO - 'NO_EM_RESTART': '1', 07:45:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:25 INFO - 'OS': 'Windows_NT', 07:45:25 INFO - 'OURDRIVE': 'C:', 07:45: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;', 07:45:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:25 INFO - 'PROCESSOR_LEVEL': '6', 07:45:25 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:25 INFO - 'PROMPT': '$P$G', 07:45:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:25 INFO - 'PWD': 'C:\\slave\\test', 07:45:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:25 INFO - 'SYSTEMDRIVE': 'C:', 07:45:25 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:25 INFO - 'TEST1': 'testie', 07:45:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:25 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:25 INFO - 'USERNAME': 'cltbld', 07:45:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:25 INFO - 'WINDIR': 'C:\\windows', 07:45:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:25 INFO - Downloading/unpacking blobuploader==1.2.4 07:45:25 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 07:45:25 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 07:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 07:45:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 07:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 07:45:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:45: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 07:45: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 07:45:27 INFO - Downloading docopt-0.6.1.tar.gz 07:45:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 07:45:27 INFO - Installing collected packages: blobuploader, requests, docopt 07:45:27 INFO - Running setup.py install for blobuploader 07:45:28 INFO - Running setup.py install for requests 07:45:29 INFO - Running setup.py install for docopt 07:45:29 INFO - Successfully installed blobuploader requests docopt 07:45:29 INFO - Cleaning up... 07:45:30 INFO - Return code: 0 07:45:30 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:30 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:30 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 07:45:30 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 07:45:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:30 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:30 INFO - 'DCLOCATION': 'SCL3', 07:45:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:30 INFO - 'HOMEDRIVE': 'C:', 07:45:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:30 INFO - 'KTS_VERSION': '1.19c', 07:45:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:30 INFO - 'MOZ_NO_REMOTE': '1', 07:45:30 INFO - 'NO_EM_RESTART': '1', 07:45:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:30 INFO - 'OS': 'Windows_NT', 07:45:30 INFO - 'OURDRIVE': 'C:', 07:45:30 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;', 07:45:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:30 INFO - 'PROCESSOR_LEVEL': '6', 07:45:30 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:30 INFO - 'PROMPT': '$P$G', 07:45:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:30 INFO - 'PWD': 'C:\\slave\\test', 07:45:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:30 INFO - 'SYSTEMDRIVE': 'C:', 07:45:30 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:30 INFO - 'TEST1': 'testie', 07:45:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:30 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:30 INFO - 'USERNAME': 'cltbld', 07:45:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:30 INFO - 'WINDIR': 'C:\\windows', 07:45:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:45:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dvb_6t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:45:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:45:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xv8dek-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k4n950-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-le5g2j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lowggi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t6m0po-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ec5ev-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqarvl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yrrbjb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:45:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:45:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p7znxv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:45:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:45:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eayjjz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:45:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:45:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zqm8p8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:45:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:45:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y7dzvj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:45:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:45:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lm8f8h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:45:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:45:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ysd19v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:45:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:45:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v5c91k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:45:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:45:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uiswwi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:45:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:45:34 INFO - Running setup.py install for manifestparser 07:45:34 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 07:45:34 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 07:45:34 INFO - Running setup.py install for mozcrash 07:45:35 INFO - Running setup.py install for mozdebug 07:45:35 INFO - Running setup.py install for mozdevice 07:45:35 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 07:45:35 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 07:45:35 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 07:45:35 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 07:45:36 INFO - Running setup.py install for mozfile 07:45:36 INFO - Running setup.py install for mozhttpd 07:45:36 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 07:45:36 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 07:45:36 INFO - Running setup.py install for mozinfo 07:45:37 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Running setup.py install for mozInstall 07:45:37 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 07:45:37 INFO - Running setup.py install for mozleak 07:45:38 INFO - Running setup.py install for mozlog 07:45:38 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 07:45:38 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 07:45:38 INFO - Running setup.py install for moznetwork 07:45:39 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 07:45:39 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 07:45:39 INFO - Running setup.py install for mozprocess 07:45:39 INFO - Running setup.py install for mozprofile 07:45:40 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Running setup.py install for mozrunner 07:45:40 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 07:45:40 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 07:45:41 INFO - Running setup.py install for mozscreenshot 07:45:41 INFO - Running setup.py install for moztest 07:45:42 INFO - Running setup.py install for mozversion 07:45:42 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 07:45:42 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 07:45:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:45:42 INFO - Cleaning up... 07:45:42 INFO - Return code: 0 07:45:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:42 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:42 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 07:45:42 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 07:45:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:42 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:42 INFO - 'DCLOCATION': 'SCL3', 07:45:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:42 INFO - 'HOMEDRIVE': 'C:', 07:45:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:42 INFO - 'KTS_VERSION': '1.19c', 07:45:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:42 INFO - 'MOZ_NO_REMOTE': '1', 07:45:42 INFO - 'NO_EM_RESTART': '1', 07:45:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:42 INFO - 'OS': 'Windows_NT', 07:45:42 INFO - 'OURDRIVE': 'C:', 07:45:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:42 INFO - 'PROCESSOR_LEVEL': '6', 07:45:42 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:42 INFO - 'PROMPT': '$P$G', 07:45:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:42 INFO - 'PWD': 'C:\\slave\\test', 07:45:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:42 INFO - 'SYSTEMDRIVE': 'C:', 07:45:42 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:42 INFO - 'TEST1': 'testie', 07:45:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:42 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:42 INFO - 'USERNAME': 'cltbld', 07:45:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:42 INFO - 'WINDIR': 'C:\\windows', 07:45:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:45:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xsbx5q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-btji6d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-arx_ff-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tq5me6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-az1bqq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tcszka-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fmafvx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:45:43 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)) 07:45:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:45:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7ct6vc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqeqhg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2jxgkp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zc68h1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ufjuyd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-igbcy8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l0mxmo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:45:44 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)) 07:45:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:45:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rrmq_e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:45:45 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)) 07:45:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:45:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-whcjqj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:45:45 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)) 07:45:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:45:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u67i45-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:45:45 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)) 07:45:45 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)) 07:45:45 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)) 07:45:45 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)) 07:45:45 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)) 07:45:45 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)) 07:45:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:46 INFO - Downloading blessings-1.6.tar.gz 07:45:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 07:45:46 INFO - Installing collected packages: blessings 07:45:46 INFO - Running setup.py install for blessings 07:45:47 INFO - Successfully installed blessings 07:45:47 INFO - Cleaning up... 07:45:49 INFO - Return code: 0 07:45:49 INFO - Done creating virtualenv C:\slave\test\build/venv. 07:45:49 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:45:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:45:50 INFO - Reading from file tmpfile_stdout 07:45:50 INFO - Using _rmtree_windows ... 07:45:50 INFO - Using _rmtree_windows ... 07:45:50 INFO - Current package versions: 07:45:50 INFO - blessings == 1.6 07:45:50 INFO - blobuploader == 1.2.4 07:45:50 INFO - docopt == 0.6.1 07:45:50 INFO - functools32 == 3.2.3-2 07:45:50 INFO - jsonschema == 2.5.1 07:45:50 INFO - manifestparser == 1.1 07:45:50 INFO - mozInstall == 1.12 07:45:50 INFO - mozcrash == 0.17 07:45:50 INFO - mozdebug == 0.1 07:45:50 INFO - mozdevice == 0.48 07:45:50 INFO - mozfile == 1.2 07:45:50 INFO - mozhttpd == 0.7 07:45:50 INFO - mozinfo == 0.9 07:45:50 INFO - mozleak == 0.1 07:45:50 INFO - mozlog == 3.4 07:45:50 INFO - moznetwork == 0.27 07:45:50 INFO - mozprocess == 0.23 07:45:50 INFO - mozprofile == 0.28 07:45:50 INFO - mozrunner == 6.12 07:45:50 INFO - mozscreenshot == 0.1 07:45:50 INFO - mozsystemmonitor == 0.3 07:45:50 INFO - moztest == 0.7 07:45:50 INFO - mozversion == 1.4 07:45:50 INFO - psutil == 3.1.1 07:45:50 INFO - requests == 1.2.3 07:45:50 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:50 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:50 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 07:45:50 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 07:45:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:50 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:50 INFO - 'DCLOCATION': 'SCL3', 07:45:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:50 INFO - 'HOMEDRIVE': 'C:', 07:45:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:50 INFO - 'KTS_VERSION': '1.19c', 07:45:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:50 INFO - 'MOZ_NO_REMOTE': '1', 07:45:50 INFO - 'NO_EM_RESTART': '1', 07:45:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:50 INFO - 'OS': 'Windows_NT', 07:45:50 INFO - 'OURDRIVE': 'C:', 07:45:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:50 INFO - 'PROCESSOR_LEVEL': '6', 07:45:50 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:50 INFO - 'PROMPT': '$P$G', 07:45:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:50 INFO - 'PWD': 'C:\\slave\\test', 07:45:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:50 INFO - 'SYSTEMDRIVE': 'C:', 07:45:50 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:50 INFO - 'TEST1': 'testie', 07:45:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:50 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:50 INFO - 'USERNAME': 'cltbld', 07:45:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:50 INFO - 'WINDIR': 'C:\\windows', 07:45:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 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)) 07:45:50 INFO - Cleaning up... 07:45:50 INFO - Return code: 0 07:45:50 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 07:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:50 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 0x018CF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01975E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 07:45:50 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 07:45:50 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 07:45:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:50 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:50 INFO - 'DCLOCATION': 'SCL3', 07:45:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:50 INFO - 'HOMEDRIVE': 'C:', 07:45:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:50 INFO - 'KTS_VERSION': '1.19c', 07:45:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:50 INFO - 'MOZ_NO_REMOTE': '1', 07:45:50 INFO - 'NO_EM_RESTART': '1', 07:45:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:50 INFO - 'OS': 'Windows_NT', 07:45:50 INFO - 'OURDRIVE': 'C:', 07:45:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:45:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:50 INFO - 'PROCESSOR_LEVEL': '6', 07:45:50 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:50 INFO - 'PROMPT': '$P$G', 07:45:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:50 INFO - 'PWD': 'C:\\slave\\test', 07:45:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:50 INFO - 'SYSTEMDRIVE': 'C:', 07:45:50 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:50 INFO - 'TEST1': 'testie', 07:45:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:50 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:50 INFO - 'USERNAME': 'cltbld', 07:45:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:50 INFO - 'WINDIR': 'C:\\windows', 07:45:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 07:45:50 INFO - Cleaning up... 07:45:50 INFO - Return code: 0 07:45:50 INFO - Running post-action listener: _resource_record_post_action 07:45:50 INFO - Running post-action listener: _start_resource_monitoring 07:45:50 INFO - Starting resource monitoring. 07:45:51 INFO - [mozharness: 2016-11-07 15:45:51.061000Z] Finished create-virtualenv step (success) 07:45:51 INFO - [mozharness: 2016-11-07 15:45:51.061000Z] Running install step. 07:45:51 INFO - Running pre-action listener: _resource_record_pre_action 07:45:51 INFO - Running main action method: install 07:45:51 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:45:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:45:51 INFO - Reading from file tmpfile_stdout 07:45:51 INFO - Using _rmtree_windows ... 07:45:51 INFO - Using _rmtree_windows ... 07:45:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:45:51 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 07:45:51 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 07:45:51 INFO - Reading from file tmpfile_stdout 07:45:51 INFO - Output received: 07:45:51 INFO - Usage: mozinstall-script.py [options] installer 07:45:51 INFO - Options: 07:45:51 INFO - -h, --help show this help message and exit 07:45:51 INFO - -d DEST, --destination=DEST 07:45:51 INFO - Directory to install application into. [default: 07:45:51 INFO - "C:\slave\test"] 07:45:51 INFO - --app=APP Application being installed. [default: firefox] 07:45:51 INFO - Using _rmtree_windows ... 07:45:51 INFO - Using _rmtree_windows ... 07:45:51 INFO - mkdir: C:\slave\test\build\application 07:45:51 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'] 07:45:51 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 07:45:53 INFO - Reading from file tmpfile_stdout 07:45:53 INFO - Output received: 07:45:53 INFO - C:\slave\test\build\application\firefox\firefox.exe 07:45:53 INFO - Using _rmtree_windows ... 07:45:53 INFO - Using _rmtree_windows ... 07:45:53 INFO - Running post-action listener: _resource_record_post_action 07:45:53 INFO - [mozharness: 2016-11-07 15:45:53.643000Z] Finished install step (success) 07:45:53 INFO - [mozharness: 2016-11-07 15:45:53.643000Z] Running run-tests step. 07:45:53 INFO - Running pre-action listener: _resource_record_pre_action 07:45:53 INFO - Running main action method: run_tests 07:45:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 07:45:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 07:45:53 INFO - Python 2.7.3 07:45:53 INFO - Return code: 0 07:45:53 INFO - grabbing minidump binary from tooltool 07:45:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:53 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 0x01982E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0139A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E7E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 07:45:53 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 07:45:53 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 07:45:53 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 07:45:53 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 07:45:55 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmparkpca 07:45:55 INFO - INFO - File integrity verified, renaming tmparkpca to win32-minidump_stackwalk.exe 07:45:55 INFO - Return code: 0 07:45:55 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 07:45:55 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 07:45:55 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 07:45:55 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 07:45:55 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 07:45:55 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build 07:45:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Mozilla-Inbound-Non-PGO --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log 07:45:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:45:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:45:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:45:55 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 07:45:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:45:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:45:55 INFO - 'DCLOCATION': 'SCL3', 07:45:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:45:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:45:55 INFO - 'HOMEDRIVE': 'C:', 07:45:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:45:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:45:55 INFO - 'KTS_VERSION': '1.19c', 07:45:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:45:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 07:45:55 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:45:55 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 07:45:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:45:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:55 INFO - 'MOZ_NO_REMOTE': '1', 07:45:55 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:45:55 INFO - 'NO_EM_RESTART': '1', 07:45:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:45:55 INFO - 'OS': 'Windows_NT', 07:45:55 INFO - 'OURDRIVE': 'C:', 07:45:55 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;', 07:45:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:45:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:45:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:45:55 INFO - 'PROCESSOR_LEVEL': '6', 07:45:55 INFO - 'PROCESSOR_REVISION': '1e05', 07:45:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:45:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:45:55 INFO - 'PROMPT': '$P$G', 07:45:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:45:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:45:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:45:55 INFO - 'PWD': 'C:\\slave\\test', 07:45:55 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 07:45:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:45:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:45:55 INFO - 'SYSTEMDRIVE': 'C:', 07:45:55 INFO - 'SYSTEMROOT': 'C:\\windows', 07:45:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:55 INFO - 'TEST1': 'testie', 07:45:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:45:55 INFO - 'USERDOMAIN': 'T-W732-IX-009', 07:45:55 INFO - 'USERNAME': 'cltbld', 07:45:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:45:55 INFO - 'WINDIR': 'C:\\windows', 07:45:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:45:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:45:55 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600 07:45:57 INFO - mozversion application_buildid: 20161104184821 07:45:57 INFO - mozversion application_changeset: b4ade2b0841c5825968f34752d86ee09507a0a9f 07:45:57 INFO - mozversion application_display_name: Nightly 07:45:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:45:57 INFO - mozversion application_name: Firefox 07:45:57 INFO - mozversion application_remotingname: firefox 07:45:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:45:57 INFO - mozversion application_vendor: Mozilla 07:45:57 INFO - mozversion application_version: 52.0a1 07:45:57 INFO - mozversion platform_buildid: 20161104184821 07:45:57 INFO - mozversion platform_changeset: b4ade2b0841c5825968f34752d86ee09507a0a9f 07:45:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:45:57 INFO - mozversion platform_version: 52.0a1 07:45:57 INFO - using testdate: 1478533557 07:45:57 INFO - actual date: 1478533557 07:45:57 INFO - starting webserver on 'localhost:49892' 07:45:57 INFO - SUITE-START | Running 2 tests 07:45:57 INFO - TEST-START | dromaeo_css 07:45:57 INFO - operating with platform_type : w7_ 07:45:57 INFO - Initialising browser for dromaeo_css test... 07:45:57 INFO - TEST-INFO | started process 2364 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvxxalt\profile http://localhost:49892/getInfo.html) 07:46:06 INFO - PROCESS | 2364 | __metrics Screen width/height:1600/1200 07:46:06 INFO - PROCESS | 2364 | colorDepth:24 07:46:06 INFO - PROCESS | 2364 | Browser inner width/height: 1010/674 07:46:06 INFO - PROCESS | 2364 | __metrics 07:46:07 ERROR - PROCESS | 2364 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 07:46:07 INFO - PROCESS | 2364 | [Child 2696] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-000000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 07:46:08 INFO - TEST-INFO | 2364: exit 0 07:46:08 INFO - Browser initialized. 07:46:08 INFO - Running cycle 1/1 for dromaeo_css test... 07:46:08 INFO - TEST-INFO | started process 488 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvxxalt\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:08 INFO - PROCESS | 488 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 07:46:19 INFO - PROCESS | 488 | RSS: Main: 145043456 07:46:19 INFO - PROCESS | 488 | 07:47:09 INFO - PROCESS | 488 | 1478533629486 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] 07:49:24 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49892/tests/dromaeo/cssquery-ext.html) 07:49:26 INFO - PROCESS | 488 | RSS: Main: 123699200 07:49:26 INFO - PROCESS | 488 | 07:52:28 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-ext.html (next: http://localhost:49892/tests/dromaeo/cssquery-jquery.html) 07:52:29 INFO - PROCESS | 488 | RSS: Main: 91344896 07:52:29 INFO - PROCESS | 488 | 07:55:31 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49892/tests/dromaeo/cssquery-mootools.html) 07:55:31 INFO - PROCESS | 488 | RSS: Main: 83255296 07:55:31 INFO - PROCESS | 488 | 07:58:33 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49892/tests/dromaeo/cssquery-prototype.html) 07:58:34 INFO - PROCESS | 488 | RSS: Main: 80601088 07:58:34 INFO - PROCESS | 488 | 08:01:36 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49892/tests/dromaeo/cssquery-yui.html) 08:01:37 INFO - PROCESS | 488 | RSS: Main: 87302144 08:01:37 INFO - PROCESS | 488 | 08:04:39 INFO - PROCESS | 488 | Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-yui.html (next: http://localhost:49892/tests/dromaeo/cssquery-dojo.html) 08:04:40 INFO - PROCESS | 488 | RSS: Main: 77168640 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | __start_tp_report 08:04:40 INFO - PROCESS | 488 | _x_x_mozilla_page_load 08:04:40 INFO - PROCESS | 488 | _x_x_mozilla_page_load_details 08:04:40 INFO - PROCESS | 488 | |i|pagename|runs| 08:04:40 INFO - PROCESS | 488 | |0;dojo.html;2434.1115492845115;2439.999600163932;2444.278937713375;2451.7180524239707;2453.092355828344;4251.256030194719;4262.126264115856;4272.893177670556;4276.080642661827;4335.046289816243;4267.082577245891;4315.158544083905;4323.286657701483;4381.145676593065;4433.689641725081;3641.453781932718;3711.647393497627;3715.9071023224365;3748.625137486257;3788.450674652174;3309.5035744638258;3671.541057367829;3739;3758.191988722424;3787.6780473659774;3668.101315177772;3729.9440508392395;3764.7741135531955;3765.3787125124595;3787.2425514896995;3556.5732112146447;3603.8198090095652;3614.2229420674416;3631.2011357501306;3654.7258955578436;3524.7004004659634;3612.259486805199;3630.2195028068827;3635.7818530887967;3636.9272614547826;4174.666026717855;4184.539700632928;4188.183304255671;4191.91616167678;4203.3064544350145;4434.334849772527;4443.111377724468;4453.9109217815785;4466.397036400077;4466.57567531084;4416.3375493675885;4431.734095954221;4459.0858873930765;4464.441944756905;4478.686491945565;4088.591140885947;4188.68584856137;4189.518205406387;4191.8532851350665;4197.517285512145;4518.2319005769095;4570.382998295254;4577.908441831211;4611.723296602215;4611.985363220087;4402.427684401071;4415.050764085737;4473.955260447419;4477.32840073985;4496.100779843979;4465.955340446554;4530.411046563926;4538.047010127844;4544.250198717241;4581.037982023746;4310.051788606502;4412.713173643767;4425;4462.754548499799;4464.374987501752;4423.955760442354;4440.7335559866515;4444.711093778894;4449.13241917823;4459.777011149429;3173.4605117130145;3182.8885988990273;3191.505316675919;3191.856366463515;3193.425183466998;2874.6406699162603;2887.509123448977;2890.407466469369;2890.682024977251;2893.4357800229172;4218.49378074633;4256.808443620044;4274.572542745701;4277.422547956048;4310.137972405469;4320.984568626371;4542.5911667949385;4570.337301091323;4578.97710511452;4595.310703394518;3986;4171.186618609403;4291.763952982616;4358.6730995175485;4380;3685;3783.981080094582;3787.0910981364823;3906.5898080701686;3909.8240579174008;4538;4720.787564559534;4728.527147285244;4780.187368147493;4781.737004464788;4327.437433133672;4422.557744225552;4613.308003799457;4646.465656449416;4646.7444290564345;4490.461144662661;4598.655100867381;4689.507601701772;4780.95219047805;4788.832390866442;4357.7385356878685;4467.061916997467;4470.083632855192;4503.527129651339;4540.704854184468;4532.27483602622;4534.183846907588;4609.815607375802;4620.630349572093;4629.513901040477;4333.674974376998;4390;4451.977740111279;4481.775911204492;4487.842925497592;4281.272183728711;4302.032042790347;4312.202242585132;4319.740815551077;4352.477702675699;4235.385869049032;4283.357496375568;4292.527821939647;4297.484301883794;4329.740215587075;4365.236083685406;4365.388845561687;4453;4453.688241823042;4457.50967393593;4267.061246525759;4303.13937212565;4303.956960430356;4341.370501277234;4394.582514661102 08:04:40 INFO - PROCESS | 488 | |1;ext.html;487.94388645305787;510.6553076673247;513.286531720908;523.9442523315518;535.5528134008108;33929;34804.30391392171;34912.82543587285;37209.441858372076;37348.81325593368;35276.647233527656;35788.82105589475;35798.463023054675;35799.46300805484;35805.2838943221;6796.08252885859;7015.088038554981;7021;7030.929690703104;7033.542819716702;6933.445324374064;7019.262977387378;7026.1217347831525;7029.648517574101;7033.191183013968;7029.297070292955;7054.329838665319;7058.364747172754;7087.043249161375;7090.610016449094;6978.581285122884;7057.788266352018;7061.08205933228;7063.258357872401;7068.939659051132;6810.659467026629;6865.244823069458;6893.4140598049225;6895.827604309882;6902.930970690279;27279;32871;32899.6710032899;32988;33008.17479563006;2660.3083198368563;2684.087410280514;2701.513727529044;3076.2770748873995;3170.7621928355234;25195.748042519524;25297.4583558302;25512.23463296104;26047;26086.478270434483;2924.3274046969104;3018.24543864034;3049.374878149996;3055.58597755852;3211.550382946389;204077;204254.99999999852;204282;205127;205842;44464.55535444669;46120.308195377096;46129.538704612525;46239;46264.53735462669;48098.759506202245;48128.759356202994;48168;48283;48392.5160748388;9899.455529945923;9904.504774761233;9949.253805964581;9978.950105249573;9999.750006249757;8110.594470276463;8125.1062383137805;8143.959280203561;8159.551224682581;8179.550124743197;15170.620734481727;15465.613359666098;15498.5350439487;15557.22213889301;15736.213189340488;6049.213602231682;6211.254649442096;6221.377862213742;6250.531210159256;6269.840079585291;706.8833642449041;708.1608294171423;770.6493545436813;776.8096816280048;877.6708734224666;21518.892405538187;24055.879720601286;24177.220215173347;24299.569503680737;24694.382640433774;760.262545331028;765.2118318132333;772.420684486635;831.9958400208081;864.2351518905688;12534.247945123321;12963.481460741654;13029.47882084725;13034.934825326003;13051.673708157183;284223;288471;288597;289426;289784;41731;41991;42052;42181.78909105435;42305;1034.1468288661883;1049.6011515624166;1069.0645685025602;1071.6999240212754;1072.3726619927274;24225;24417.38956526101;25006.37484062913;25094.12170574021;25216.117435890388;36145.45781813222;36921.81539092287;36969.26061478702;37056.629433706396;37076;280924;284206;284787;285102;285916;20387.388378348674;21106.894465527574;21385.03767330443;21423;21436.785632143477;33942.83028584841;36595.634043660284;36615.45076823903;36622.45066323954;36715.49267609773;30479.390412191187;31406.37187256288;31421.595794078254;31633.5254971171;32248.83875580607;30692;31391.529127062655;31474.52788208133;31512.212194695316;32275.677243227266;25266.621000685373;30011.849940750155;30112.54831177578;30471.847640761654;30681.693183068775;25214.369640758396;28139.71860281371;28781.424371512872;30085.69914300829;30209.395812083196;21431;22137.889310553343;22634;22680.319590412313;23749 08:04:40 INFO - PROCESS | 488 | |2;jquery.html;303.5931851319232;304.1559671910448;311.6634035241939;313.55005567011335;319.913623321703;11024.559017639285;11109.833352499698;14126.788098178522;15291.929564930446;15317.157556334398;11496.080313574916;11654.708632284197;11719.941400292986;15215.39138434464;15338.539843804676;4504.572065653758;4549.090181963612;4567.543245675422;4570.085982803453;4578.450585929693;4507.819687212507;4519.186546421643;4569;4570.680052396334;4575.33657619645;4398.384226208317;4474.753888536129;4536.206163921317;4537;4538.523455037223;4520.751358675271;4562.087582483517;4568.2462393705;4581.083783243364;4581.79381927814;4001.239764444746;4041.5756345583586;4046;4048.3117869962175;4051.7771522566245;17065.7440138398;18247.817521824745;18816.81183188178;18929.9053504733;18946.336878209197;1518.8860835437386;1540.9675517403366;1545.1964978211379;1549.5893588199135;1577.5188567486912;8062.717804876801;8077.071136819282;8266.504009759374;8339.916600834036;8428.367872409532;1539.0381011867582;1539.5612250508552;1553.2311505804587;1557.244736302892;1613.3627217249239;61570;61874;63633.363666363206;63796.68101659508;65679.67160164217;8190.836183276301;8293.502389856507;8404.327653787683;8606.483610983361;8611.61247743853;11122.666320010412;11138.164637652208;11141.387223702612;11143.33140011602;11147.832782508269;5683.857903552362;5794.188813566104;5869.735861886225;5875.676837773964;5882.323532793784;5481.260029895992;5510.1459273812625;5565.888682226333;5588.91616625751;5612.719364031782;3410.9829450852585;3600.801955892451;3615.5661320641693;3633.691136253395;3656.835442405098;1565.9921700391426;1597.8641815445585;1603.7033148867497;1630.9021458712516;1650.867930565552;692.5567636712508;714.628393235525;716.0941409117471;717.5336031579515;728.3735987051132;14742.336594853257;15230.162341071347;15303.158326292161;15648.4523041693;15676.529704108894;705.5343473307593;745.3031415626407;747.4767662636176;776.0454640791858;787.4881327137292;8416.83166336682;8557.101504341954;8607.397482176188;8733.34499912509;8852.778680533038;214387;216652;218489;264225;276854;44422.777886110365;73406;74588.62705686437;75501.62249188719;75750;775.8564665536894;804.0753133896067;807.0436532708759;807.4428644235342;809.4576633655465;13849.515266965607;14002.36989335462;14038.1577105374;14273.214973176575;14310.427582896567;32002.67997319997;33347.49978750272;34795.47806782951;35110.473342899364;37417.251654967295;46475;50478.495215048846;50551.49448505468;51960.74019629878;52302.384880756574;6297.779577714758;6365.2361716594305;6377.8724425512155;6392.20097487814;6508.316626754123;7116.928830711627;9944.950275248577;10365;10466.947665261929;10490.947545262225;6567.638779867153;8555.529445880537;8626.194905054183;8890.288776897962;9105.544722763967;8609.139086091342;8724.52015139148;8981.236594889508;9145.131212534798;9315.25477961775;8748.431351962103;8781.165789250012;9029.77425564366;9038.457692538468;9226.769330766785;6502.187226596676;6563.113979612691;6826.965865170642;6842.178938527409;7171.175314838861;7029.332213439715;8660.17728315809;8756.343274254328;8869.334799889904;8904 08:04:40 INFO - PROCESS | 488 | |3;mootools.html;1173.3370645585244;1186.9109816763744;1229.1088960503632;1407.9014468987168;1425.7647488164453;5396.325459317585;5593.580481463896;5782.335031471377;6064.151018857364;6105.633661980279;5795.4784069433745;5992.071228959508;5995.730192141354;6006.7597296108215;6106.969465152679;1786.8838525495867;1825.3155066849931;1832.1388947194798;1834.9633007339846;1835.7429959805638;1802.134975211899;1826.5524946388155;1830;1830.7253911913253;1833.4499650104915;1771.2737777511159;1782.3850771483817;1788.651213013463;1803.990980045098;1805.088430342675;1683.1415977851261;1687.6034131978975;1706.9146542672818;1717.9999999999875;1730.3078768492514;1680.882338236324;1690.653416049707;1692.593777493405;1696.2451708989504;1704.3608646757466;5763.394843541451;5781.682007489587;5838.241028666246;5847.44449277318;5880.382559831199;3534.3991521441158;3594.8741794037346;3621.692156166729;3622.4023036198996;3627.909302267438;2326.26722582386;2355.8350915435926;2409.6024156014237;2427.3324835670155;2429.2712186344024;728.9744858929965;759.0966749567997;759.2028370211256;765.7128576783706;775.3448336155939;7731.45879788415;7876.921230787676;7952.20477952206;7967.083785364699;7986.121526632066;3217.887373941901;3292.456744637156;3307.140143562691;3307.7849939753837;3320.5683261176378;6903.1716194057035;6910;6913.75801846943;6925.445964322842;6927.411170050602;3721.9069523262133;3734.4958430612064;3734.8692795752563;3735.7571757835653;3739.6447337502896;2900.2894290898867;2995.7303842654264;3402.914927126792;3451.2924850405607;3452.102453362144;2792.3664656176365;2907.6220091387986;2909.723576260252;2914.6502419709345;2942.823430594171;965.5220665770488;976.2141476111798;979.8873129590046;984.0602224875227;984.3159004491953;723.10335184371;726.3354031061625;739.1832025611621;740.0970815604953;774.3727580659684;4110.116324990141;4161.542230354658;4188.392683060939;4243.917800960735;4274.679399045084;742.8142964258935;756.7729681095649;758.5524540521119;774.082711986298;783.7296132834163;2917.1394438640227;2967.1246982139887;2999.0852789898854;3006.9398612027635;3053.4656435123766;6822.4200942919415;7134.92865071353;7252.782416527512;7256.528325658815;7261.164966028869;7101.041359416413;7107.076080109553;7140.821479462951;7141.714331426789;7147.89278160828;5600.327960644749;5689.2319536862;5694.459026392569;5698.344690360579;5713.457221563945;4110.321796903478;4157.854475093357;4161.479815023122;4175.081482073939;4275.1663425631705;6593.472522198309;6595.142631457879;6609.20689517261;6617.106690596708;6624.6025238485845;6691.631960242234;6794.082798822095;6822.420094292041;6835.384815366641;6856.965715171392;5320.547753441001;5320.547753441001;5371.14695430076;5382.869597384593;5393.056215162409;5761;5792.507636850746;5870.735816888165;5881.941180588139;5890.676012819336;5381.27352807366;5389.622726409114;5450.31871016123;5485.4788795064405;5544.584156188383;5268.446813084724;5294.205869119663;5328.733563321896;5348.1442969124755;5373.704446255493;5008.1235783736975;5027.698338099725;5075.593952483866;5097.337346144977;5101.591872650105;3689.760165589228;3739.1399978004665;3753.887383378503;3774.5659249186697;3827.2536855312946;3817.541894972621;3824.254270417242;3866.226754649025;3876.4572959786215;3877.961220387873 08:04:40 INFO - PROCESS | 488 | |4;prototype.html;240.78088939065455;244.37196405238538;250.43152044858172;252.259618021108;253.18222142479777;4534.893597885451;4613.054323863608;4619.2609182530805;4636.453113368272;4661.394018777562;4547.113312903985;4550.249208880531;4578.221702310607;4582.945922437833;4597.747123908183;3276;3278.639349671534;3282.1138292660967;3283.343331333731;3284.458064419368;3255.251292202795;3281.032095531824;3284.7536434767367;3284.8521816518196;3287.4411350070427;3127.4839651457482;3236.4336241157703;3239.4492936200904;3249.8700051997894;3258.0877354340705;3090.474619314722;3238.2875767331147;3247.805131692094;3255.7232635226032;3256.5929258842643;2801.341684704093;2802.8038037337396;2805.340744924941;2808.8595570221405;2822.3226425657695;4389.912201755979;4426.889327766831;4446.866594002185;4451.488078870939;4458.175237581057;3873.1285460771383;3883.4951456310782;3886.514185726784;3887.378019516864;3894.4547763313158;4722.7402492862875;4733.106757297074;4755.83354582588;4763.523307774566;4767.666263361566;3025.4705426550267;3134.3417882244757;3195.1308484536576;3196.648368679467;3198.1524895902603;4905.975470122627;4910.288008238821;4930.506949305077;4942.456329803755;4948.812285051597;3227;3228.1768149122295;3234.6118465784257;3242.643309235982;3242.918927026796;3275.213948652354;3276.885309014173;3277.1643230975947;3280.294246991599;3287.342531493711;3843.5964223756105;3848.461215429842;3856.5179352580926;3861.3049651062515;3873.1866308075055;4612.654050946193;4719.513353293702;4740.834070807505;4759.928601070987;4768.523147685273;1101.8457415961773;1103.6688993301977;1104.364990130678;1105.5080489182396;1108.8724796648328;723.3815088099705;737.5943231222806;743.4164181117796;744.0624812735991;746.8804991201382;4456.665750068758;4531.297648864365;4541.250693635579;4610.308453731967;4639;4914.852554423373;4918.975405122951;4923.286123512071;4932.704037757746;4939.259111133359;4612.331211974245;4623.375844261049;4624.329472226508;4633.212353899845;4652.488226295105;2452.264320703782;2549.9290298074484;2564.34609174663;2567.7047139578813;2568.7174410814796;4936.667099883083;4950.925736113961;4962.652614317035;4968.838555228487;4993.80024799004;4888.511148885154;4911.582515486224;4918.360613120271;4924.965757190958;4931.457539670633;4966.975165124151;4973.651844370859;4985.80340718225;4989.775460104304;5007.4241462231585;4624.630029597557;4737.810487580458;4784.521547845187;4815.470298267258;4948.35671362735;4840.394950631171;4852.336485953083;4852.733099679552;4855.247436647325;4871.464138944784;5110.795568177231;5132.717700526507;5149.330587023663;5154.7938082476285;5163.793448262028;3246.3994161080277;3260.657630948811;3269.133679574962;3270.0680306112645;3272.9836350818096;4664.6501512387385;4897.436794768647;4931.235658472942;4931.260310953386;4954.578860796729;4691.366665500113;4722.1028004679;4736.218523943511;4739.080672327713;4744.857654270378;4721.716696998191;4751.311059896365;4755.976220118877;4757.500462451393;4770.666053376231;4649.209634362234;4661.137689527448;4661.883452913568;4689.601383882272;4692.389989301369;4669.206234940137;4783.425988881218;4788.377510923558;4815.109204797123;4829.5653391194965;4585.587297143273;4700.720150726826;4722.456917454399;4730.385049943486;4744.217204161275 08:04:40 INFO - PROCESS | 488 | |5;yui.html;552.4558310064585;553.8919910617432;563;567.3957235544145;571.0891128649805;10740.140788736902;11513.539458421674;11537.65387038389;11551.364674942875;11563.421828908542;10885.727856803584;11015.118790496761;11068.501917413716;11081.889181108187;11168.609098681547;610.3133974278936;621.6984762390238;621.7481919822479;622.0575827621158;622.5082185073809;614.2874265851613;615.1725928626013;615.3047056825781;626.139058794158;631.059721015688;659.967001649918;661.7816120680188;662.1822049768556;664.103460328555;664.9634270115142;651.4592887903053;651.8337823854911;652.9967350163266;654.1822721597955;654.3521913305817;481.1075455030937;481.7759741720117;481.93252944587783;482.35364611420516;482.4042307749926;11622.76754464906;11692.123090768226;11734.589289374831;11737.295762254207;11787.17489775716;10747.355158690425;11313.434328283634;11436.885631143665;11565.537378504934;11711.882881171165;8762.912370876273;9378.624855005792;9430.198433133191;9461.858072128924;9642.855357169648;2895.7683385329333;2937;2938.147937098239;2967.302683869289;3046.8628911698806;47943.169658780185;48393.75803120997;48490.757546212044;49078.50921490811;49418.25872611914;8298.128696486903;8312;8439.451435656663;8790.780230494289;9108;13469.326533673473;13561.525346613018;13812.171269723647;13879.097858639156;13970.511032113827;5613.129964855454;5635.8591035224445;5736.368999410061;5781.075027995501;5799.507041901401;3133.435981523348;3145.732612727944;3156.4949608063057;3193.1059303395086;3250.593675790526;2235.7764223577838;2317.8029867461455;2323.082382458953;2323.686302349161;2575.4720282342073;432.4507874998734;433.3737748952796;447.11248172377805;447.5189171640499;447.82087165133555;546.8660178256353;555.5888642404592;562.3954249182145;563.1946316767062;573.5010540829421;7111.324424179695;7202.819929501699;7208.855822883513;7372.373348265351;7434.888476672854;532.6777299733683;548.8435795798179;550.9641873278224;564.9887002260014;566.3826429192201;3265.5264986576813;3434.055634700477;3436.759426840097;3437.656234376542;3506.8071256080652;59232.407675923554;61949;62241.377586223556;65572;66525.66737166283;46665;50748.492515075115;52818.80133495879;55791.16313255223;56031.71984140135;42454.57545424568;49541.752291238314;50249;50432.199080767154;52543.474565254626;7477.88783168242;7536.417352356067;7593.924060759322;7696.191899850435;7697.807554810951;34957.65042349646;36073.278534429686;36153.81923090473;36586.63413365832;37013;43267.56732432635;49028.754856226915;49280;49719.75140124421;49946.75026624843;9384.859227111736;9451.338406311766;9693.41839489633;9709;9829.606815727291;10967.725806854893;11212.103031757277;11261.831072533754;11673.591424300108;11844.348560829238;10781;10892.237543372205;10933.180011499011;11189.888101118884;11406.828897566376;10640.308379955279;10743.89256107429;10934.79717231088;11182.888171118184;11282.717932051435;10052.798944021224;10140.898591014291;10162.39025658463;10262.179025677779;10390.376577405379;8213.95893020531;8480.660773568989;8620.482771033758;8954.910450895408;9078.228350590274;8484.915150848412;8533.402661813614;8810.295176386002;8872.822543548964;9042.231410330196 08:04:40 INFO - PROCESS | 488 | __end_tp_report 08:04:40 INFO - PROCESS | 488 | __start_cc_report 08:04:40 INFO - PROCESS | 488 | _x_x_mozilla_cycle_collect,306 08:04:40 INFO - PROCESS | 488 | __end_cc_report 08:04:40 INFO - PROCESS | 488 | __startTimestamp1478534680218__endTimestamp 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | ------- Summary: start ------- 08:04:40 INFO - PROCESS | 488 | Number of tests: 6 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#0] dojo.html Cycles:165 Average:4143.65 Median:4323.29 stddev:526.30 (12.2%) stddev-sans-first:510.54 08:04:40 INFO - PROCESS | 488 | Values: 2434.1 2440.0 2444.3 2451.7 2453.1 4251.3 4262.1 4272.9 4276.1 4335.0 4267.1 4315.2 4323.3 4381.1 4433.7 3641.5 3711.6 3715.9 3748.6 3788.5 3309.5 3671.5 3739.0 3758.2 3787.7 3668.1 3729.9 3764.8 3765.4 3787.2 3556.6 3603.8 3614.2 3631.2 3654.7 3524.7 3612.3 3630.2 3635.8 3636.9 4174.7 4184.5 4188.2 4191.9 4203.3 4434.3 4443.1 4453.9 4466.4 4466.6 4416.3 4431.7 4459.1 4464.4 4478.7 4088.6 4188.7 4189.5 4191.9 4197.5 4518.2 4570.4 4577.9 4611.7 4612.0 4402.4 4415.1 4474.0 4477.3 4496.1 4466.0 4530.4 4538.0 4544.3 4581.0 4310.1 4412.7 4425.0 4462.8 4464.4 4424.0 4440.7 4444.7 4449.1 4459.8 3173.5 3182.9 3191.5 3191.9 3193.4 2874.6 2887.5 2890.4 2890.7 2893.4 4218.5 4256.8 4274.6 4277.4 4310.1 4321.0 4542.6 4570.3 4579.0 4595.3 3986.0 4171.2 4291.8 4358.7 4380.0 3685.0 3784.0 3787.1 3906.6 3909.8 4538.0 4720.8 4728.5 4780.2 4781.7 4327.4 4422.6 4613.3 4646.5 4646.7 4490.5 4598.7 4689.5 4781.0 4788.8 4357.7 4467.1 4470.1 4503.5 4540.7 4532.3 4534.2 4609.8 4620.6 4629.5 4333.7 4390.0 4452.0 4481.8 4487.8 4281.3 4302.0 4312.2 4319.7 4352.5 4235.4 4283.4 4292.5 4297.5 4329.7 4365.2 4365.4 4453.0 4453.7 4457.5 4267.1 4303.1 4304.0 4341.4 4394.6 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#1] ext.html Cycles:180 Average:39568.22 Median:33935.92 stddev:68719.64 (202.5%) stddev-sans-first:68849.77 08:04:40 INFO - PROCESS | 488 | Values: 487.9 510.7 513.3 523.9 535.6 33929.0 34804.3 34912.8 37209.4 37348.8 35276.6 35788.8 35798.5 35799.5 35805.3 6796.1 7015.1 7021.0 7030.9 7033.5 6933.4 7019.3 7026.1 7029.6 7033.2 7029.3 7054.3 7058.4 7087.0 7090.6 6978.6 7057.8 7061.1 7063.3 7068.9 6810.7 6865.2 6893.4 6895.8 6902.9 27279.0 32871.0 32899.7 32988.0 33008.2 2660.3 2684.1 2701.5 3076.3 3170.8 25195.7 25297.5 25512.2 26047.0 26086.5 2924.3 3018.2 3049.4 3055.6 3211.6 204077.0 204255.0 204282.0 205127.0 205842.0 44464.6 46120.3 46129.5 46239.0 46264.5 48098.8 48128.8 48168.0 48283.0 48392.5 9899.5 9904.5 9949.3 9979.0 9999.8 8110.6 8125.1 8144.0 8159.6 8179.6 15170.6 15465.6 15498.5 15557.2 15736.2 6049.2 6211.3 6221.4 6250.5 6269.8 706.9 708.2 770.6 776.8 877.7 21518.9 24055.9 24177.2 24299.6 24694.4 760.3 765.2 772.4 832.0 864.2 12534.2 12963.5 13029.5 13034.9 13051.7 284223.0 288471.0 288597.0 289426.0 289784.0 41731.0 41991.0 42052.0 42181.8 42305.0 1034.1 1049.6 1069.1 1071.7 1072.4 24225.0 24417.4 25006.4 25094.1 25216.1 36145.5 36921.8 36969.3 37056.6 37076.0 280924.0 284206.0 284787.0 285102.0 285916.0 20387.4 21106.9 21385.0 21423.0 21436.8 33942.8 36595.6 36615.5 36622.5 36715.5 30479.4 31406.4 31421.6 31633.5 32248.8 30692.0 31391.5 31474.5 31512.2 32275.7 25266.6 30011.8 30112.5 30471.8 30681.7 25214.4 28139.7 28781.4 30085.7 30209.4 21431.0 22137.9 22634.0 22680.3 23749.0 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#2] jquery.html Cycles:180 Average:18549.11 Median:4576.89 stddev:40867.12 (892.9%) stddev-sans-first:40958.80 08:04:40 INFO - PROCESS | 488 | Values: 303.6 304.2 311.7 313.6 319.9 11024.6 11109.8 14126.8 15291.9 15317.2 11496.1 11654.7 11719.9 15215.4 15338.5 4504.6 4549.1 4567.5 4570.1 4578.5 4507.8 4519.2 4569.0 4570.7 4575.3 4398.4 4474.8 4536.2 4537.0 4538.5 4520.8 4562.1 4568.2 4581.1 4581.8 4001.2 4041.6 4046.0 4048.3 4051.8 17065.7 18247.8 18816.8 18929.9 18946.3 1518.9 1541.0 1545.2 1549.6 1577.5 8062.7 8077.1 8266.5 8339.9 8428.4 1539.0 1539.6 1553.2 1557.2 1613.4 61570.0 61874.0 63633.4 63796.7 65679.7 8190.8 8293.5 8404.3 8606.5 8611.6 11122.7 11138.2 11141.4 11143.3 11147.8 5683.9 5794.2 5869.7 5875.7 5882.3 5481.3 5510.1 5565.9 5588.9 5612.7 3411.0 3600.8 3615.6 3633.7 3656.8 1566.0 1597.9 1603.7 1630.9 1650.9 692.6 714.6 716.1 717.5 728.4 14742.3 15230.2 15303.2 15648.5 15676.5 705.5 745.3 747.5 776.0 787.5 8416.8 8557.1 8607.4 8733.3 8852.8 214387.0 216652.0 218489.0 264225.0 276854.0 44422.8 73406.0 74588.6 75501.6 75750.0 775.9 804.1 807.0 807.4 809.5 13849.5 14002.4 14038.2 14273.2 14310.4 32002.7 33347.5 34795.5 35110.5 37417.3 46475.0 50478.5 50551.5 51960.7 52302.4 6297.8 6365.2 6377.9 6392.2 6508.3 7116.9 9945.0 10365.0 10466.9 10490.9 6567.6 8555.5 8626.2 8890.3 9105.5 8609.1 8724.5 8981.2 9145.1 9315.3 8748.4 8781.2 9029.8 9038.5 9226.8 6502.2 6563.1 6827.0 6842.2 7171.2 7029.3 8660.2 8756.3 8869.3 8904.0 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#3] mootools.html Cycles:180 Average:4007.71 Median:5281.33 stddev:2121.38 (40.2%) stddev-sans-first:2116.64 08:04:40 INFO - PROCESS | 488 | Values: 1173.3 1186.9 1229.1 1407.9 1425.8 5396.3 5593.6 5782.3 6064.2 6105.6 5795.5 5992.1 5995.7 6006.8 6107.0 1786.9 1825.3 1832.1 1835.0 1835.7 1802.1 1826.6 1830.0 1830.7 1833.4 1771.3 1782.4 1788.7 1804.0 1805.1 1683.1 1687.6 1706.9 1718.0 1730.3 1680.9 1690.7 1692.6 1696.2 1704.4 5763.4 5781.7 5838.2 5847.4 5880.4 3534.4 3594.9 3621.7 3622.4 3627.9 2326.3 2355.8 2409.6 2427.3 2429.3 729.0 759.1 759.2 765.7 775.3 7731.5 7876.9 7952.2 7967.1 7986.1 3217.9 3292.5 3307.1 3307.8 3320.6 6903.2 6910.0 6913.8 6925.4 6927.4 3721.9 3734.5 3734.9 3735.8 3739.6 2900.3 2995.7 3402.9 3451.3 3452.1 2792.4 2907.6 2909.7 2914.7 2942.8 965.5 976.2 979.9 984.1 984.3 723.1 726.3 739.2 740.1 774.4 4110.1 4161.5 4188.4 4243.9 4274.7 742.8 756.8 758.6 774.1 783.7 2917.1 2967.1 2999.1 3006.9 3053.5 6822.4 7134.9 7252.8 7256.5 7261.2 7101.0 7107.1 7140.8 7141.7 7147.9 5600.3 5689.2 5694.5 5698.3 5713.5 4110.3 4157.9 4161.5 4175.1 4275.2 6593.5 6595.1 6609.2 6617.1 6624.6 6691.6 6794.1 6822.4 6835.4 6857.0 5320.5 5320.5 5371.1 5382.9 5393.1 5761.0 5792.5 5870.7 5881.9 5890.7 5381.3 5389.6 5450.3 5485.5 5544.6 5268.4 5294.2 5328.7 5348.1 5373.7 5008.1 5027.7 5075.6 5097.3 5101.6 3689.8 3739.1 3753.9 3774.6 3827.3 3817.5 3824.3 3866.2 3876.5 3878.0 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#4] prototype.html Cycles:180 Average:3927.12 Median:4623.85 stddev:1231.96 (26.6%) stddev-sans-first:1203.95 08:04:40 INFO - PROCESS | 488 | Values: 240.8 244.4 250.4 252.3 253.2 4534.9 4613.1 4619.3 4636.5 4661.4 4547.1 4550.2 4578.2 4582.9 4597.7 3276.0 3278.6 3282.1 3283.3 3284.5 3255.3 3281.0 3284.8 3284.9 3287.4 3127.5 3236.4 3239.4 3249.9 3258.1 3090.5 3238.3 3247.8 3255.7 3256.6 2801.3 2802.8 2805.3 2808.9 2822.3 4389.9 4426.9 4446.9 4451.5 4458.2 3873.1 3883.5 3886.5 3887.4 3894.5 4722.7 4733.1 4755.8 4763.5 4767.7 3025.5 3134.3 3195.1 3196.6 3198.2 4906.0 4910.3 4930.5 4942.5 4948.8 3227.0 3228.2 3234.6 3242.6 3242.9 3275.2 3276.9 3277.2 3280.3 3287.3 3843.6 3848.5 3856.5 3861.3 3873.2 4612.7 4719.5 4740.8 4759.9 4768.5 1101.8 1103.7 1104.4 1105.5 1108.9 723.4 737.6 743.4 744.1 746.9 4456.7 4531.3 4541.3 4610.3 4639.0 4914.9 4919.0 4923.3 4932.7 4939.3 4612.3 4623.4 4624.3 4633.2 4652.5 2452.3 2549.9 2564.3 2567.7 2568.7 4936.7 4950.9 4962.7 4968.8 4993.8 4888.5 4911.6 4918.4 4925.0 4931.5 4967.0 4973.7 4985.8 4989.8 5007.4 4624.6 4737.8 4784.5 4815.5 4948.4 4840.4 4852.3 4852.7 4855.2 4871.5 5110.8 5132.7 5149.3 5154.8 5163.8 3246.4 3260.7 3269.1 3270.1 3273.0 4664.7 4897.4 4931.2 4931.3 4954.6 4691.4 4722.1 4736.2 4739.1 4744.9 4721.7 4751.3 4756.0 4757.5 4770.7 4649.2 4661.1 4661.9 4689.6 4692.4 4669.2 4783.4 4788.4 4815.1 4829.6 4585.6 4700.7 4722.5 4730.4 4744.2 08:04:40 INFO - PROCESS | 488 | 08:04:40 INFO - PROCESS | 488 | [#5] yui.html Cycles:180 Average:13418.03 Median:49630.75 stddev:17097.10 (34.4%) stddev-sans-first:17117.77 08:04:40 INFO - PROCESS | 488 | Values: 552.5 553.9 563.0 567.4 571.1 10740.1 11513.5 11537.7 11551.4 11563.4 10885.7 11015.1 11068.5 11081.9 11168.6 610.3 621.7 621.7 622.1 622.5 614.3 615.2 615.3 626.1 631.1 660.0 661.8 662.2 664.1 665.0 651.5 651.8 653.0 654.2 654.4 481.1 481.8 481.9 482.4 482.4 11622.8 11692.1 11734.6 11737.3 11787.2 10747.4 11313.4 11436.9 11565.5 11711.9 8762.9 9378.6 9430.2 9461.9 9642.9 2895.8 2937.0 2938.1 2967.3 3046.9 47943.2 48393.8 48490.8 49078.5 49418.3 8298.1 8312.0 8439.5 8790.8 9108.0 13469.3 13561.5 13812.2 13879.1 13970.5 5613.1 5635.9 5736.4 5781.1 5799.5 3133.4 3145.7 3156.5 3193.1 3250.6 2235.8 2317.8 2323.1 2323.7 2575.5 432.5 433.4 447.1 447.5 447.8 546.9 555.6 562.4 563.2 573.5 7111.3 7202.8 7208.9 7372.4 7434.9 532.7 548.8 551.0 565.0 566.4 3265.5 3434.1 3436.8 3437.7 3506.8 59232.4 61949.0 62241.4 65572.0 66525.7 46665.0 50748.5 52818.8 55791.2 56031.7 42454.6 49541.8 50249.0 50432.2 52543.5 7477.9 7536.4 7593.9 7696.2 7697.8 34957.7 36073.3 36153.8 36586.6 37013.0 43267.6 49028.8 49280.0 49719.8 49946.8 9384.9 9451.3 9693.4 9709.0 9829.6 10967.7 11212.1 11261.8 11673.6 11844.3 10781.0 10892.2 10933.2 11189.9 11406.8 10640.3 10743.9 10934.8 11182.9 11282.7 10052.8 10140.9 10162.4 10262.2 10390.4 8214.0 8480.7 8620.5 8954.9 9078.2 8484.9 8533.4 8810.3 8872.8 9042.2 08:04:40 INFO - PROCESS | 488 | -------- Summary: end -------- 08:04:40 INFO - PROCESS | 488 | 08:04:41 INFO - TEST-INFO | 488: exit 0 08:04:41 INFO - TEST-OK | dromaeo_css | took 1124213ms 08:04:41 INFO - TEST-START | kraken 08:04:41 INFO - operating with platform_type : w7_ 08:04:41 INFO - Initialising browser for kraken test... 08:04:41 INFO - TEST-INFO | started process 236 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpggvmzv\profile http://localhost:49892/getInfo.html) 08:04:47 INFO - PROCESS | 236 | __metrics Screen width/height:1600/1200 08:04:47 INFO - PROCESS | 236 | colorDepth:24 08:04:47 INFO - PROCESS | 236 | Browser inner width/height: 1010/674 08:04:47 INFO - PROCESS | 236 | __metrics 08:04:48 ERROR - PROCESS | 236 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 08:04:49 INFO - PROCESS | 236 | [Child 3100] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-000000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 08:04:49 INFO - PROCESS | 236 | [Child 3100] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-in-w32-000000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 08:04:49 INFO - PROCESS | 236 | console.error: 08:04:49 INFO - PROCESS | 236 | Message: Error: Connection closed before committing the transaction. 08:04:49 INFO - PROCESS | 236 | Stack: 08:04:49 INFO - PROCESS | 236 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 08:04:49 INFO - PROCESS | 236 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 08:04:49 INFO - PROCESS | 236 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 08:04:49 INFO - PROCESS | 236 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 08:04:49 INFO - PROCESS | 236 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 08:04:49 INFO - PROCESS | 236 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 08:04:49 INFO - PROCESS | 236 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 08:04:49 INFO - PROCESS | 236 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 08:04:49 INFO - PROCESS | 236 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 08:04:49 INFO - PROCESS | 236 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 08:04:49 INFO - PROCESS | 236 | Barrier/this.client.addBlocker/promise13.5% 08:05:49 INFO - TinderboxPrint: I/O read bytes / time
139,022,848 / 1,408,590 08:05:49 INFO - TinderboxPrint: I/O write bytes / time
544,168,960 / 3,441,400 08:05:49 INFO - TinderboxPrint: CPU idle
8,290.6 (86.5%) 08:05:49 INFO - TinderboxPrint: CPU user
1,247.0 (13.0%) 08:05:49 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 32768; Write bytes: 34103296; Read time: 3270; Write time: 138590 08:05:49 INFO - run-tests - Wall time: 1196s; CPU: 14.0%; Read bytes: 137957888; Write bytes: 469959680; Read time: 1355110; Write time: 3126630 08:05:50 INFO - Running post-run listener: _upload_blobber_files 08:05:50 INFO - Blob upload gear active. 08:05:50 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 08:05:50 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:05:50 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', 'Mozilla-Inbound-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 08:05:50 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 Mozilla-Inbound-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 08:05:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:05:50 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 08:05:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:05:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:51 INFO - (blobuploader) - INFO - Done attempting. 08:05:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 08:05:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:05:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:05:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:05:52 INFO - (blobuploader) - INFO - Done attempting. 08:05:52 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 08:05:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:05:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:06:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:00 INFO - (blobuploader) - INFO - Done attempting. 08:06:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:06:00 INFO - Return code: 0 08:06:00 INFO - rmtree: C:\slave\test\build\uploaded_files.json 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 08:06:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/411ddf4983ffa7b64f0f77f0450cd6add43c1d98d74f65540f581b91ecb919745738341de48550b7de0747bc1e4105a073522e4d595dc43deef9889c98da8ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/2c9a1fbfccb4238bc04b33f6dc52ab0e11e6bf6e95d48a9ea388aa9d072e5dcd0760b39df710068bfe2260de8f88e8e6958b28b31ba677fa23974d604ec53872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/4f8d64313735862aa01bade2b027d44a2b5b8b195259a96fd4239060bf5389ba6d017f7f4b3c9b2d0cee998879cf692ff2abb5acd5d296daab02576829046531"} 08:06:00 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 08:06:00 INFO - Writing to file C:\slave\test\properties\blobber_files 08:06:00 INFO - Contents: 08:06:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/411ddf4983ffa7b64f0f77f0450cd6add43c1d98d74f65540f581b91ecb919745738341de48550b7de0747bc1e4105a073522e4d595dc43deef9889c98da8ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/2c9a1fbfccb4238bc04b33f6dc52ab0e11e6bf6e95d48a9ea388aa9d072e5dcd0760b39df710068bfe2260de8f88e8e6958b28b31ba677fa23974d604ec53872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/4f8d64313735862aa01bade2b027d44a2b5b8b195259a96fd4239060bf5389ba6d017f7f4b3c9b2d0cee998879cf692ff2abb5acd5d296daab02576829046531"} 08:06:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:06:00 INFO - Copying logs to upload dir... 08:06:00 INFO - mkdir: C:\slave\test\build\upload\logs 08:06:00 INFO - Copying logs to upload dir... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... 08:06:00 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1327.430000 ========= master_lag: 0.44 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2016-11-07 08:06:01.283195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:01.283814) ========= '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-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/411ddf4983ffa7b64f0f77f0450cd6add43c1d98d74f65540f581b91ecb919745738341de48550b7de0747bc1e4105a073522e4d595dc43deef9889c98da8ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/2c9a1fbfccb4238bc04b33f6dc52ab0e11e6bf6e95d48a9ea388aa9d072e5dcd0760b39df710068bfe2260de8f88e8e6958b28b31ba677fa23974d604ec53872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/4f8d64313735862aa01bade2b027d44a2b5b8b195259a96fd4239060bf5389ba6d017f7f4b3c9b2d0cee998879cf692ff2abb5acd5d296daab02576829046531"} build_url:https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/M5COYHjdQF6k_ZLczB2TVQ/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/411ddf4983ffa7b64f0f77f0450cd6add43c1d98d74f65540f581b91ecb919745738341de48550b7de0747bc1e4105a073522e4d595dc43deef9889c98da8ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/2c9a1fbfccb4238bc04b33f6dc52ab0e11e6bf6e95d48a9ea388aa9d072e5dcd0760b39df710068bfe2260de8f88e8e6958b28b31ba677fa23974d604ec53872", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound-Non-PGO/sha512/4f8d64313735862aa01bade2b027d44a2b5b8b195259a96fd4239060bf5389ba6d017f7f4b3c9b2d0cee998879cf692ff2abb5acd5d296daab02576829046531"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:01.445640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:01.445975) ========= '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-009 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-009 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-009 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.59 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:02.138302) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:02.138633) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-07 08:06:02.668908) ========= ========= Total master_lag: 6.77 =========