builder: fx-team_win7_ix_test-dromaeojs-e10s-pgo slave: t-w732-ix-027 starttime: 1477767125.45 results: success (0) buildid: 20161029093005 builduid: 7f169e42477040a28eb6e4ba70777630 revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:05.453132) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:05.453624) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:05.453917) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:05.799561) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 11:52:05.799912) ========= '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-027 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-027 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-027 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 --11:52:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 10.24 MB/s 11:52:07 (10.24 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.420000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 11:52:07.244417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-29 11:52:07.244743) ========= '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-027 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-027 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-027 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=14.001000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-10-29 11:52:21.269028) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 11:52:21.269426) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 969c3295d3aa77931cca26eddb047d9d74bd9858 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-027 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-027 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-027 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-10-29 11:52:21,943 truncating revision to first 12 chars 2016-10-29 11:52:21,943 Setting DEBUG logging. 2016-10-29 11:52:21,943 attempt 1/10 2016-10-29 11:52:21,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/969c3295d3aa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 11:52:22,743 unpacking tar archive at: fx-team-969c3295d3aa/testing/mozharness/ 2016-10-29 11:52:22,871 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.497000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-29 11:52:22.820924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:22.821310) ========= script_repo_revision: 969c3295d3aa77931cca26eddb047d9d74bd9858 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:22.821804) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:22.822086) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 11:52:22.832625) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-10-29 11:52:22.832954) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-027 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-027 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-027 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 11:52:23 INFO - MultiFileLogger online at 20161029 11:52:23 in C:\slave\test 11:52:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:52:23 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 11:52:23 INFO - {'append_to_log': False, 11:52:23 INFO - 'base_work_dir': 'C:\\slave\\test', 11:52:23 INFO - 'blob_upload_branch': 'Fx-Team', 11:52:23 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 11:52:23 INFO - 'branch': 'Fx-Team', 11:52:23 INFO - 'buildbot_json_path': 'buildprops.json', 11:52:23 INFO - 'config_files': ('talos/windows_config.py',), 11:52:23 INFO - 'default_actions': ('clobber', 11:52:23 INFO - 'read-buildbot-config', 11:52:23 INFO - 'download-and-extract', 11:52:23 INFO - 'populate-webroot', 11:52:23 INFO - 'create-virtualenv', 11:52:23 INFO - 'install', 11:52:23 INFO - 'run-tests'), 11:52:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:52:23 INFO - 'download_minidump_stackwalk': True, 11:52:23 INFO - 'download_symbols': 'ondemand', 11:52:23 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 11:52:23 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 11:52:23 INFO - 'hg': 'c:/mozilla-build/hg/hg', 11:52:23 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 11:52:23 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 11:52:23 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 11:52:23 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 11:52:23 INFO - 'C:/mozilla-build/tooltool.py'), 11:52:23 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 11:52:23 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 11:52:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:52:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:52:23 INFO - 'installer_path': 'installer.exe', 11:52:23 INFO - 'log_level': 'info', 11:52:23 INFO - 'log_name': 'talos', 11:52:23 INFO - 'log_to_console': True, 11:52:23 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 11:52:23 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 11:52:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 11:52:23 INFO - 'opt_config_files': (), 11:52:23 INFO - 'pip_index': False, 11:52:23 INFO - 'sps_profile': False, 11:52:23 INFO - 'sps_profile_interval': 0, 11:52:23 INFO - 'suite': 'dromaeojs-e10s', 11:52:23 INFO - 'system_bits': '32', 11:52:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:52:23 INFO - 'title': 'T-W732-IX-027', 11:52:23 INFO - 'use_talos_json': True, 11:52:23 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 11:52:23 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 11:52:23 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 11:52:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:52:23 INFO - 'work_dir': 'build'} 11:52:23 INFO - [mozharness: 2016-10-29 18:52:23.619000Z] Running clobber step. 11:52:23 INFO - Running pre-action listener: _resource_record_pre_action 11:52:23 INFO - Running main action method: clobber 11:52:23 INFO - rmtree: C:\slave\test\build 11:52:23 INFO - Using _rmtree_windows ... 11:52:23 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 11:52:45 INFO - Running post-action listener: _resource_record_post_action 11:52:45 INFO - [mozharness: 2016-10-29 18:52:45.265000Z] Finished clobber step (success) 11:52:45 INFO - [mozharness: 2016-10-29 18:52:45.265000Z] Running read-buildbot-config step. 11:52:45 INFO - Running pre-action listener: _resource_record_pre_action 11:52:45 INFO - Running main action method: read_buildbot_config 11:52:45 INFO - Using buildbot properties: 11:52:45 INFO - { 11:52:45 INFO - "project": "", 11:52:45 INFO - "product": "firefox", 11:52:45 INFO - "script_repo_revision": "production", 11:52:45 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs-e10s", 11:52:45 INFO - "repository": "", 11:52:45 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs-e10s", 11:52:45 INFO - "buildid": "20161029093005", 11:52:45 INFO - "pgo_build": "True", 11:52:45 INFO - "basedir": "C:\\slave\\test", 11:52:45 INFO - "buildnumber": 224, 11:52:45 INFO - "slavename": "t-w732-ix-027", 11:52:45 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 11:52:45 INFO - "platform": "win7_ix", 11:52:45 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 11:52:45 INFO - "branch": "fx-team", 11:52:45 INFO - "repo_path": "integration/fx-team", 11:52:45 INFO - "revision": "969c3295d3aa77931cca26eddb047d9d74bd9858", 11:52:45 INFO - "stage_platform": "win32-pgo", 11:52:45 INFO - "builduid": "7f169e42477040a28eb6e4ba70777630", 11:52:45 INFO - "slavebuilddir": "test" 11:52:45 INFO - } 11:52:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 11:52:45 INFO - Running post-action listener: _resource_record_post_action 11:52:45 INFO - [mozharness: 2016-10-29 18:52:45.268000Z] Finished read-buildbot-config step (success) 11:52:45 INFO - [mozharness: 2016-10-29 18:52:45.269000Z] Running download-and-extract step. 11:52:45 INFO - Running pre-action listener: _resource_record_pre_action 11:52:45 INFO - Running main action method: download_and_extract 11:52:45 INFO - mkdir: C:\slave\test\build\tests 11:52:45 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')]} 11:52:45 INFO - trying https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 11:52:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/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 11:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/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 11:52:46 INFO - Downloaded 1218 bytes. 11:52:46 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 11:52:46 INFO - Using the following test package requirements: 11:52:46 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 11:52:46 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 11:52:46 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 11:52:46 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'jsshell-win32.zip'], 11:52:46 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 11:52:46 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 11:52:46 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 11:52:46 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 11:52:46 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 11:52:46 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 11:52:46 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 11:52:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 11:52:46 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 11:52:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 11:52:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 11:52:46 INFO - Http code: 200 11:52:46 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 11:52:46 INFO - x-amz-cf-id: iTfroVVxUauvUVG4ZpcxhHxG1EfFDlMLBDlQi6SfZttxeC-NdLGmYQ== 11:52:46 INFO - x-amz-version-id: puw8Z4WHztE0DFQIZtwAm8AKo90K9yPQ 11:52:47 INFO - Expected file size: 17149828 11:52:47 INFO - Obtained file size: 17149828 11:53:17 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 11:53:17 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 11:53:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 11:53:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 11:53:17 INFO - Http code: 200 11:53:17 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 11:53:17 INFO - x-amz-cf-id: Fm7onrdoYL6kPzYRf-r7WJimzIo7x-OPFmTbo77hpM-fDZlNDqU6Bw== 11:53:17 INFO - x-amz-version-id: puw8Z4WHztE0DFQIZtwAm8AKo90K9yPQ 11:53:18 INFO - Expected file size: 17149828 11:53:18 INFO - Obtained file size: 17149828 11:53:29 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 11:53:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 11:53:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 11:53:30 INFO - Http code: 200 11:53:30 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 11:53:30 INFO - x-amz-cf-id: 85KwD3PkKA4-UuMrui5duLRpbCDCojr_C-CuRsAmS52YMm0qlYp9HQ== 11:53:30 INFO - x-amz-version-id: IVZnO62idKJ_OVF8klZPXdJkKmfwge7r 11:53:30 INFO - Expected file size: 14409038 11:53:30 INFO - Obtained file size: 14409038 11:53:31 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')]} 11:53:31 INFO - trying https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 11:53:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 11:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/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 11:53:34 INFO - Downloaded 54288864 bytes. 11:53:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 11:53:34 INFO - mkdir: C:\slave\test\properties 11:53:35 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 11:53:35 INFO - Writing to file C:\slave\test\properties\build_url 11:53:35 INFO - Contents: 11:53:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 11:53:35 INFO - Running post-action listener: _resource_record_post_action 11:53:35 INFO - Running post-action listener: set_extra_try_arguments 11:53:35 INFO - [mozharness: 2016-10-29 18:53:35.758000Z] Finished download-and-extract step (success) 11:53:35 INFO - [mozharness: 2016-10-29 18:53:35.758000Z] Running populate-webroot step. 11:53:35 INFO - Running pre-action listener: _resource_record_pre_action 11:53:35 INFO - Running main action method: populate_webroot 11:53:35 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 11:53:35 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 11:53:35 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 11:53:35 INFO - u'--tppagecycles', 11:53:35 INFO - u'7'], 11:53:35 INFO - u'tests': [u'tsvgm']}}, 11:53:35 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 11:53:35 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 11:53:35 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 11:53:35 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 11:53:35 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 11:53:35 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 11:53:35 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'damp', u'tps']}, 11:53:35 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'tests': [u'damp', u'tps']}, 11:53:35 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'dromaeo_dom']}, 11:53:35 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 11:53:35 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'basic_compositor_video']}, 11:53:35 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 11:53:35 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'other-e10s': {u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'a11yr', 11:53:35 INFO - u'ts_paint', 11:53:35 INFO - u'tpaint', 11:53:35 INFO - u'sessionrestore', 11:53:35 INFO - u'sessionrestore_no_auto_restore', 11:53:35 INFO - u'tabpaint']}, 11:53:35 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'tsvgx', 11:53:35 INFO - u'tsvgr_opacity', 11:53:35 INFO - u'tart', 11:53:35 INFO - u'tscrollx', 11:53:35 INFO - u'cart']}, 11:53:35 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 11:53:35 INFO - u'tsvgr_opacity', 11:53:35 INFO - u'tart', 11:53:35 INFO - u'tscrollx', 11:53:35 INFO - u'cart']}, 11:53:35 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'talos_options': [u'--disable-e10s'], 11:53:35 INFO - u'tests': [u'tp5o']}, 11:53:35 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'tests': [u'tp5o']}, 11:53:35 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'talos_options': [u'--disable-e10s', 11:53:35 INFO - u'--xperf_path', 11:53:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:35 INFO - u'tests': [u'tp5n']}, 11:53:35 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:35 INFO - u'talos_options': [u'--xperf_path', 11:53:35 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:35 INFO - u'tests': [u'tp5n']}}, 11:53:35 INFO - u'talos.zip': {u'path': u'', 11:53:35 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:53:35 INFO - Running post-action listener: _resource_record_post_action 11:53:35 INFO - [mozharness: 2016-10-29 18:53:35.836000Z] Finished populate-webroot step (success) 11:53:35 INFO - [mozharness: 2016-10-29 18:53:35.836000Z] Running create-virtualenv step. 11:53:35 INFO - Running pre-action listener: _resource_record_pre_action 11:53:35 INFO - Running main action method: create_virtualenv 11:53:35 INFO - Creating virtualenv C:\slave\test\build/venv 11:53:35 INFO - mkdir: C:\slave\test\build\venv\Scripts 11:53:36 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 11:53:36 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'! 11:53:36 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 11:53:36 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 11:53:39 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 11:53:51 INFO - Installing distribute......................................................................................................................................................................................done. 11:53:52 INFO - Return code: 0 11:53:52 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 11:53:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 11:53:53 INFO - Reading from file tmpfile_stdout 11:53:53 INFO - Output received: 11:53:53 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 11:53:53 INFO - Using _rmtree_windows ... 11:53:53 INFO - Using _rmtree_windows ... 11:53:53 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 11:53: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')]} 11:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53: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')]} 11:53:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:53 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:53:53 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 11:53:53 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 11:53:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:53:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:53:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:53:53 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:53:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:53:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:53:53 INFO - 'DCLOCATION': 'SCL3', 11:53:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:53:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:53:53 INFO - 'HOMEDRIVE': 'C:', 11:53:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:53:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:53:53 INFO - 'KTS_VERSION': '1.19c', 11:53:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:53:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:53:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:53:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:53:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:53 INFO - 'MOZ_NO_REMOTE': '1', 11:53:53 INFO - 'NO_EM_RESTART': '1', 11:53:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:53:53 INFO - 'OS': 'Windows_NT', 11:53:53 INFO - 'OURDRIVE': 'C:', 11:53:53 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;', 11:53:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:53:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:53:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:53:53 INFO - 'PROCESSOR_LEVEL': '6', 11:53:53 INFO - 'PROCESSOR_REVISION': '1e05', 11:53:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:53:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:53:53 INFO - 'PROMPT': '$P$G', 11:53:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:53:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:53:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:53:53 INFO - 'PWD': 'C:\\slave\\test', 11:53:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:53:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:53:53 INFO - 'SYSTEMDRIVE': 'C:', 11:53:53 INFO - 'SYSTEMROOT': 'C:\\windows', 11:53:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:53:53 INFO - 'TEST1': 'testie', 11:53:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:53:53 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:53:53 INFO - 'USERNAME': 'cltbld', 11:53:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:53:53 INFO - 'WINDIR': 'C:\\windows', 11:53:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:53:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:53:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:53:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:53:54 INFO - Downloading/unpacking pip>=1.5 11:53:58 INFO - Running setup.py egg_info for package pip 11:53:58 INFO - warning: no files found matching 'pip\cacert.pem' 11:53:58 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 11:53:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 11:53:58 INFO - Installing collected packages: pip 11:53:58 INFO - Found existing installation: pip 0.8.2 11:53:58 INFO - Uninstalling pip: 11:53:58 INFO - Successfully uninstalled pip 11:53:58 INFO - Running setup.py install for pip 11:53:58 INFO - warning: no files found matching 'pip\cacert.pem' 11:53:58 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 11:53:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 11:53:58 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 11:53:58 INFO - Successfully installed pip 11:53:58 INFO - Cleaning up... 11:53:58 INFO - Return code: 0 11:53:58 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:58 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:53:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 11:53:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:53:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:53:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:53:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:53:58 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:53:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:53:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:53:58 INFO - 'DCLOCATION': 'SCL3', 11:53:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:53:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:53:58 INFO - 'HOMEDRIVE': 'C:', 11:53:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:53:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:53:58 INFO - 'KTS_VERSION': '1.19c', 11:53:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:53:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:53:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:53:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:58 INFO - 'MOZ_NO_REMOTE': '1', 11:53:58 INFO - 'NO_EM_RESTART': '1', 11:53:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:53:58 INFO - 'OS': 'Windows_NT', 11:53:58 INFO - 'OURDRIVE': 'C:', 11:53:58 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:53:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:53:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:53:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:53:58 INFO - 'PROCESSOR_LEVEL': '6', 11:53:58 INFO - 'PROCESSOR_REVISION': '1e05', 11:53:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:53:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:53:58 INFO - 'PROMPT': '$P$G', 11:53:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:53:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:53:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:53:58 INFO - 'PWD': 'C:\\slave\\test', 11:53:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:53:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:53:58 INFO - 'SYSTEMDRIVE': 'C:', 11:53:58 INFO - 'SYSTEMROOT': 'C:\\windows', 11:53:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:53:58 INFO - 'TEST1': 'testie', 11:53:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:53:58 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:53:58 INFO - 'USERNAME': 'cltbld', 11:53:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:53:58 INFO - 'WINDIR': 'C:\\windows', 11:53:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:53:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:00 INFO - Downloading/unpacking psutil>=3.1.1 11:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:01 INFO - Installing collected packages: psutil 11:54:01 INFO - Successfully installed psutil 11:54:01 INFO - Cleaning up... 11:54:01 INFO - Return code: 0 11:54:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 11:54:01 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')]} 11:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:01 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')]} 11:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:01 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:01 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 11:54:01 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 11:54:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:01 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:01 INFO - 'DCLOCATION': 'SCL3', 11:54:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:01 INFO - 'HOMEDRIVE': 'C:', 11:54:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:01 INFO - 'KTS_VERSION': '1.19c', 11:54:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:01 INFO - 'MOZ_NO_REMOTE': '1', 11:54:01 INFO - 'NO_EM_RESTART': '1', 11:54:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:01 INFO - 'OS': 'Windows_NT', 11:54:01 INFO - 'OURDRIVE': 'C:', 11:54:01 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;', 11:54:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:01 INFO - 'PROCESSOR_LEVEL': '6', 11:54:01 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:01 INFO - 'PROMPT': '$P$G', 11:54:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:01 INFO - 'PWD': 'C:\\slave\\test', 11:54:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:01 INFO - 'SYSTEMDRIVE': 'C:', 11:54:01 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:01 INFO - 'TEST1': 'testie', 11:54:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:01 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:01 INFO - 'USERNAME': 'cltbld', 11:54:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:01 INFO - 'WINDIR': 'C:\\windows', 11:54:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:54:02 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 11:54:02 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 11:54:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:54:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 11:54:03 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) 11:54:03 INFO - Installing collected packages: mozsystemmonitor 11:54:03 INFO - Running setup.py install for mozsystemmonitor 11:54:03 INFO - Successfully installed mozsystemmonitor 11:54:03 INFO - Cleaning up... 11:54:04 INFO - Return code: 0 11:54:04 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 11:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:04 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:04 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 11:54:04 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 11:54:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:04 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:04 INFO - 'DCLOCATION': 'SCL3', 11:54:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:04 INFO - 'HOMEDRIVE': 'C:', 11:54:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:04 INFO - 'KTS_VERSION': '1.19c', 11:54:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:04 INFO - 'MOZ_NO_REMOTE': '1', 11:54:04 INFO - 'NO_EM_RESTART': '1', 11:54:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:04 INFO - 'OS': 'Windows_NT', 11:54:04 INFO - 'OURDRIVE': 'C:', 11:54:04 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:54:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:04 INFO - 'PROCESSOR_LEVEL': '6', 11:54:04 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:04 INFO - 'PROMPT': '$P$G', 11:54:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:04 INFO - 'PWD': 'C:\\slave\\test', 11:54:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:04 INFO - 'SYSTEMDRIVE': 'C:', 11:54:04 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:04 INFO - 'TEST1': 'testie', 11:54:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:04 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:04 INFO - 'USERNAME': 'cltbld', 11:54:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:04 INFO - 'WINDIR': 'C:\\windows', 11:54:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:04 INFO - Downloading/unpacking jsonschema==2.5.1 11:54:04 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 11:54:04 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 11:54:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:54:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:05 INFO - Downloading functools32-3.2.3-2.tar.gz 11:54:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 11:54:05 INFO - warning: no files found matching '*.txt' 11:54:05 INFO - no previously-included directories found matching 'build' 11:54:05 INFO - no previously-included directories found matching 'dist' 11:54:05 INFO - no previously-included directories found matching '.git*' 11:54:05 INFO - Installing collected packages: jsonschema, functools32 11:54:05 INFO - Running setup.py install for functools32 11:54:05 INFO - warning: no files found matching '*.txt' 11:54:05 INFO - no previously-included directories found matching 'build' 11:54:05 INFO - no previously-included directories found matching 'dist' 11:54:05 INFO - no previously-included directories found matching '.git*' 11:54:05 INFO - Successfully installed jsonschema functools32 11:54:05 INFO - Cleaning up... 11:54:06 INFO - Return code: 0 11:54:06 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:06 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:06 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 11:54:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:54:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:06 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:06 INFO - 'DCLOCATION': 'SCL3', 11:54:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:06 INFO - 'HOMEDRIVE': 'C:', 11:54:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:06 INFO - 'KTS_VERSION': '1.19c', 11:54:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:06 INFO - 'MOZ_NO_REMOTE': '1', 11:54:06 INFO - 'NO_EM_RESTART': '1', 11:54:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:06 INFO - 'OS': 'Windows_NT', 11:54:06 INFO - 'OURDRIVE': 'C:', 11:54:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:54:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:06 INFO - 'PROCESSOR_LEVEL': '6', 11:54:06 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:06 INFO - 'PROMPT': '$P$G', 11:54:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:06 INFO - 'PWD': 'C:\\slave\\test', 11:54:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:06 INFO - 'SYSTEMDRIVE': 'C:', 11:54:06 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:06 INFO - 'TEST1': 'testie', 11:54:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:06 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:06 INFO - 'USERNAME': 'cltbld', 11:54:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:06 INFO - 'WINDIR': 'C:\\windows', 11:54:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 11:54:06 INFO - Cleaning up... 11:54:06 INFO - Return code: 0 11:54:06 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:06 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:06 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 11:54:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:54:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:06 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:06 INFO - 'DCLOCATION': 'SCL3', 11:54:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:06 INFO - 'HOMEDRIVE': 'C:', 11:54:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:06 INFO - 'KTS_VERSION': '1.19c', 11:54:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:06 INFO - 'MOZ_NO_REMOTE': '1', 11:54:06 INFO - 'NO_EM_RESTART': '1', 11:54:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:06 INFO - 'OS': 'Windows_NT', 11:54:06 INFO - 'OURDRIVE': 'C:', 11:54:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:54:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:06 INFO - 'PROCESSOR_LEVEL': '6', 11:54:06 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:06 INFO - 'PROMPT': '$P$G', 11:54:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:06 INFO - 'PWD': 'C:\\slave\\test', 11:54:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:06 INFO - 'SYSTEMDRIVE': 'C:', 11:54:06 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:06 INFO - 'TEST1': 'testie', 11:54:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:06 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:06 INFO - 'USERNAME': 'cltbld', 11:54:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:06 INFO - 'WINDIR': 'C:\\windows', 11:54:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:07 INFO - Downloading/unpacking blobuploader==1.2.4 11:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:08 INFO - Downloading blobuploader-1.2.4.tar.gz 11:54:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 11:54:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 11:54:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:09 INFO - Downloading docopt-0.6.1.tar.gz 11:54:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 11:54:09 INFO - Installing collected packages: blobuploader, requests, docopt 11:54:09 INFO - Running setup.py install for blobuploader 11:54:09 INFO - Running setup.py install for requests 11:54:10 INFO - Running setup.py install for docopt 11:54:10 INFO - Successfully installed blobuploader requests docopt 11:54:10 INFO - Cleaning up... 11:54:11 INFO - Return code: 0 11:54:11 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:54: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')]} 11:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54: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')]} 11:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:11 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:11 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 11:54:11 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 11:54:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:11 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:11 INFO - 'DCLOCATION': 'SCL3', 11:54:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:11 INFO - 'HOMEDRIVE': 'C:', 11:54:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:11 INFO - 'KTS_VERSION': '1.19c', 11:54:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:11 INFO - 'MOZ_NO_REMOTE': '1', 11:54:11 INFO - 'NO_EM_RESTART': '1', 11:54:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:11 INFO - 'OS': 'Windows_NT', 11:54:11 INFO - 'OURDRIVE': 'C:', 11:54: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;', 11:54:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:11 INFO - 'PROCESSOR_LEVEL': '6', 11:54:11 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:11 INFO - 'PROMPT': '$P$G', 11:54:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:11 INFO - 'PWD': 'C:\\slave\\test', 11:54:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:11 INFO - 'SYSTEMDRIVE': 'C:', 11:54:11 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:11 INFO - 'TEST1': 'testie', 11:54:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:11 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:11 INFO - 'USERNAME': 'cltbld', 11:54:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:11 INFO - 'WINDIR': 'C:\\windows', 11:54:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0prnkk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ouz2w2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wsfki1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lt8v2u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hewnef-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kxtfn_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 11:54:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 11:54:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mmiz_h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ifpkdz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v12510-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2okxoc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m0ijtc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qycddl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1cdckc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 11:54:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 11:54:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kozdv6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 11:54:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 11:54:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dmdtc5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 11:54:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 11:54:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9igowo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 11:54:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 11:54:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-khgrhn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 11:54:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:54:14 INFO - Running setup.py install for manifestparser 11:54:14 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 11:54:14 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 11:54:15 INFO - Running setup.py install for mozcrash 11:54:15 INFO - Running setup.py install for mozdebug 11:54:15 INFO - Running setup.py install for mozdevice 11:54:16 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 11:54:16 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 11:54:16 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 11:54:16 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 11:54:16 INFO - Running setup.py install for mozfile 11:54:16 INFO - Running setup.py install for mozhttpd 11:54:16 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 11:54:16 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Running setup.py install for mozinfo 11:54:17 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Running setup.py install for mozInstall 11:54:17 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 11:54:17 INFO - Running setup.py install for mozleak 11:54:18 INFO - Running setup.py install for mozlog 11:54:18 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 11:54:18 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 11:54:19 INFO - Running setup.py install for moznetwork 11:54:19 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 11:54:19 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 11:54:19 INFO - Running setup.py install for mozprocess 11:54:20 INFO - Running setup.py install for mozprofile 11:54:20 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Running setup.py install for mozrunner 11:54:20 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 11:54:20 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 11:54:21 INFO - Running setup.py install for mozscreenshot 11:54:21 INFO - Running setup.py install for moztest 11:54:22 INFO - Running setup.py install for mozversion 11:54:22 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 11:54:22 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 11:54:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:54:22 INFO - Cleaning up... 11:54:22 INFO - Return code: 0 11:54:22 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:54: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')]} 11:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54: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')]} 11:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:22 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:22 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 11:54:22 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 11:54:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:22 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:22 INFO - 'DCLOCATION': 'SCL3', 11:54:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:22 INFO - 'HOMEDRIVE': 'C:', 11:54:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:22 INFO - 'KTS_VERSION': '1.19c', 11:54:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:22 INFO - 'MOZ_NO_REMOTE': '1', 11:54:22 INFO - 'NO_EM_RESTART': '1', 11:54:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:22 INFO - 'OS': 'Windows_NT', 11:54:22 INFO - 'OURDRIVE': 'C:', 11:54: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;', 11:54:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:22 INFO - 'PROCESSOR_LEVEL': '6', 11:54:22 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:22 INFO - 'PROMPT': '$P$G', 11:54:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:22 INFO - 'PWD': 'C:\\slave\\test', 11:54:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:22 INFO - 'SYSTEMDRIVE': 'C:', 11:54:22 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:22 INFO - 'TEST1': 'testie', 11:54:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:22 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:22 INFO - 'USERNAME': 'cltbld', 11:54:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:22 INFO - 'WINDIR': 'C:\\windows', 11:54:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 11:54:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xvotl9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tw8atc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ghuud-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7k_lf1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rxqw5n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tferln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tlov31-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 11:54:23 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)) 11:54:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 11:54:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9nlq9h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lsncro-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uyfqn7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3g2n0e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxdxhs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lrum0f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 11:54:24 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)) 11:54:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 11:54:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u7tqlq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 11:54:25 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)) 11:54:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 11:54:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ctznp3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 11:54:25 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)) 11:54:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 11:54:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-srrvpe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 11:54:25 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)) 11:54:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 11:54:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iad5go-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 11:54:25 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)) 11:54:25 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)) 11:54:25 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)) 11:54:25 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)) 11:54:25 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)) 11:54:25 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)) 11:54:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 11:54: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 11:54: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 11:54:26 INFO - Downloading blessings-1.6.tar.gz 11:54:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 11:54:26 INFO - Installing collected packages: blessings 11:54:26 INFO - Running setup.py install for blessings 11:54:27 INFO - Successfully installed blessings 11:54:27 INFO - Cleaning up... 11:54:30 INFO - Return code: 0 11:54:30 INFO - Done creating virtualenv C:\slave\test\build/venv. 11:54:30 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 11:54:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 11:54:30 INFO - Reading from file tmpfile_stdout 11:54:30 INFO - Using _rmtree_windows ... 11:54:30 INFO - Using _rmtree_windows ... 11:54:30 INFO - Current package versions: 11:54:30 INFO - blessings == 1.6 11:54:30 INFO - blobuploader == 1.2.4 11:54:30 INFO - docopt == 0.6.1 11:54:30 INFO - functools32 == 3.2.3-2 11:54:30 INFO - jsonschema == 2.5.1 11:54:30 INFO - manifestparser == 1.1 11:54:30 INFO - mozInstall == 1.12 11:54:30 INFO - mozcrash == 0.17 11:54:30 INFO - mozdebug == 0.1 11:54:30 INFO - mozdevice == 0.48 11:54:30 INFO - mozfile == 1.2 11:54:30 INFO - mozhttpd == 0.7 11:54:30 INFO - mozinfo == 0.9 11:54:30 INFO - mozleak == 0.1 11:54:30 INFO - mozlog == 3.4 11:54:30 INFO - moznetwork == 0.27 11:54:30 INFO - mozprocess == 0.23 11:54:30 INFO - mozprofile == 0.28 11:54:30 INFO - mozrunner == 6.12 11:54:30 INFO - mozscreenshot == 0.1 11:54:30 INFO - mozsystemmonitor == 0.3 11:54:30 INFO - moztest == 0.7 11:54:30 INFO - mozversion == 1.4 11:54:30 INFO - psutil == 3.1.1 11:54:30 INFO - requests == 1.2.3 11:54:30 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:54: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')]} 11:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54: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')]} 11:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:30 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:30 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 11:54:30 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 11:54:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:30 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:30 INFO - 'DCLOCATION': 'SCL3', 11:54:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:30 INFO - 'HOMEDRIVE': 'C:', 11:54:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:30 INFO - 'KTS_VERSION': '1.19c', 11:54:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:30 INFO - 'MOZ_NO_REMOTE': '1', 11:54:30 INFO - 'NO_EM_RESTART': '1', 11:54:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:30 INFO - 'OS': 'Windows_NT', 11:54:30 INFO - 'OURDRIVE': 'C:', 11:54: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;', 11:54:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:30 INFO - 'PROCESSOR_LEVEL': '6', 11:54:30 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:30 INFO - 'PROMPT': '$P$G', 11:54:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:30 INFO - 'PWD': 'C:\\slave\\test', 11:54:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:30 INFO - 'SYSTEMDRIVE': 'C:', 11:54:30 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:30 INFO - 'TEST1': 'testie', 11:54:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:30 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:30 INFO - 'USERNAME': 'cltbld', 11:54:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:30 INFO - 'WINDIR': 'C:\\windows', 11:54:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 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)) 11:54:30 INFO - Cleaning up... 11:54:30 INFO - Return code: 0 11:54:30 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 11:54: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')]} 11:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54: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')]} 11:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:30 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 0x019B8640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019F2E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01932B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-027', 'USERDOMAIN': 'T-W732-IX-027', '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-027', '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 11:54:30 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 11:54:30 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 11:54:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:30 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:30 INFO - 'DCLOCATION': 'SCL3', 11:54:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:30 INFO - 'HOMEDRIVE': 'C:', 11:54:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:30 INFO - 'KTS_VERSION': '1.19c', 11:54:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:30 INFO - 'MOZ_NO_REMOTE': '1', 11:54:30 INFO - 'NO_EM_RESTART': '1', 11:54:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:30 INFO - 'OS': 'Windows_NT', 11:54:30 INFO - 'OURDRIVE': 'C:', 11:54: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;', 11:54:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:30 INFO - 'PROCESSOR_LEVEL': '6', 11:54:30 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:30 INFO - 'PROMPT': '$P$G', 11:54:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:30 INFO - 'PWD': 'C:\\slave\\test', 11:54:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:30 INFO - 'SYSTEMDRIVE': 'C:', 11:54:30 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:30 INFO - 'TEST1': 'testie', 11:54:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:30 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:30 INFO - 'USERNAME': 'cltbld', 11:54:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:30 INFO - 'WINDIR': 'C:\\windows', 11:54:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 11:54:31 INFO - Cleaning up... 11:54:31 INFO - Return code: 0 11:54:31 INFO - Running post-action listener: _resource_record_post_action 11:54:31 INFO - Running post-action listener: _start_resource_monitoring 11:54:31 INFO - Starting resource monitoring. 11:54:31 INFO - [mozharness: 2016-10-29 18:54:31.293000Z] Finished create-virtualenv step (success) 11:54:31 INFO - [mozharness: 2016-10-29 18:54:31.294000Z] Running install step. 11:54:31 INFO - Running pre-action listener: _resource_record_pre_action 11:54:31 INFO - Running main action method: install 11:54:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 11:54:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 11:54:31 INFO - Reading from file tmpfile_stdout 11:54:31 INFO - Using _rmtree_windows ... 11:54:31 INFO - Using _rmtree_windows ... 11:54:31 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:31 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 11:54:31 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 11:54:31 INFO - Reading from file tmpfile_stdout 11:54:31 INFO - Output received: 11:54:31 INFO - Usage: mozinstall-script.py [options] installer 11:54:31 INFO - Options: 11:54:31 INFO - -h, --help show this help message and exit 11:54:31 INFO - -d DEST, --destination=DEST 11:54:31 INFO - Directory to install application into. [default: 11:54:31 INFO - "C:\slave\test"] 11:54:31 INFO - --app=APP Application being installed. [default: firefox] 11:54:31 INFO - Using _rmtree_windows ... 11:54:31 INFO - Using _rmtree_windows ... 11:54:31 INFO - mkdir: C:\slave\test\build\application 11:54:31 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'] 11:54:31 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 11:54:34 INFO - Reading from file tmpfile_stdout 11:54:34 INFO - Output received: 11:54:34 INFO - C:\slave\test\build\application\firefox\firefox.exe 11:54:34 INFO - Using _rmtree_windows ... 11:54:34 INFO - Using _rmtree_windows ... 11:54:34 INFO - Running post-action listener: _resource_record_post_action 11:54:34 INFO - [mozharness: 2016-10-29 18:54:34.136000Z] Finished install step (success) 11:54:34 INFO - [mozharness: 2016-10-29 18:54:34.136000Z] Running run-tests step. 11:54:34 INFO - Running pre-action listener: _resource_record_pre_action 11:54:34 INFO - Running main action method: run_tests 11:54:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 11:54:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 11:54:34 INFO - Python 2.7.3 11:54:34 INFO - Return code: 0 11:54:34 INFO - grabbing minidump binary from tooltool 11:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:34 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 0x01A0BE90>, '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 0x0130A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A78520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 11:54:34 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 11:54:34 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 11:54:34 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 11:54:34 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 11:54:35 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpvdztto 11:54:35 INFO - INFO - File integrity verified, renaming tmpvdztto to win32-minidump_stackwalk.exe 11:54:35 INFO - Return code: 0 11:54:35 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 11:54:35 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 11:54:35 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 11:54:35 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 11:54:35 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 11:54:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-027', '--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 11:54:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-027 --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 11:54:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:54:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:54:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:54:35 INFO - 'COMPUTERNAME': 'T-W732-IX-027', 11:54:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:54:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:54:35 INFO - 'DCLOCATION': 'SCL3', 11:54:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:54:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:54:35 INFO - 'HOMEDRIVE': 'C:', 11:54:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:54:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:54:35 INFO - 'KTS_VERSION': '1.19c', 11:54:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:54:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-027', 11:54:35 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 11:54:35 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 11:54:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:54:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:35 INFO - 'MOZ_NO_REMOTE': '1', 11:54:35 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 11:54:35 INFO - 'NO_EM_RESTART': '1', 11:54:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:54:35 INFO - 'OS': 'Windows_NT', 11:54:35 INFO - 'OURDRIVE': 'C:', 11:54:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:54:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:54:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:54:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:54:35 INFO - 'PROCESSOR_LEVEL': '6', 11:54:35 INFO - 'PROCESSOR_REVISION': '1e05', 11:54:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:54:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:54:35 INFO - 'PROMPT': '$P$G', 11:54:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:54:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:54:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:54:35 INFO - 'PWD': 'C:\\slave\\test', 11:54:35 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 11:54:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:54:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:54:35 INFO - 'SYSTEMDRIVE': 'C:', 11:54:35 INFO - 'SYSTEMROOT': 'C:\\windows', 11:54:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:35 INFO - 'TEST1': 'testie', 11:54:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:54:35 INFO - 'USERDOMAIN': 'T-W732-IX-027', 11:54:35 INFO - 'USERNAME': 'cltbld', 11:54:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:54:35 INFO - 'WINDIR': 'C:\\windows', 11:54:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:54:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:54:35 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-027', '--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 11:54:36 INFO - mozversion application_buildid: 20161029093005 11:54:36 INFO - mozversion application_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 11:54:36 INFO - mozversion application_display_name: Nightly 11:54:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:36 INFO - mozversion application_name: Firefox 11:54:36 INFO - mozversion application_remotingname: firefox 11:54:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:54:36 INFO - mozversion application_vendor: Mozilla 11:54:36 INFO - mozversion application_version: 52.0a1 11:54:36 INFO - mozversion platform_buildid: 20161029093005 11:54:36 INFO - mozversion platform_changeset: 969c3295d3aa77931cca26eddb047d9d74bd9858 11:54:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:54:36 INFO - mozversion platform_version: 52.0a1 11:54:36 INFO - using testdate: 1477767276 11:54:36 INFO - actual date: 1477767276 11:54:36 INFO - starting webserver on 'localhost:49592' 11:54:36 INFO - SUITE-START | Running 2 tests 11:54:36 INFO - TEST-START | dromaeo_css 11:54:36 INFO - operating with platform_type : w7_ 11:54:36 INFO - Initialising browser for dromaeo_css test... 11:54:36 INFO - TEST-INFO | started process 1656 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn2w8dm\profile http://localhost:49592/getInfo.html) 11:54:47 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:54:47 INFO - PROCESS | 1656 | colorDepth:24 11:54:47 INFO - PROCESS | 1656 | Browser inner width/height: 1010/674 11:54:47 INFO - PROCESS | 1656 | __metrics 11:54:47 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 11:54:47 INFO - PROCESS | 1656 | [Child 3804] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 11:54:47 INFO - PROCESS | 1656 | [Child 3804] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 11:54:48 INFO - TEST-INFO | 1656: exit 0 11:54:48 INFO - Browser initialized. 11:54:48 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:48 INFO - TEST-INFO | started process 2948 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn2w8dm\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:49 INFO - PROCESS | 2948 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 11:55:00 INFO - PROCESS | 2948 | RSS: Main: 130977792 11:55:00 INFO - PROCESS | 2948 | 11:55:49 INFO - PROCESS | 2948 | 1477767349742 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] 11:57:58 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49592/tests/dromaeo/cssquery-ext.html) 11:58:01 INFO - PROCESS | 2948 | RSS: Main: 112037888 11:58:01 INFO - PROCESS | 2948 | 12:01:03 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-ext.html (next: http://localhost:49592/tests/dromaeo/cssquery-jquery.html) 12:01:03 INFO - PROCESS | 2948 | RSS: Main: 85348352 12:01:03 INFO - PROCESS | 2948 | 12:04:05 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49592/tests/dromaeo/cssquery-mootools.html) 12:04:05 INFO - PROCESS | 2948 | RSS: Main: 76222464 12:04:05 INFO - PROCESS | 2948 | 12:07:07 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49592/tests/dromaeo/cssquery-prototype.html) 12:07:08 INFO - PROCESS | 2948 | RSS: Main: 74928128 12:07:08 INFO - PROCESS | 2948 | 12:10:10 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49592/tests/dromaeo/cssquery-yui.html) 12:10:11 INFO - PROCESS | 2948 | RSS: Main: 80003072 12:10:11 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | Cycle 1(1): loaded http://localhost:49592/tests/dromaeo/cssquery-yui.html (next: http://localhost:49592/tests/dromaeo/cssquery-dojo.html) 12:13:13 INFO - PROCESS | 2948 | RSS: Main: 71188480 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | __start_tp_report 12:13:13 INFO - PROCESS | 2948 | _x_x_mozilla_page_load 12:13:13 INFO - PROCESS | 2948 | _x_x_mozilla_page_load_details 12:13:13 INFO - PROCESS | 2948 | |i|pagename|runs| 12:13:13 INFO - PROCESS | 2948 | |0;dojo.html;2811.14260150654;2818.1827270091676;2823.421198654276;2833.8724757385917;2843.7298456646618;4855.441624213212;4857.902841943159;4858.659893807435;4903.950960490394;4975.776090075946;4853.126437241295;4907.8527644170645;4921.655484116113;4950.777215025323;4951.702897826124;4281.464816897887;4286.785660716967;4304.332828411586;4312.913741725163;4328.04782947751;4236.618704316611;4294.785260736966;4301.225779359714;4303.0963497665525;4310.849120280791;4223.36649502574;4259.637930775888;4267.871963841089;4279.358096285567;4292.14157168566;4221.5567365426805;4256.851010214627;4263.701540892139;4286.464191976003;4310.374995625617;4300.462442194726;4301.44081269433;4308.116836048634;4308.590683885057;4313.892152696208;4815.783289751935;4868.148074087056;4915.9508404916205;4957.206846904478;4967.130752118364;5085.415177254589;5112.079825631385;5159.58723302135;5234.18870075139;5240.895182096375;3884.47559579456;5110.565601923841;5178.456262092502;5178.559822415062;5284.207368894658;4738.218193998021;4834.564889159992;4836;4839.685420447695;4848.393950756155;5220.843374698765;5225.529702326809;5229.320188375486;5301.575873930076;5339.786408543615;5029.698218106925;5053.620978426633;5080.441151473335;5100.515451032146;5114.744262786846;5110.97444512775;5159.845204643867;5236.790528378899;5256.737163141904;5267.288915996368;5112.897742045213;5114.335136432314;5136.1525348317855;5198.402183748842;5220.216967454836;5106.489351064938;5135.871603209875;5138.97430512845;5203.19350500673;5235.973820130951;3392.1010932103013;3501.474778783203;3502.527158833525;3518.2435776308216;3518.40187168182;3110.7044830741042;3158.242021914724;3209.988853511183;3213.935721285561;3214.8231847248157;4783.928241076386;4816.566509014206;4824.204006338985;4899.265110233422;4984.825531106467;5160.303359046556;5167.9224811627855;5270;5305.575553955674;5312.150055991023;4857.101436234308;4860.586850117709;4951.133551628451;4964.106460837012;4975.0796102721115;4199.454070970754;4313.309870420718;4353.303471444617;4369.038811461473;4409.581089796464;5361;5419.403865574784;5539.833804985777;5568.081266590967;5584.52531534816;5083.669561478492;5168.121419358643;5349.4650534946195;5351.759170837244;5354.2771725818075;5331.200319952039;5415.620906536505;5460.481254280837;5476.780928762964;5584.218209450762;5062.291279220985;5158.767855446589;5204.739763011758;5218.295530103539;5225.634205605571;5240.4759524047295;5248.737563121752;5341.813036543703;5346.465353464778;5358.223057656696;4975.253711943237;5010.624203184703;5059.266406371129;5176.042432150064;5178.948210517847;4994.4755800640405;5032.949670503248;5040.621953353587;5045.445001049955;5048.217526283432;4833.371661683958;4919.114559379346;4945.357103576656;4952.702837829742;4961;4997.800087996439;5052.545270925634;5059.392872855281;5098.821541246039;5135.409427915837;5011.323471331323;5040.16837221869;5108.386993560796;5118.206677964921;5119.0529751996 12:13:13 INFO - PROCESS | 2948 | |1;ext.html;516.1354730825868;516.9767360468778;534.2787237229741;536.7906516458581;595.731920635714;33596.66403335966;34473.82763086181;35458.29083418333;35535.46696799547;35539.644603553956;30199.547006794917;30538.38923221534;30680.84659576699;30761.538576921364;30871.845640771764;7514.924850751491;7702.730404435846;7706.6917323307;7710.113336966237;7724.497907636014;7617.428692848031;7651.349635281008;7680.308772210527;7695.961520192392;7703;7539.321461068503;7666.501677390979;7698.461107722462;7700.383969282472;7704.768856934301;7649.502782319131;7652.464327497078;7680.347170490515;7682.116556596007;7704.6532906019365;7660.961695191544;7712.57580833054;7731.149573546905;7735.961320193419;7738.458307918448;31345;32263;32522;32545;32587.83706081478;2665.640138581286;2704.8512331821744;2705.5130076586206;2732.357895894463;2760.875760590778;26905.32736681579;27006;27068.323291917663;27804.30489237765;27915.720842791514;3335.533025376449;3337.8831740888954;3346.732261419105;3394.575678040245;3459.515667806509;289522;306982;308429;308443;308832;45712.771436142604;45922.5407745925;46028.07943841104;46082.30876536854;46117.84705382392;47657.28514072292;47898.5210147901;48010.519894801306;48024.7598762004;48157.27764083542;12963.351832408342;13080.28058456794;13207.735845283041;13208.471661133639;13237.602871913858;10090.646827361154;10143.289969702199;10200.795984080276;10201.744956376002;10207.132393746466;13578.592642220552;13677.658058548417;13717.45130194801;13785.793213101811;13847.792283115761;5038.420581633086;5150.944056468415;5152.587792976553;5160.045391602491;5160.045391602491;681.7589190470546;718.4503854551273;751.6204316820081;756.9507981981153;815.2011029191307;24561.631575526382;25153.93136208032;25383.61924571133;25445.236642900374;25596.976120954954;801.5951944268107;814.2142832166869;826.4049884083571;834.0783434305006;850.4344610833726;12343;12442.440090195963;12696.047796415305;12907.225566466042;12930.418131184118;458584;460200;461413;463069;463195;43556;43591.78204109023;43630.12739745187;43660.90847728832;43696.3445548317;1010.1363334349172;1015.0762805846645;1031.510032734445;1031.834906414985;1033.720895358264;23622.645660314764;24270.87864560666;24288;24430.144944926844;24929.750702493468;42114.368284475146;42361.15277694367;42656;42706.78646606747;42718.57281427146;380376;390720;401805;402960;405407;18763.34328298503;19339.226430943167;21508.354749356917;21845.235416760337;21871.343859684235;36296.27407451889;38089.4286585707;38188.42717359186;38357.424638631004;38411;32431.189220268738;32877.17807054843;32940.67059329376;33226.169345766546;33683.15792105217;33184.66815331912;33276.83361583274;33319;33430.665693343726;33716.66283337135;27639.723602763715;31111.84444077765;31887.52168717425;32060.839695802304;32090.839545803057;29557.556636650897;29947.401051979276;30445.543316850708;30523;30574.694253057183;23683.763162368155;23839.404014899766;23936.332415178873;24111.879440602686;24161.395965101012 12:13:13 INFO - PROCESS | 2948 | |2;jquery.html;301.59736751436833;301.9879204831807;303.7372672638169;307.70152951636913;316.0486934327673;16314.184290785473;17000.574985625364;17023.489295321127;17237.82762172378;17268.74096888546;15632.140232287247;15683.45107921223;15817.60455988601;15915.84084159158;16191;5031.899362012758;5198.2722418861385;5201.583873290147;5207.9479205207845;5209.557187639055;4939.58013568847;5039.546440820325;5080.695158290496;5157.4068982067165;5278.261043453919;5306.230596563496;5308.814191503298;5309.707966061865;5314.627976041678;5319.148936170213;5140.100482415581;5289.312389389374;5307.336582927134;5316.415194328621;5594.328680558318;5135.717535535544;5144.845654630367;5145.511176438233;5146.305248791403;5150.665206761549;10706;11013.944930275378;11026;19322.454203663674;20276.493087672778;1696.4401747423444;1714.4428060880139;1725.0770837601822;1733.2027267456986;1902.2105826082159;8493.447925884859;8555.486670799772;8618;9456.196223321025;12977.87022129776;1609.34974511998;1628.9818863210494;1635.492997170881;1653.8594219491358;1671.7575951486967;82813;86797.56601216954;88128;88253.55873220657;88459;8974.19232269099;9071.002189759121;9098.272138228926;9130.406523575946;9227.400218985744;11664.358460284766;11700.765984680258;11715.297082175097;11724.355160466257;11739.765204695857;6938.687759050855;7011.3339232772805;7018.543794653331;7071.752488662872;7077.610731409719;7041.436685065182;7046.365827075588;7049.718011279491;7151.89272160918;7187.209406965229;3635.2547727715755;3635.890923272253;3662.5788034375855;3704.962950370462;3705.314516814398;1460.3720400228003;1484.6511069898565;1495.0655840099937;1495.6485225971887;1505.9397624094913;713.4149997002512;742.4691345687859;777.4324742937687;808.0747544062134;846.9618867150994;16542.503724888273;16683.83316166847;17136.48590542286;17184.82815171857;17593.38423155209;785.5954183595453;805.4160733468306;812.382589232176;824.04410883375;852.4075767341762;9131.589078491485;9573.760655983517;9581.089796469325;9645.565949532287;9705.175060119827;357260;407851;408848;408913;411520;89480.10519894848;92543.0745692548;92809;94812.05187948032;95231;762.5348537392186;789.2896393246125;789.9684012639545;793.7499687598388;809.1908091907974;15225.923870380577;15501.612459688147;15625.29686164148;15651;15973.440929567409;34122.48816267708;42250;44100.7794961036;44752.88117797081;45124.32313515365;60614.696926515084;63234.68382658057;63548.364516354246;63911;64543;6119.724612392542;6415.743370265137;6517.73929042833;6538.869222615616;6872.656367181721;10528.78942421132;12765.617031488699;12876.678083047998;12883.227006379648;12985.610431687064;10210.489475526343;10344.482775861328;10388.688339349832;10456.895431045592;11028.613998510014;10293.43386113771;10415.583376664848;10550.89449105499;10954.561817527529;11035.944820275847;10773.515191816543;10879.619213327496;11028.172887033345;11069.335839849635;11118.277311974696;7285.016522769359;7292.671829767567;7366.77899663011;7369.373603243785;7501.437392195498;8327.417080804285;10165.491725413553;10419.166466682798;10672.57309707634;10747.408892510986 12:13:13 INFO - PROCESS | 2948 | |3;mootools.html;921.8709380686703;941.2281928818368;973.5570315506445;984.5815528400431;1133.2463911498853;5918.733656985435;6016;6457.418832305091;6811.284815094418;6830.282820303871;6603.834904127387;6730.932690673092;6740;6812.659367031653;6825.112735344411;1855.647426988871;1870.9812901871012;1874.8875067495994;1875.4373687893578;1881.887086774791;1843.778746550409;1854.7866995295512;1867.7478540397008;1869.719542068687;1871.3637363296477;1832.6426346862368;1846.3260909767973;1847.3995951315808;1850.731643911632;1850.8154780940208;1733.6272701369203;1757.912104394775;1770.2653398839466;1771.477414162825;1772.3087995681694;1783.7502749615064;1789.2395731814056;1804.53082198628;1804.6120084181964;1805.6096805459722;6456.967715161394;6537.5423720339595;6563.146790917198;6589.341065893373;6600.471962243009;4036.8385264589383;4092;4120.896977575555;4134.883581433026;4137.275976704064;2716;2761.4753196892525;2775.4865349910333;2785.331520435101;2796.4127533218048;811.7037281392309;845.2097289034772;848.2704873808519;853.3130829682103;857.8155696525278;8586.44188127776;8593.742187734379;8606.053334133241;8609.956950215272;8627.698030568963;3267.673232676761;3391.0505058583635;3408.897733068012;3422.6235114137426;3423.5891692997;6823.112995310578;6827.556208846409;6834.453243740489;6847.417969472551;6855.760048398282;3953.446517487554;3955.7428767130045;3960.8019599019935;3963.326234540135;3965.980170099189;3433.3819912415506;3518.665726755954;3762.360398732222;3801.6388443097862;3805.562360328542;2826.971730282712;2883.178294186147;2906.811057281312;2921.897733579357;2923.2984083820156;873.2577309287182;884.3234925282164;885.2475395913551;888.9555522223862;891.4695756025284;680.1769858471206;700.1423256510734;702.0487239790114;708.1325376413852;709.8225443639091;4350.347447882843;4417.646588272931;4469.7318160910445;4473.105378924163;4484.529124441887;675.625028109404;676.9356911093438;685.5098604497808;726.8800647893144;763.4465012865628;3044.497657886468;3069.2633767895545;3071.1400807773857;3083.198368424181;3107.238726512019;7954.642041108164;8006.719764808204;8100.149484304181;8134.877976830352;8183.918160818434;8026.518408895485;8063.233992770677;8154.103048664642;8165.469244499089;8184.058833234135;5929.229200204032;6217;6278.748850045947;6296.0555916612875;6296.150019747367;4107.301758700968;4269.252880745919;4293.806778694904;4313.654907607447;4339.218940590597;7489.438292128003;7514.173440921385;7517.812054698457;7606.125295591078;7616.0099187105325;7289.599072051088;7569.129550101588;7664;7668.386529077772;7697.114831794192;5147.305113809588;5391.9460805391445;5436.510714035756;5449.591280653887;5454.29094217765;5820.126980952891;5984.521238301013;6258.217722784652;6324.209473815773;6419.614823110628;5638.464345887134;5643.407442218508;5690.2887139107615;5848.53211743068;5879.382664820132;5652.039153344023;5777.566682498914;5778.27771528559;5791.449812267828;5847.239858818326;5360.946390536044;5401.648892821954;5407.215953686772;5407.810726624549;5417.51242388187;3564.8574057037426;3737.9065523362133;3773.603771603942;3794.5446546414605;3803.6386543278345;3898.1813819098306;3899.941500877432;3915.373540233663;3916.823742931632;3965.365541513344 12:13:13 INFO - PROCESS | 2948 | |4;prototype.html;247.54822449030524;248.11052377227637;251.45936237090254;257.79505293291834;259.3580887303922;5232.3459567554055;5328.280682107914;5344.412114667383;5378.300820893283;5391.272178255935;5227.634065615409;5296.655717378368;5297.8014124138535;5325.680459172443;5351.652142610737;3797.259534390795;3817.3701339278987;3865.516810398693;3869.593692662272;3882.107115363468;3862.439946207798;3867.1105645701505;3879.301725689375;3880.689544836406;3882.7282090253693;3733.253349330131;3779.7732136071922;3819.2934307153128;3828.617138286163;3835.6931445484433;3744.756590821588;3828.3300422425964;3833.4249862520564;3834.5973672764235;3838.2899163654533;3196.9040928772174;3285.737141028712;3292.5555050068174;3298.802071875671;3301.3067255876294;5106.64253502255;5205.505476979681;5212.817551385721;5213.973930130363;5217.139172036609;4499.212637788374;4504.48198457175;4506.188886000518;4510.0979804039;4514.45826500822;5391.81128660495;5476.315460567429;5487.506124448819;5490.368607610136;5498.477644623755;3613.530241068644;3619.7828130312264;3623.40213864712;3624.8731294404834;3629.491871138043;5759.222504961818;5765.971170144165;5767.480926716574;5770.596058275881;5807.390224026501;3694.8152592370275;3702.9814850926114;3708.9258214835554;3711.2020915503294;3712.3132220539287;3468.4103702370658;3642.7996460194413;3660.432632941845;3754.1740817020213;3760.1539653577724;4294.527601963781;4372.366006928977;4390.429244198298;4393.384926110347;4393.7363758174615;5578.107502799533;5611.242482264924;5620.85947851307;5625.6905870176715;5635.380108188096;1241.3979220078254;1247.0522402973625;1263.5451237554473;1268.4355461819587;1271.879171478708;764.1670579068841;768.2164192523595;779.3609240422912;793.5159552672865;803.2329125684956;5217.817376391884;5236.528712415825;5255.39562950258;5261.76322065508;5309.787608495617;5667.744951477193;5677.501899164355;5687.800926967537;5691.117876729114;5709.3719690834805;5246.134387826042;5259.868503287448;5294.12646913255;5295.285136506523;5298.629095963245;2945.2195168280423;3047.5467248864875;3053.358794653148;3057.7400920921527;3065.4941934580543;5966;5983.700814959235;5985.730642121115;5985.730642121115;5999.730012149376;5805.36141024487;5812.273465816773;5815.778686475887;5826.388229235954;5833.987274390413;5788.482505699227;5887.381824908409;5948.821535353946;5994.850128746729;6002.249718785152;5573.554115670656;5605.65464288576;5709.25779648643;5725.713714314351;5730.770769169187;5608.850185712001;5622;5622.493975542057;5631.88736225265;5653.462921022497;5639.577031722719;5926.259217597801;5934.673592952429;5947.286325640951;5961.791337303173;3447.87932422364;3520.9119772005906;3531.187826799799;3534.3814832403714;3538.0624134603836;5661.2923384576925;5677.3471050829685;5703.347943206429;5717.085266357363;5727.36998930109;5467.343918729778;5493.489290445;5496.587755918402;5512.586556008235;5524.281843360337;5333.200019997032;5352;5401.378841433286;5448.482394172705;5473.343198816167;5320.627556071193;5354.598405119709;5374.704391258519;5377.973110134424;5378.247045413724;5361.954418888279;5477.3427188737605;5483.970930755646;5491.588130890279;5511.889762204813;5378.1663842104535;5502.669839809624;5512.476314750093;5521.365043020104;5525.9723701382845 12:13:13 INFO - PROCESS | 2948 | |5;yui.html;581.874896897167;595.9940400595992;603.5714642603751;607.5595193484723;608.8082254089961;13669.589912302621;14358.425662973481;14386.280685965712;14457.710845783104;14514;12562.685932851684;12614.621561353151;12619.936900315508;13091.80362294564;13652.863471365283;498.2376963246239;516.6409345504876;530.5410819641002;533.573141486811;535.0556268186654;534.6631622078086;546.0198942897508;547.8383876756367;547.9287692599976;551.6689986008403;570.9486146246858;572.7680289482745;574.9310082790071;575.3010092737323;575.7035126909645;565.4317411001916;572.3046498504295;572.5648507134566;573.2776701356279;573.3778849947812;402.6577409202155;413.18602353363826;414.06628053738626;414.4943169333408;415.0805964787979;12875.484980600764;12892.484300628057;12921.483140674361;12930.28883411412;12935.223886566837;11449.141314401453;12022.338771367571;12097.092718046182;12226.510939562408;12255.95824354922;10379.169666426744;10403;10618.309809862334;10641.04230619248;10751.6236931707;3261.445554255782;3264.069740124053;3286.3920174767745;3286.6384697683234;3375.9493607595905;64540;67119.66440167816;67395.32604673988;67418.3258167417;67448;8926.73219803407;9245.630174792934;9309.72070837876;9407.2003879671;9437.19783818383;16401.42595009169;16408.26162822676;16506.917465412596;16613.169341532874;16693.916530417515;6813.897791533131;6888.862222755517;7007.789766307019;7052.33002864727;7062.435005199572;3719.9070023249637;3753.6246375362794;3773.735838491334;3801.334766415866;3833.961660383416;2691.461707658476;2745.2450576091733;2777.4861650594303;2778.2082106599523;2781.568856827195;444.46442037344923;456.5115326600572;466.2190830359135;468.2437862851538;469.8026828731941;530.7585048802786;573.7418161827194;577.8728679690462;579.4553120067123;592.8369698332907;8097.473664211808;8165.510069395856;8226.259636632733;8240.340772738167;8371.869797577248;486.2268992302186;518.5229588778333;561.6545824318015;574.2333984131149;584.4885724990634;3995.6603688686205;4071.89820254496;4123.422720819088;4133.090720041586;4256.1487702459635;83385.58307208425;83845.58077209698;85218.57390713131;85356;87162.56418717866;76193.61903190448;76434.85347719789;77477.225227747;77761.61119194368;77879.61060194776;65601.34398655953;68240.31759682439;68607.65696171488;68719.65640171868;69447.65276173587;8156.708759091555;8300.377471689526;8367.95816020916;8388.245057944814;8491.235788778793;41306.586934130275;42965;43098.784506078526;43228.35157472578;43562;55310;57392.426075740375;58120;58304.125438119314;58330;11096.833547496955;11135.164862635496;11194;11254.437278136224;11298.548058077586;13180.275084870424;13254.138480998705;13287.335633218107;13306.600801975956;13586.32068396596;12145.939270303592;12257.080718946294;12406.379681015733;12593.93703031479;12629.557965471164;11946.163768536118;12154.209976351509;12423.130380873252;12702.237865728086;12970.935145324212;12134.696632584257;12389;12677.492900283885;12734.490620375082;12801.679958001125;10016.54144329067;10485.161187105166;10536.57853685844;10795;10838.241323107623;10001.649942251986;10061.295709300572;10147.543360548645;10223.028812262823;10269.537870795682 12:13:13 INFO - PROCESS | 2948 | __end_tp_report 12:13:13 INFO - PROCESS | 2948 | __start_cc_report 12:13:13 INFO - PROCESS | 2948 | _x_x_mozilla_cycle_collect,276 12:13:13 INFO - PROCESS | 2948 | __end_cc_report 12:13:13 INFO - PROCESS | 2948 | __startTimestamp1477768393642__endTimestamp 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | ------- Summary: start ------- 12:13:13 INFO - PROCESS | 2948 | Number of tests: 6 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#0] dojo.html Cycles:165 Average:4777.13 Median:4994.48 stddev:631.22 (12.6%) stddev-sans-first:614.03 12:13:13 INFO - PROCESS | 2948 | Values: 2811.1 2818.2 2823.4 2833.9 2843.7 4855.4 4857.9 4858.7 4904.0 4975.8 4853.1 4907.9 4921.7 4950.8 4951.7 4281.5 4286.8 4304.3 4312.9 4328.0 4236.6 4294.8 4301.2 4303.1 4310.8 4223.4 4259.6 4267.9 4279.4 4292.1 4221.6 4256.9 4263.7 4286.5 4310.4 4300.5 4301.4 4308.1 4308.6 4313.9 4815.8 4868.1 4916.0 4957.2 4967.1 5085.4 5112.1 5159.6 5234.2 5240.9 3884.5 5110.6 5178.5 5178.6 5284.2 4738.2 4834.6 4836.0 4839.7 4848.4 5220.8 5225.5 5229.3 5301.6 5339.8 5029.7 5053.6 5080.4 5100.5 5114.7 5111.0 5159.8 5236.8 5256.7 5267.3 5112.9 5114.3 5136.2 5198.4 5220.2 5106.5 5135.9 5139.0 5203.2 5236.0 3392.1 3501.5 3502.5 3518.2 3518.4 3110.7 3158.2 3210.0 3213.9 3214.8 4783.9 4816.6 4824.2 4899.3 4984.8 5160.3 5167.9 5270.0 5305.6 5312.2 4857.1 4860.6 4951.1 4964.1 4975.1 4199.5 4313.3 4353.3 4369.0 4409.6 5361.0 5419.4 5539.8 5568.1 5584.5 5083.7 5168.1 5349.5 5351.8 5354.3 5331.2 5415.6 5460.5 5476.8 5584.2 5062.3 5158.8 5204.7 5218.3 5225.6 5240.5 5248.7 5341.8 5346.5 5358.2 4975.3 5010.6 5059.3 5176.0 5178.9 4994.5 5032.9 5040.6 5045.4 5048.2 4833.4 4919.1 4945.4 4952.7 4961.0 4997.8 5052.5 5059.4 5098.8 5135.4 5011.3 5040.2 5108.4 5118.2 5119.1 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#1] ext.html Cycles:180 Average:50811.56 Median:18471.70 stddev:104437.14 (565.4%) stddev-sans-first:104661.84 12:13:13 INFO - PROCESS | 2948 | Values: 516.1 517.0 534.3 536.8 595.7 33596.7 34473.8 35458.3 35535.5 35539.6 30199.5 30538.4 30680.8 30761.5 30871.8 7514.9 7702.7 7706.7 7710.1 7724.5 7617.4 7651.3 7680.3 7696.0 7703.0 7539.3 7666.5 7698.5 7700.4 7704.8 7649.5 7652.5 7680.3 7682.1 7704.7 7661.0 7712.6 7731.1 7736.0 7738.5 31345.0 32263.0 32522.0 32545.0 32587.8 2665.6 2704.9 2705.5 2732.4 2760.9 26905.3 27006.0 27068.3 27804.3 27915.7 3335.5 3337.9 3346.7 3394.6 3459.5 289522.0 306982.0 308429.0 308443.0 308832.0 45712.8 45922.5 46028.1 46082.3 46117.8 47657.3 47898.5 48010.5 48024.8 48157.3 12963.4 13080.3 13207.7 13208.5 13237.6 10090.6 10143.3 10200.8 10201.7 10207.1 13578.6 13677.7 13717.5 13785.8 13847.8 5038.4 5150.9 5152.6 5160.0 5160.0 681.8 718.5 751.6 757.0 815.2 24561.6 25153.9 25383.6 25445.2 25597.0 801.6 814.2 826.4 834.1 850.4 12343.0 12442.4 12696.0 12907.2 12930.4 458584.0 460200.0 461413.0 463069.0 463195.0 43556.0 43591.8 43630.1 43660.9 43696.3 1010.1 1015.1 1031.5 1031.8 1033.7 23622.6 24270.9 24288.0 24430.1 24929.8 42114.4 42361.2 42656.0 42706.8 42718.6 380376.0 390720.0 401805.0 402960.0 405407.0 18763.3 19339.2 21508.4 21845.2 21871.3 36296.3 38089.4 38188.4 38357.4 38411.0 32431.2 32877.2 32940.7 33226.2 33683.2 33184.7 33276.8 33319.0 33430.7 33716.7 27639.7 31111.8 31887.5 32060.8 32090.8 29557.6 29947.4 30445.5 30523.0 30574.7 23683.8 23839.4 23936.3 24111.9 24161.4 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#2] jquery.html Cycles:180 Average:25658.46 Median:4985.74 stddev:66953.50 (1342.9%) stddev-sans-first:67114.25 12:13:13 INFO - PROCESS | 2948 | Values: 301.6 302.0 303.7 307.7 316.0 16314.2 17000.6 17023.5 17237.8 17268.7 15632.1 15683.5 15817.6 15915.8 16191.0 5031.9 5198.3 5201.6 5207.9 5209.6 4939.6 5039.5 5080.7 5157.4 5278.3 5306.2 5308.8 5309.7 5314.6 5319.1 5140.1 5289.3 5307.3 5316.4 5594.3 5135.7 5144.8 5145.5 5146.3 5150.7 10706.0 11013.9 11026.0 19322.5 20276.5 1696.4 1714.4 1725.1 1733.2 1902.2 8493.4 8555.5 8618.0 9456.2 12977.9 1609.3 1629.0 1635.5 1653.9 1671.8 82813.0 86797.6 88128.0 88253.6 88459.0 8974.2 9071.0 9098.3 9130.4 9227.4 11664.4 11700.8 11715.3 11724.4 11739.8 6938.7 7011.3 7018.5 7071.8 7077.6 7041.4 7046.4 7049.7 7151.9 7187.2 3635.3 3635.9 3662.6 3705.0 3705.3 1460.4 1484.7 1495.1 1495.6 1505.9 713.4 742.5 777.4 808.1 847.0 16542.5 16683.8 17136.5 17184.8 17593.4 785.6 805.4 812.4 824.0 852.4 9131.6 9573.8 9581.1 9645.6 9705.2 357260.0 407851.0 408848.0 408913.0 411520.0 89480.1 92543.1 92809.0 94812.1 95231.0 762.5 789.3 790.0 793.7 809.2 15225.9 15501.6 15625.3 15651.0 15973.4 34122.5 42250.0 44100.8 44752.9 45124.3 60614.7 63234.7 63548.4 63911.0 64543.0 6119.7 6415.7 6517.7 6538.9 6872.7 10528.8 12765.6 12876.7 12883.2 12985.6 10210.5 10344.5 10388.7 10456.9 11028.6 10293.4 10415.6 10550.9 10954.6 11035.9 10773.5 10879.6 11028.2 11069.3 11118.3 7285.0 7292.7 7366.8 7369.4 7501.4 8327.4 10165.5 10419.2 10672.6 10747.4 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#3] mootools.html Cycles:180 Average:4293.80 Median:5546.38 stddev:2383.25 (43.0%) stddev-sans-first:2376.46 12:13:13 INFO - PROCESS | 2948 | Values: 921.9 941.2 973.6 984.6 1133.2 5918.7 6016.0 6457.4 6811.3 6830.3 6603.8 6730.9 6740.0 6812.7 6825.1 1855.6 1871.0 1874.9 1875.4 1881.9 1843.8 1854.8 1867.7 1869.7 1871.4 1832.6 1846.3 1847.4 1850.7 1850.8 1733.6 1757.9 1770.3 1771.5 1772.3 1783.8 1789.2 1804.5 1804.6 1805.6 6457.0 6537.5 6563.1 6589.3 6600.5 4036.8 4092.0 4120.9 4134.9 4137.3 2716.0 2761.5 2775.5 2785.3 2796.4 811.7 845.2 848.3 853.3 857.8 8586.4 8593.7 8606.1 8610.0 8627.7 3267.7 3391.1 3408.9 3422.6 3423.6 6823.1 6827.6 6834.5 6847.4 6855.8 3953.4 3955.7 3960.8 3963.3 3966.0 3433.4 3518.7 3762.4 3801.6 3805.6 2827.0 2883.2 2906.8 2921.9 2923.3 873.3 884.3 885.2 889.0 891.5 680.2 700.1 702.0 708.1 709.8 4350.3 4417.6 4469.7 4473.1 4484.5 675.6 676.9 685.5 726.9 763.4 3044.5 3069.3 3071.1 3083.2 3107.2 7954.6 8006.7 8100.1 8134.9 8183.9 8026.5 8063.2 8154.1 8165.5 8184.1 5929.2 6217.0 6278.7 6296.1 6296.2 4107.3 4269.3 4293.8 4313.7 4339.2 7489.4 7514.2 7517.8 7606.1 7616.0 7289.6 7569.1 7664.0 7668.4 7697.1 5147.3 5391.9 5436.5 5449.6 5454.3 5820.1 5984.5 6258.2 6324.2 6419.6 5638.5 5643.4 5690.3 5848.5 5879.4 5652.0 5777.6 5778.3 5791.4 5847.2 5360.9 5401.6 5407.2 5407.8 5417.5 3564.9 3737.9 3773.6 3794.5 3803.6 3898.2 3899.9 3915.4 3916.8 3965.4 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#4] prototype.html Cycles:180 Average:4556.59 Median:5326.98 stddev:1451.93 (27.3%) stddev-sans-first:1419.52 12:13:13 INFO - PROCESS | 2948 | Values: 247.5 248.1 251.5 257.8 259.4 5232.3 5328.3 5344.4 5378.3 5391.3 5227.6 5296.7 5297.8 5325.7 5351.7 3797.3 3817.4 3865.5 3869.6 3882.1 3862.4 3867.1 3879.3 3880.7 3882.7 3733.3 3779.8 3819.3 3828.6 3835.7 3744.8 3828.3 3833.4 3834.6 3838.3 3196.9 3285.7 3292.6 3298.8 3301.3 5106.6 5205.5 5212.8 5214.0 5217.1 4499.2 4504.5 4506.2 4510.1 4514.5 5391.8 5476.3 5487.5 5490.4 5498.5 3613.5 3619.8 3623.4 3624.9 3629.5 5759.2 5766.0 5767.5 5770.6 5807.4 3694.8 3703.0 3708.9 3711.2 3712.3 3468.4 3642.8 3660.4 3754.2 3760.2 4294.5 4372.4 4390.4 4393.4 4393.7 5578.1 5611.2 5620.9 5625.7 5635.4 1241.4 1247.1 1263.5 1268.4 1271.9 764.2 768.2 779.4 793.5 803.2 5217.8 5236.5 5255.4 5261.8 5309.8 5667.7 5677.5 5687.8 5691.1 5709.4 5246.1 5259.9 5294.1 5295.3 5298.6 2945.2 3047.5 3053.4 3057.7 3065.5 5966.0 5983.7 5985.7 5985.7 5999.7 5805.4 5812.3 5815.8 5826.4 5834.0 5788.5 5887.4 5948.8 5994.9 6002.2 5573.6 5605.7 5709.3 5725.7 5730.8 5608.9 5622.0 5622.5 5631.9 5653.5 5639.6 5926.3 5934.7 5947.3 5961.8 3447.9 3520.9 3531.2 3534.4 3538.1 5661.3 5677.3 5703.3 5717.1 5727.4 5467.3 5493.5 5496.6 5512.6 5524.3 5333.2 5352.0 5401.4 5448.5 5473.3 5320.6 5354.6 5374.7 5378.0 5378.2 5362.0 5477.3 5484.0 5491.6 5511.9 5378.2 5502.7 5512.5 5521.4 5526.0 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [#5] yui.html Cycles:180 Average:16963.54 Median:23610.57 stddev:23301.38 (98.7%) stddev-sans-first:23334.28 12:13:13 INFO - PROCESS | 2948 | Values: 581.9 596.0 603.6 607.6 608.8 13669.6 14358.4 14386.3 14457.7 14514.0 12562.7 12614.6 12619.9 13091.8 13652.9 498.2 516.6 530.5 533.6 535.1 534.7 546.0 547.8 547.9 551.7 570.9 572.8 574.9 575.3 575.7 565.4 572.3 572.6 573.3 573.4 402.7 413.2 414.1 414.5 415.1 12875.5 12892.5 12921.5 12930.3 12935.2 11449.1 12022.3 12097.1 12226.5 12256.0 10379.2 10403.0 10618.3 10641.0 10751.6 3261.4 3264.1 3286.4 3286.6 3375.9 64540.0 67119.7 67395.3 67418.3 67448.0 8926.7 9245.6 9309.7 9407.2 9437.2 16401.4 16408.3 16506.9 16613.2 16693.9 6813.9 6888.9 7007.8 7052.3 7062.4 3719.9 3753.6 3773.7 3801.3 3834.0 2691.5 2745.2 2777.5 2778.2 2781.6 444.5 456.5 466.2 468.2 469.8 530.8 573.7 577.9 579.5 592.8 8097.5 8165.5 8226.3 8240.3 8371.9 486.2 518.5 561.7 574.2 584.5 3995.7 4071.9 4123.4 4133.1 4256.1 83385.6 83845.6 85218.6 85356.0 87162.6 76193.6 76434.9 77477.2 77761.6 77879.6 65601.3 68240.3 68607.7 68719.7 69447.7 8156.7 8300.4 8368.0 8388.2 8491.2 41306.6 42965.0 43098.8 43228.4 43562.0 55310.0 57392.4 58120.0 58304.1 58330.0 11096.8 11135.2 11194.0 11254.4 11298.5 13180.3 13254.1 13287.3 13306.6 13586.3 12145.9 12257.1 12406.4 12593.9 12629.6 11946.2 12154.2 12423.1 12702.2 12970.9 12134.7 12389.0 12677.5 12734.5 12801.7 10016.5 10485.2 10536.6 10795.0 10838.2 10001.6 10061.3 10147.5 10223.0 10269.5 12:13:13 INFO - PROCESS | 2948 | -------- Summary: end -------- 12:13:13 INFO - PROCESS | 2948 | 12:13:13 INFO - PROCESS | 2948 | [Child 3916] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 12:13:14 INFO - TEST-INFO | 2948: exit 0 12:13:15 INFO - TEST-OK | dromaeo_css | took 1118834ms 12:13:15 INFO - TEST-START | kraken 12:13:15 INFO - operating with platform_type : w7_ 12:13:15 INFO - Initialising browser for kraken test... 12:13:15 INFO - TEST-INFO | started process 3468 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpfqgcha\profile http://localhost:49592/getInfo.html) 12:13:21 INFO - PROCESS | 3468 | __metrics Screen width/height:1600/1200 12:13:21 INFO - PROCESS | 3468 | colorDepth:24 12:13:21 INFO - PROCESS | 3468 | Browser inner width/height: 1010/674 12:13:21 INFO - PROCESS | 3468 | __metrics 12:13:22 ERROR - PROCESS | 3468 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 12:13:22 INFO - PROCESS | 3468 | [Child 620] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 12:13:22 INFO - PROCESS | 3468 | console.error: 12:13:22 INFO - PROCESS | 3468 | Message: Error: Connection closed before committing the transaction. 12:13:22 INFO - PROCESS | 3468 | Stack: 12:13:22 INFO - PROCESS | 3468 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 12:13:22 INFO - PROCESS | 3468 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 12:13:22 INFO - PROCESS | 3468 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 12:13:22 INFO - PROCESS | 3468 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 12:13:22 INFO - PROCESS | 3468 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 12:13:22 INFO - PROCESS | 3468 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 12:13:22 INFO - PROCESS | 3468 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 12:13:22 INFO - PROCESS | 3468 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 12:13:22 INFO - PROCESS | 3468 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 12:13:22 INFO - PROCESS | 3468 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 12:13:22 INFO - PROCESS | 3468 | Barrier/this.client.addBlocker/promise13.4% 12:14:19 INFO - TinderboxPrint: I/O read bytes / time
165,888,512 / 2,762,790 12:14:19 INFO - TinderboxPrint: I/O write bytes / time
551,142,400 / 3,775,600 12:14:19 INFO - TinderboxPrint: CPU idle
8,229.6 (86.6%) 12:14:19 INFO - TinderboxPrint: CPU user
1,228.9 (12.9%) 12:14:19 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 34035712; Read time: 0; Write time: 52260 12:14:19 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 165888512; Write bytes: 481385472; Read time: 2762790; Write time: 3681240 12:14:20 INFO - Running post-run listener: _upload_blobber_files 12:14:20 INFO - Blob upload gear active. 12:14:20 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 12:14:20 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 12:14:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 12:14:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 12:14:21 INFO - (blobuploader) - INFO - Open directory for files ... 12:14:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 12:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:21 INFO - (blobuploader) - INFO - Done attempting. 12:14:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 12:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:22 INFO - (blobuploader) - INFO - Done attempting. 12:14:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 12:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:14:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:31 INFO - (blobuploader) - INFO - Done attempting. 12:14:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:14:31 INFO - Return code: 0 12:14:31 INFO - rmtree: C:\slave\test\build\uploaded_files.json 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 12:14:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90bafa370b240b53dba5bd760acf1939534eb51c6dd3a96e97c59ce0b1ab6aa3d99ab163c50f6827109c26394e44d9baef4ebcbc910ea909a821b7e2be58d6d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ab8a372c7c94ccd5f8a90cae8c54418462e358f5947a72ddce10cacded4e618ec90088c825fcbbba94b5b793467018f6d92554c625f2971107751177a94c72b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9c40cdbff9018e7c32b30b51f3ad4a18cd5c394a2c366d69e7aa48ca279728f2b26c37b33ea1539f772afdb3fbced54c2a8aa1230d7bed29dfb0bf8c65ca0a9"} 12:14:31 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 12:14:31 INFO - Writing to file C:\slave\test\properties\blobber_files 12:14:31 INFO - Contents: 12:14:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90bafa370b240b53dba5bd760acf1939534eb51c6dd3a96e97c59ce0b1ab6aa3d99ab163c50f6827109c26394e44d9baef4ebcbc910ea909a821b7e2be58d6d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ab8a372c7c94ccd5f8a90cae8c54418462e358f5947a72ddce10cacded4e618ec90088c825fcbbba94b5b793467018f6d92554c625f2971107751177a94c72b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9c40cdbff9018e7c32b30b51f3ad4a18cd5c394a2c366d69e7aa48ca279728f2b26c37b33ea1539f772afdb3fbced54c2a8aa1230d7bed29dfb0bf8c65ca0a9"} 12:14:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:14:31 INFO - Copying logs to upload dir... 12:14:31 INFO - mkdir: C:\slave\test\build\upload\logs 12:14:31 INFO - Copying logs to upload dir... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... 12:14:31 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1328.295000 ========= master_lag: 0.37 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-10-29 12:14:31.496811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:31.497587) ========= '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-027 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-027 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-027 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/Fx-Team/sha512/90bafa370b240b53dba5bd760acf1939534eb51c6dd3a96e97c59ce0b1ab6aa3d99ab163c50f6827109c26394e44d9baef4ebcbc910ea909a821b7e2be58d6d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ab8a372c7c94ccd5f8a90cae8c54418462e358f5947a72ddce10cacded4e618ec90088c825fcbbba94b5b793467018f6d92554c625f2971107751177a94c72b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9c40cdbff9018e7c32b30b51f3ad4a18cd5c394a2c366d69e7aa48ca279728f2b26c37b33ea1539f772afdb3fbced54c2a8aa1230d7bed29dfb0bf8c65ca0a9"} build_url:https://queue.taskcluster.net/v1/task/Ews6TEOkRmu7sl-zgbvT4A/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/Ews6TEOkRmu7sl-zgbvT4A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/90bafa370b240b53dba5bd760acf1939534eb51c6dd3a96e97c59ce0b1ab6aa3d99ab163c50f6827109c26394e44d9baef4ebcbc910ea909a821b7e2be58d6d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ab8a372c7c94ccd5f8a90cae8c54418462e358f5947a72ddce10cacded4e618ec90088c825fcbbba94b5b793467018f6d92554c625f2971107751177a94c72b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9c40cdbff9018e7c32b30b51f3ad4a18cd5c394a2c366d69e7aa48ca279728f2b26c37b33ea1539f772afdb3fbced54c2a8aa1230d7bed29dfb0bf8c65ca0a9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:31.613403) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:31.613897) ========= '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-027 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-027 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-027 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.100000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:31.725396) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:31.725772) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-29 12:14:32.248669) ========= ========= Total master_lag: 0.50 =========