builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-062 starttime: 1476393592.68 results: success (0) buildid: 20161013123018 builduid: d1ace9580dd04d3c8b85f40a81349036 revision: 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 14:19:52.683516) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 14:19:52.684023) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 14:19:52.684346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 14:19:52.831107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 14:19:52.831517) ========= '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-062 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-062 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-062 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 --14:19:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 8.12 MB/s 14:19:54 (8.12 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.290000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 14:19:54.157432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-13 14:19:54.157822) ========= '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-062 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-062 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-062 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=15.401000 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-13 14:20:09.600154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 14:20:09.600949) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 --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 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-062 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-062 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-062 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-13 14:20:10,387 truncating revision to first 12 chars 2016-10-13 14:20:10,387 Setting DEBUG logging. 2016-10-13 14:20:10,387 attempt 1/10 2016-10-13 14:20:10,387 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/62ccb498db3e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 14:20:10,979 unpacking tar archive at: fx-team-62ccb498db3e/testing/mozharness/ 2016-10-13 14:20:11,121 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.197000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 14:20:10.836492) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 14:20:10.836985) ========= script_repo_revision: 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 14:20:10.837877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 14:20:10.838531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 14:20:10.849656) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 13 secs) (at 2016-10-13 14:20:10.850297) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-062 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-062 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-062 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 14:20:12 INFO - MultiFileLogger online at 20161013 14:20:12 in C:\slave\test 14:20:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:20:12 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 14:20:12 INFO - {'append_to_log': False, 14:20:12 INFO - 'base_work_dir': 'C:\\slave\\test', 14:20:12 INFO - 'blob_upload_branch': 'Fx-Team', 14:20:12 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 14:20:12 INFO - 'branch': 'Fx-Team', 14:20:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:20:12 INFO - 'config_files': ('talos/windows_config.py',), 14:20:12 INFO - 'default_actions': ('clobber', 14:20:12 INFO - 'read-buildbot-config', 14:20:12 INFO - 'download-and-extract', 14:20:12 INFO - 'populate-webroot', 14:20:12 INFO - 'create-virtualenv', 14:20:12 INFO - 'install', 14:20:12 INFO - 'run-tests'), 14:20:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:20:12 INFO - 'download_minidump_stackwalk': True, 14:20:12 INFO - 'download_symbols': 'ondemand', 14:20:12 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 14:20:12 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 14:20:12 INFO - 'hg': 'c:/mozilla-build/hg/hg', 14:20:12 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 14:20:12 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 14:20:12 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 14:20:12 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 14:20:12 INFO - 'C:/mozilla-build/tooltool.py'), 14:20:12 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 14:20:12 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 14:20:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:20:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:20:12 INFO - 'installer_path': 'installer.exe', 14:20:12 INFO - 'log_level': 'info', 14:20:12 INFO - 'log_name': 'talos', 14:20:12 INFO - 'log_to_console': True, 14:20:12 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 14:20:12 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 14:20:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 14:20:12 INFO - 'opt_config_files': (), 14:20:12 INFO - 'pip_index': False, 14:20:12 INFO - 'sps_profile': False, 14:20:12 INFO - 'sps_profile_interval': 0, 14:20:12 INFO - 'suite': 'dromaeojs', 14:20:12 INFO - 'system_bits': '32', 14:20:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:20:12 INFO - 'title': 'T-W732-IX-062', 14:20:12 INFO - 'use_talos_json': True, 14:20:12 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 14:20:12 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 14:20:12 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 14:20:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:20:12 INFO - 'work_dir': 'build'} 14:20:12 INFO - [mozharness: 2016-10-13 21:20:12.135000Z] Running clobber step. 14:20:12 INFO - Running pre-action listener: _resource_record_pre_action 14:20:12 INFO - Running main action method: clobber 14:20:12 INFO - rmtree: C:\slave\test\build 14:20:12 INFO - Using _rmtree_windows ... 14:20:12 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 14:22:35 INFO - Running post-action listener: _resource_record_post_action 14:22:35 INFO - [mozharness: 2016-10-13 21:22:35.796000Z] Finished clobber step (success) 14:22:35 INFO - [mozharness: 2016-10-13 21:22:35.797000Z] Running read-buildbot-config step. 14:22:35 INFO - Running pre-action listener: _resource_record_pre_action 14:22:35 INFO - Running main action method: read_buildbot_config 14:22:35 INFO - Using buildbot properties: 14:22:35 INFO - { 14:22:35 INFO - "project": "", 14:22:35 INFO - "product": "firefox", 14:22:35 INFO - "script_repo_revision": "production", 14:22:35 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 14:22:35 INFO - "repository": "", 14:22:35 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 14:22:35 INFO - "buildid": "20161013123018", 14:22:35 INFO - "pgo_build": "True", 14:22:35 INFO - "basedir": "C:\\slave\\test", 14:22:35 INFO - "buildnumber": 235, 14:22:35 INFO - "slavename": "t-w732-ix-062", 14:22:35 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 14:22:35 INFO - "platform": "win7_ix", 14:22:35 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 14:22:35 INFO - "branch": "fx-team", 14:22:35 INFO - "repo_path": "integration/fx-team", 14:22:35 INFO - "revision": "62ccb498db3ec05bef1b6427d5b1b4b0e501cad5", 14:22:35 INFO - "stage_platform": "win32-pgo", 14:22:35 INFO - "builduid": "d1ace9580dd04d3c8b85f40a81349036", 14:22:35 INFO - "slavebuilddir": "test" 14:22:35 INFO - } 14:22:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 14:22:35 INFO - Running post-action listener: _resource_record_post_action 14:22:35 INFO - [mozharness: 2016-10-13 21:22:35.800000Z] Finished read-buildbot-config step (success) 14:22:35 INFO - [mozharness: 2016-10-13 21:22:35.800000Z] Running download-and-extract step. 14:22:35 INFO - Running pre-action listener: _resource_record_pre_action 14:22:35 INFO - Running main action method: download_and_extract 14:22:35 INFO - mkdir: C:\slave\test\build\tests 14:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:35 INFO - trying https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 14:22:35 INFO - Downloading https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/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 14:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/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 14:22:36 INFO - Downloaded 1218 bytes. 14:22:36 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 14:22:36 INFO - Using the following test package requirements: 14:22:36 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 14:22:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 14:22:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 14:22:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'jsshell-win32.zip'], 14:22:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 14:22:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 14:22:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 14:22:36 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 14:22:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 14:22:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 14:22:36 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 14:22:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 14:22:36 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 14:22:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 14:22:36 INFO - Fetch https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 14:22:37 INFO - Http code: 200 14:22:37 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 14:22:37 INFO - x-amz-cf-id: sSV0XUH-60e2ERZYzjcYyGWf13uq0JipZKEJopgT-z8Ww82UAyAvJg== 14:22:37 INFO - x-amz-version-id: tjVmDHJolMljIOev8nRzD.u_xwH87gqx 14:22:38 INFO - Expected file size: 17271835 14:22:38 INFO - Obtained file size: 17271835 14:23:10 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 14:23:10 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 14:23:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 14:23:10 INFO - Fetch https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 14:23:11 INFO - Http code: 200 14:23:11 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 14:23:11 INFO - x-amz-cf-id: 2oARcLUwxqKxhM-oENIW-4OYPSBEFF3KmGXuB5KmL5VKFmv-gOMNbA== 14:23:11 INFO - x-amz-version-id: tjVmDHJolMljIOev8nRzD.u_xwH87gqx 14:23:11 INFO - Expected file size: 17271835 14:23:11 INFO - Obtained file size: 17271835 14:23:23 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 14:23:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 14:23:23 INFO - Fetch https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 14:23:24 INFO - Http code: 200 14:23:24 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 14:23:24 INFO - x-amz-cf-id: WjypKKDae5g46ujG3wyzRHqy08TJNdsAmc96w-POCraao_wig52Hbg== 14:23:24 INFO - x-amz-version-id: AONBfz91ULO0RL91Sw.SdHzPy98c_tBI 14:23:24 INFO - Expected file size: 14409006 14:23:24 INFO - Obtained file size: 14409006 14:23:29 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')]} 14:23:29 INFO - trying https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 14:23:29 INFO - Downloading https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 14:23:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/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 14:23:31 INFO - Downloaded 54675414 bytes. 14:23:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 14:23:31 INFO - mkdir: C:\slave\test\properties 14:23:31 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 14:23:31 INFO - Writing to file C:\slave\test\properties\build_url 14:23:31 INFO - Contents: 14:23:31 INFO - build_url:https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 14:23:31 INFO - Running post-action listener: _resource_record_post_action 14:23:31 INFO - Running post-action listener: set_extra_try_arguments 14:23:31 INFO - [mozharness: 2016-10-13 21:23:31.954000Z] Finished download-and-extract step (success) 14:23:31 INFO - [mozharness: 2016-10-13 21:23:31.954000Z] Running populate-webroot step. 14:23:31 INFO - Running pre-action listener: _resource_record_pre_action 14:23:31 INFO - Running main action method: populate_webroot 14:23:31 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 14:23:31 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 14:23:31 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 14:23:31 INFO - u'--tppagecycles', 14:23:31 INFO - u'7'], 14:23:31 INFO - u'tests': [u'tsvgm']}}, 14:23:31 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 14:23:31 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 14:23:31 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 14:23:31 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 14:23:31 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:31 INFO - u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 14:23:31 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:31 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 14:23:31 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:31 INFO - u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'damp', u'tps']}, 14:23:31 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:31 INFO - u'tests': [u'damp', u'tps']}, 14:23:31 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'dromaeo_dom']}, 14:23:31 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 14:23:31 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'basic_compositor_video']}, 14:23:31 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 14:23:31 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 14:23:31 INFO - u'tests': [u'a11yr', 14:23:31 INFO - u'ts_paint', 14:23:31 INFO - u'tpaint', 14:23:31 INFO - u'sessionrestore', 14:23:31 INFO - u'sessionrestore_no_auto_restore', 14:23:31 INFO - u'tabpaint']}, 14:23:31 INFO - u'other-e10s': {u'tests': [u'a11yr', 14:23:31 INFO - u'ts_paint', 14:23:31 INFO - u'tpaint', 14:23:31 INFO - u'sessionrestore', 14:23:31 INFO - u'sessionrestore_no_auto_restore', 14:23:31 INFO - u'tabpaint']}, 14:23:31 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 14:23:31 INFO - u'ts_paint', 14:23:32 INFO - u'tpaint', 14:23:32 INFO - u'sessionrestore', 14:23:32 INFO - u'sessionrestore_no_auto_restore', 14:23:32 INFO - u'tabpaint']}, 14:23:32 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 14:23:32 INFO - u'ts_paint', 14:23:32 INFO - u'tpaint', 14:23:32 INFO - u'sessionrestore', 14:23:32 INFO - u'sessionrestore_no_auto_restore', 14:23:32 INFO - u'tabpaint']}, 14:23:32 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 14:23:32 INFO - u'tests': [u'a11yr', 14:23:32 INFO - u'ts_paint', 14:23:32 INFO - u'tpaint', 14:23:32 INFO - u'sessionrestore', 14:23:32 INFO - u'sessionrestore_no_auto_restore', 14:23:32 INFO - u'tabpaint']}, 14:23:32 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 14:23:32 INFO - u'tests': [u'a11yr', 14:23:32 INFO - u'ts_paint', 14:23:32 INFO - u'tpaint', 14:23:32 INFO - u'sessionrestore', 14:23:32 INFO - u'sessionrestore_no_auto_restore', 14:23:32 INFO - u'tabpaint']}, 14:23:32 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 14:23:32 INFO - u'tests': [u'tsvgx', 14:23:32 INFO - u'tsvgr_opacity', 14:23:32 INFO - u'tart', 14:23:32 INFO - u'tscrollx', 14:23:32 INFO - u'cart']}, 14:23:32 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 14:23:32 INFO - u'tsvgr_opacity', 14:23:32 INFO - u'tart', 14:23:32 INFO - u'tscrollx', 14:23:32 INFO - u'cart']}, 14:23:32 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:32 INFO - u'talos_options': [u'--disable-e10s'], 14:23:32 INFO - u'tests': [u'tp5o']}, 14:23:32 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:32 INFO - u'tests': [u'tp5o']}, 14:23:32 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:32 INFO - u'talos_options': [u'--disable-e10s', 14:23:32 INFO - u'--xperf_path', 14:23:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:32 INFO - u'tests': [u'tp5n']}, 14:23:32 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:23:32 INFO - u'talos_options': [u'--xperf_path', 14:23:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:32 INFO - u'tests': [u'tp5n']}}, 14:23:32 INFO - u'talos.zip': {u'path': u'', 14:23:32 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:32 INFO - Running post-action listener: _resource_record_post_action 14:23:32 INFO - [mozharness: 2016-10-13 21:23:32.015000Z] Finished populate-webroot step (success) 14:23:32 INFO - [mozharness: 2016-10-13 21:23:32.015000Z] Running create-virtualenv step. 14:23:32 INFO - Running pre-action listener: _resource_record_pre_action 14:23:32 INFO - Running main action method: create_virtualenv 14:23:32 INFO - Creating virtualenv C:\slave\test\build/venv 14:23:32 INFO - mkdir: C:\slave\test\build\venv\Scripts 14:23:32 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 14:23:32 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'! 14:23:32 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 14:23:32 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 14:23:33 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 14:23:43 INFO - Installing distribute......................................................................................................................................................................................done. 14:23:45 INFO - Return code: 0 14:23:45 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 14:23:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 14:23:45 INFO - Reading from file tmpfile_stdout 14:23:45 INFO - Output received: 14:23:45 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 14:23:45 INFO - Using _rmtree_windows ... 14:23:45 INFO - Using _rmtree_windows ... 14:23:45 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 14:23: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')]} 14:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23: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')]} 14:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:45 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23:45 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 14:23:45 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 14:23:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:45 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:45 INFO - 'DCLOCATION': 'SCL3', 14:23:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:45 INFO - 'HOMEDRIVE': 'C:', 14:23:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:45 INFO - 'KTS_VERSION': '1.19c', 14:23:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:45 INFO - 'MOZ_NO_REMOTE': '1', 14:23:45 INFO - 'NO_EM_RESTART': '1', 14:23:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:45 INFO - 'OS': 'Windows_NT', 14:23:45 INFO - 'OURDRIVE': 'C:', 14:23:45 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;', 14:23:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:45 INFO - 'PROCESSOR_LEVEL': '6', 14:23:45 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:45 INFO - 'PROMPT': '$P$G', 14:23:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:45 INFO - 'PWD': 'C:\\slave\\test', 14:23:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:45 INFO - 'SYSTEMDRIVE': 'C:', 14:23:45 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:45 INFO - 'TEST1': 'testie', 14:23:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:45 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:45 INFO - 'USERNAME': 'cltbld', 14:23:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:45 INFO - 'WINDIR': 'C:\\windows', 14:23:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 14:23:46 INFO - Downloading/unpacking pip>=1.5 14:23:50 INFO - Running setup.py egg_info for package pip 14:23:50 INFO - warning: no files found matching 'pip\cacert.pem' 14:23:50 INFO - warning: no files found matching '*.html' under directory 'docs' 14:23:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 14:23:50 INFO - no previously-included directories found matching 'docs\_build\_sources' 14:23:50 INFO - Installing collected packages: pip 14:23:50 INFO - Found existing installation: pip 0.8.2 14:23:50 INFO - Uninstalling pip: 14:23:50 INFO - Successfully uninstalled pip 14:23:50 INFO - Running setup.py install for pip 14:23:50 INFO - warning: no files found matching 'pip\cacert.pem' 14:23:50 INFO - warning: no files found matching '*.html' under directory 'docs' 14:23:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 14:23:50 INFO - no previously-included directories found matching 'docs\_build\_sources' 14:23:50 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 14:23:50 INFO - Successfully installed pip 14:23:50 INFO - Cleaning up... 14:23:50 INFO - Return code: 0 14:23:50 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 14:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 14:23:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:23:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:50 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:50 INFO - 'DCLOCATION': 'SCL3', 14:23:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:50 INFO - 'HOMEDRIVE': 'C:', 14:23:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:50 INFO - 'KTS_VERSION': '1.19c', 14:23:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:50 INFO - 'MOZ_NO_REMOTE': '1', 14:23:50 INFO - 'NO_EM_RESTART': '1', 14:23:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:50 INFO - 'OS': 'Windows_NT', 14:23:50 INFO - 'OURDRIVE': 'C:', 14:23:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 14:23:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:50 INFO - 'PROCESSOR_LEVEL': '6', 14:23:50 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:50 INFO - 'PROMPT': '$P$G', 14:23:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:50 INFO - 'PWD': 'C:\\slave\\test', 14:23:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:50 INFO - 'SYSTEMDRIVE': 'C:', 14:23:50 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:50 INFO - 'TEST1': 'testie', 14:23:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:50 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:50 INFO - 'USERNAME': 'cltbld', 14:23:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:50 INFO - 'WINDIR': 'C:\\windows', 14:23:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:52 INFO - Downloading/unpacking psutil>=3.1.1 14:23:52 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 14:23:52 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 14:23:53 INFO - Installing collected packages: psutil 14:23:53 INFO - Successfully installed psutil 14:23:53 INFO - Cleaning up... 14:23:54 INFO - Return code: 0 14:23:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 14:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:54 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23:54 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 14:23:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:23:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:54 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:54 INFO - 'DCLOCATION': 'SCL3', 14:23:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:54 INFO - 'HOMEDRIVE': 'C:', 14:23:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:54 INFO - 'KTS_VERSION': '1.19c', 14:23:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:54 INFO - 'MOZ_NO_REMOTE': '1', 14:23:54 INFO - 'NO_EM_RESTART': '1', 14:23:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:54 INFO - 'OS': 'Windows_NT', 14:23:54 INFO - 'OURDRIVE': 'C:', 14:23:54 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 14:23:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:54 INFO - 'PROCESSOR_LEVEL': '6', 14:23:54 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:54 INFO - 'PROMPT': '$P$G', 14:23:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:54 INFO - 'PWD': 'C:\\slave\\test', 14:23:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:54 INFO - 'SYSTEMDRIVE': 'C:', 14:23:54 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:54 INFO - 'TEST1': 'testie', 14:23:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:54 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:54 INFO - 'USERNAME': 'cltbld', 14:23:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:54 INFO - 'WINDIR': 'C:\\windows', 14:23:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 14:23:55 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) 14:23:55 INFO - Installing collected packages: mozsystemmonitor 14:23:55 INFO - Running setup.py install for mozsystemmonitor 14:23:55 INFO - Successfully installed mozsystemmonitor 14:23:55 INFO - Cleaning up... 14:23:55 INFO - Return code: 0 14:23:55 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 14:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:55 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23:55 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 14:23:55 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 14:23:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:55 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:55 INFO - 'DCLOCATION': 'SCL3', 14:23:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:55 INFO - 'HOMEDRIVE': 'C:', 14:23:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:55 INFO - 'KTS_VERSION': '1.19c', 14:23:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:55 INFO - 'MOZ_NO_REMOTE': '1', 14:23:55 INFO - 'NO_EM_RESTART': '1', 14:23:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:55 INFO - 'OS': 'Windows_NT', 14:23:55 INFO - 'OURDRIVE': 'C:', 14:23:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 14:23:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:55 INFO - 'PROCESSOR_LEVEL': '6', 14:23:55 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:55 INFO - 'PROMPT': '$P$G', 14:23:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:55 INFO - 'PWD': 'C:\\slave\\test', 14:23:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:55 INFO - 'SYSTEMDRIVE': 'C:', 14:23:55 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:55 INFO - 'TEST1': 'testie', 14:23:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:55 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:55 INFO - 'USERNAME': 'cltbld', 14:23:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:55 INFO - 'WINDIR': 'C:\\windows', 14:23:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:56 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:57 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 14:23:57 INFO - warning: no files found matching '*.txt' 14:23:57 INFO - no previously-included directories found matching 'build' 14:23:57 INFO - no previously-included directories found matching 'dist' 14:23:57 INFO - no previously-included directories found matching '.git*' 14:23:57 INFO - Installing collected packages: jsonschema, functools32 14:23:57 INFO - Running setup.py install for functools32 14:23:57 INFO - warning: no files found matching '*.txt' 14:23:57 INFO - no previously-included directories found matching 'build' 14:23:57 INFO - no previously-included directories found matching 'dist' 14:23:57 INFO - no previously-included directories found matching '.git*' 14:23:57 INFO - Successfully installed jsonschema functools32 14:23:57 INFO - Cleaning up... 14:23:58 INFO - Return code: 0 14:23:58 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 14:23: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')]} 14:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23: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')]} 14:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23: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', '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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23: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', 'functools32==3.2.3-2'] in C:\slave\test\build 14:23: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 functools32==3.2.3-2 14:23:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:58 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:58 INFO - 'DCLOCATION': 'SCL3', 14:23:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:58 INFO - 'HOMEDRIVE': 'C:', 14:23:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:58 INFO - 'KTS_VERSION': '1.19c', 14:23:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:58 INFO - 'MOZ_NO_REMOTE': '1', 14:23:58 INFO - 'NO_EM_RESTART': '1', 14:23:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:58 INFO - 'OS': 'Windows_NT', 14:23:58 INFO - 'OURDRIVE': 'C:', 14:23: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;', 14:23:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:58 INFO - 'PROCESSOR_LEVEL': '6', 14:23:58 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:58 INFO - 'PROMPT': '$P$G', 14:23:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:58 INFO - 'PWD': 'C:\\slave\\test', 14:23:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:58 INFO - 'SYSTEMDRIVE': 'C:', 14:23:58 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:58 INFO - 'TEST1': 'testie', 14:23:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:58 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:58 INFO - 'USERNAME': 'cltbld', 14:23:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:58 INFO - 'WINDIR': 'C:\\windows', 14:23:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 14:23:58 INFO - Cleaning up... 14:23:58 INFO - Return code: 0 14:23:58 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 14:23: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')]} 14:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23: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')]} 14:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23: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', '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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:23: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', 'blobuploader==1.2.4'] in C:\slave\test\build 14:23: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 blobuploader==1.2.4 14:23:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:23:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:23:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:23:58 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:23:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:23:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:23:58 INFO - 'DCLOCATION': 'SCL3', 14:23:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:23:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:23:58 INFO - 'HOMEDRIVE': 'C:', 14:23:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:23:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:23:58 INFO - 'KTS_VERSION': '1.19c', 14:23:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:23:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:23:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:23:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:58 INFO - 'MOZ_NO_REMOTE': '1', 14:23:58 INFO - 'NO_EM_RESTART': '1', 14:23:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:23:58 INFO - 'OS': 'Windows_NT', 14:23:58 INFO - 'OURDRIVE': 'C:', 14:23: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;', 14:23:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:23:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:23:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:23:58 INFO - 'PROCESSOR_LEVEL': '6', 14:23:58 INFO - 'PROCESSOR_REVISION': '1e05', 14:23:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:23:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:23:58 INFO - 'PROMPT': '$P$G', 14:23:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:23:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:23:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:23:58 INFO - 'PWD': 'C:\\slave\\test', 14:23:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:23:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:23:58 INFO - 'SYSTEMDRIVE': 'C:', 14:23:58 INFO - 'SYSTEMROOT': 'C:\\windows', 14:23:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:58 INFO - 'TEST1': 'testie', 14:23:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:23:58 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:23:58 INFO - 'USERNAME': 'cltbld', 14:23:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:23:58 INFO - 'WINDIR': 'C:\\windows', 14:23:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:23:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:59 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:59 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 14:23:59 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 14:24:00 INFO - Downloading blobuploader-1.2.4.tar.gz 14:24:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 14:24:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:24: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 14:24: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 14:24:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 14:24:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:24: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 14:24: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 14:24:00 INFO - Downloading docopt-0.6.1.tar.gz 14:24:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 14:24:01 INFO - Installing collected packages: blobuploader, requests, docopt 14:24:01 INFO - Running setup.py install for blobuploader 14:24:01 INFO - Running setup.py install for requests 14:24:02 INFO - Running setup.py install for docopt 14:24:02 INFO - Successfully installed blobuploader requests docopt 14:24:02 INFO - Cleaning up... 14:24:03 INFO - Return code: 0 14:24:03 INFO - Installing None into virtualenv C:\slave\test\build/venv 14:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:24:03 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:24:03 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 14:24:03 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 14:24:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:24:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:24:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:24:03 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:24:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:24:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:24:03 INFO - 'DCLOCATION': 'SCL3', 14:24:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:24:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:24:03 INFO - 'HOMEDRIVE': 'C:', 14:24:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:24:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:24:03 INFO - 'KTS_VERSION': '1.19c', 14:24:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:24:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:24:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:24:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:03 INFO - 'MOZ_NO_REMOTE': '1', 14:24:03 INFO - 'NO_EM_RESTART': '1', 14:24:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:24:03 INFO - 'OS': 'Windows_NT', 14:24:03 INFO - 'OURDRIVE': 'C:', 14:24:03 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;', 14:24:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:24:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:24:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:24:03 INFO - 'PROCESSOR_LEVEL': '6', 14:24:03 INFO - 'PROCESSOR_REVISION': '1e05', 14:24:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:24:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:24:03 INFO - 'PROMPT': '$P$G', 14:24:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:24:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:24:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:24:03 INFO - 'PWD': 'C:\\slave\\test', 14:24:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:24:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:24:03 INFO - 'SYSTEMDRIVE': 'C:', 14:24:03 INFO - 'SYSTEMROOT': 'C:\\windows', 14:24:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:03 INFO - 'TEST1': 'testie', 14:24:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:03 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:24:03 INFO - 'USERNAME': 'cltbld', 14:24:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:24:03 INFO - 'WINDIR': 'C:\\windows', 14:24:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:24:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:24:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:24:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s_jrtd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_w9h9q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d87qxp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wcbxvl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ufitxy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ne5bb9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-besdrr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 14:24:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 14:24:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rmrfns-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 14:24:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 14:24:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owel0m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 14:24:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 14:24:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i_hkq9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 14:24:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 14:24:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_niq2w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mkv323-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mdakik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-riyozz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jrgb9_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kkk8tb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 14:24:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 14:24:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkykbf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 14:24:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:24:07 INFO - Running setup.py install for manifestparser 14:24:07 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 14:24:07 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 14:24:07 INFO - Running setup.py install for mozcrash 14:24:08 INFO - Running setup.py install for mozdebug 14:24:08 INFO - Running setup.py install for mozdevice 14:24:08 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 14:24:08 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 14:24:08 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 14:24:08 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 14:24:08 INFO - Running setup.py install for mozfile 14:24:09 INFO - Running setup.py install for mozhttpd 14:24:09 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 14:24:09 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 14:24:09 INFO - Running setup.py install for mozinfo 14:24:09 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 14:24:09 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Running setup.py install for mozInstall 14:24:10 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 14:24:10 INFO - Running setup.py install for mozleak 14:24:10 INFO - Running setup.py install for mozlog 14:24:11 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 14:24:11 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 14:24:11 INFO - Running setup.py install for moznetwork 14:24:11 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 14:24:11 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 14:24:11 INFO - Running setup.py install for mozprocess 14:24:12 INFO - Running setup.py install for mozprofile 14:24:12 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 14:24:12 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 14:24:12 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 14:24:12 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 14:24:12 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 14:24:12 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 14:24:13 INFO - Running setup.py install for mozrunner 14:24:13 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 14:24:13 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 14:24:13 INFO - Running setup.py install for mozscreenshot 14:24:14 INFO - Running setup.py install for moztest 14:24:14 INFO - Running setup.py install for mozversion 14:24:14 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 14:24:14 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 14:24:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:24:15 INFO - Cleaning up... 14:24:15 INFO - Return code: 0 14:24:15 INFO - Installing None into virtualenv C:\slave\test\build/venv 14:24:15 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')]} 14:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:24:15 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')]} 14:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:24:15 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:24:15 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 14:24:15 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 14:24:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:24:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:24:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:24:15 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:24:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:24:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:24:15 INFO - 'DCLOCATION': 'SCL3', 14:24:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:24:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:24:15 INFO - 'HOMEDRIVE': 'C:', 14:24:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:24:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:24:15 INFO - 'KTS_VERSION': '1.19c', 14:24:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:24:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:24:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:24:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:15 INFO - 'MOZ_NO_REMOTE': '1', 14:24:15 INFO - 'NO_EM_RESTART': '1', 14:24:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:24:15 INFO - 'OS': 'Windows_NT', 14:24:15 INFO - 'OURDRIVE': 'C:', 14:24:15 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;', 14:24:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:24:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:24:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:24:15 INFO - 'PROCESSOR_LEVEL': '6', 14:24:15 INFO - 'PROCESSOR_REVISION': '1e05', 14:24:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:24:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:24:15 INFO - 'PROMPT': '$P$G', 14:24:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:24:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:24:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:24:15 INFO - 'PWD': 'C:\\slave\\test', 14:24:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:24:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:24:15 INFO - 'SYSTEMDRIVE': 'C:', 14:24:15 INFO - 'SYSTEMROOT': 'C:\\windows', 14:24:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:15 INFO - 'TEST1': 'testie', 14:24:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:15 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:24:15 INFO - 'USERNAME': 'cltbld', 14:24:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:24:15 INFO - 'WINDIR': 'C:\\windows', 14:24:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:24:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:24:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 14:24:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cqkh3l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 14:24:15 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)) 14:24:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 14:24:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ceihh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 14:24:15 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)) 14:24:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 14:24:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v6nxq4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 14:24:15 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)) 14:24:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 14:24:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vw0twk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fmkwkn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qjxlm6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mtykgk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3dmto4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bju1gi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y611ws-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 14:24:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p0yqcr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 14:24:16 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)) 14:24:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i9h3ft-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 14:24:17 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)) 14:24:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4at8wv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 14:24:17 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)) 14:24:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ruvovk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 14:24:17 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)) 14:24:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uhwqfr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 14:24:17 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)) 14:24:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h688f2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 14:24:17 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)) 14:24:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 14:24:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zjprye-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 14:24:17 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)) 14:24:17 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)) 14:24:17 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)) 14:24:17 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)) 14:24:17 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)) 14:24:17 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)) 14:24:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 14:24:17 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 14:24:17 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 14:24:18 INFO - Downloading blessings-1.6.tar.gz 14:24:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 14:24:19 INFO - Installing collected packages: blessings 14:24:19 INFO - Running setup.py install for blessings 14:24:19 INFO - Successfully installed blessings 14:24:19 INFO - Cleaning up... 14:24:22 INFO - Return code: 0 14:24:22 INFO - Done creating virtualenv C:\slave\test\build/venv. 14:24:22 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 14:24:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 14:24:22 INFO - Reading from file tmpfile_stdout 14:24:22 INFO - Using _rmtree_windows ... 14:24:22 INFO - Using _rmtree_windows ... 14:24:22 INFO - Current package versions: 14:24:22 INFO - blessings == 1.6 14:24:22 INFO - blobuploader == 1.2.4 14:24:22 INFO - docopt == 0.6.1 14:24:22 INFO - functools32 == 3.2.3-2 14:24:22 INFO - jsonschema == 2.5.1 14:24:22 INFO - manifestparser == 1.1 14:24:22 INFO - mozInstall == 1.12 14:24:22 INFO - mozcrash == 0.17 14:24:22 INFO - mozdebug == 0.1 14:24:22 INFO - mozdevice == 0.48 14:24:22 INFO - mozfile == 1.2 14:24:22 INFO - mozhttpd == 0.7 14:24:22 INFO - mozinfo == 0.9 14:24:22 INFO - mozleak == 0.1 14:24:22 INFO - mozlog == 3.4 14:24:22 INFO - moznetwork == 0.27 14:24:22 INFO - mozprocess == 0.23 14:24:22 INFO - mozprofile == 0.28 14:24:22 INFO - mozrunner == 6.12 14:24:22 INFO - mozscreenshot == 0.1 14:24:22 INFO - mozsystemmonitor == 0.3 14:24:22 INFO - moztest == 0.7 14:24:22 INFO - mozversion == 1.4 14:24:22 INFO - psutil == 3.1.1 14:24:22 INFO - requests == 1.2.3 14:24:22 INFO - Installing None into virtualenv C:\slave\test\build/venv 14:24: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')]} 14:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:24: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')]} 14:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:24:22 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:24:22 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 14:24:22 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 14:24:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:24:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:24:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:24:22 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:24:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:24:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:24:22 INFO - 'DCLOCATION': 'SCL3', 14:24:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:24:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:24:22 INFO - 'HOMEDRIVE': 'C:', 14:24:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:24:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:24:22 INFO - 'KTS_VERSION': '1.19c', 14:24:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:24:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:24:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:24:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:22 INFO - 'MOZ_NO_REMOTE': '1', 14:24:22 INFO - 'NO_EM_RESTART': '1', 14:24:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:24:22 INFO - 'OS': 'Windows_NT', 14:24:22 INFO - 'OURDRIVE': 'C:', 14:24: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;', 14:24:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:24:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:24:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:24:22 INFO - 'PROCESSOR_LEVEL': '6', 14:24:22 INFO - 'PROCESSOR_REVISION': '1e05', 14:24:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:24:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:24:22 INFO - 'PROMPT': '$P$G', 14:24:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:24:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:24:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:24:22 INFO - 'PWD': 'C:\\slave\\test', 14:24:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:24:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:24:22 INFO - 'SYSTEMDRIVE': 'C:', 14:24:22 INFO - 'SYSTEMROOT': 'C:\\windows', 14:24:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:22 INFO - 'TEST1': 'testie', 14:24:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:22 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:24:22 INFO - 'USERNAME': 'cltbld', 14:24:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:24:22 INFO - 'WINDIR': 'C:\\windows', 14:24:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:24:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 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)) 14:24:23 INFO - Cleaning up... 14:24:23 INFO - Return code: 0 14:24:23 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 14:24:23 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')]} 14:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:24:23 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')]} 14:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:24:23 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018F5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-062', 'USERDOMAIN': 'T-W732-IX-062', '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-062', '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 14:24:23 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 14:24:23 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 14:24:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:24:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:24:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:24:23 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:24:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:24:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:24:23 INFO - 'DCLOCATION': 'SCL3', 14:24:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:24:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:24:23 INFO - 'HOMEDRIVE': 'C:', 14:24:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:24:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:24:23 INFO - 'KTS_VERSION': '1.19c', 14:24:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:24:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:24:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:24:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:23 INFO - 'MOZ_NO_REMOTE': '1', 14:24:23 INFO - 'NO_EM_RESTART': '1', 14:24:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:24:23 INFO - 'OS': 'Windows_NT', 14:24:23 INFO - 'OURDRIVE': 'C:', 14:24:23 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;', 14:24:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:24:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:24:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:24:23 INFO - 'PROCESSOR_LEVEL': '6', 14:24:23 INFO - 'PROCESSOR_REVISION': '1e05', 14:24:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:24:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:24:23 INFO - 'PROMPT': '$P$G', 14:24:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:24:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:24:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:24:23 INFO - 'PWD': 'C:\\slave\\test', 14:24:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:24:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:24:23 INFO - 'SYSTEMDRIVE': 'C:', 14:24:23 INFO - 'SYSTEMROOT': 'C:\\windows', 14:24:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:23 INFO - 'TEST1': 'testie', 14:24:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:23 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:24:23 INFO - 'USERNAME': 'cltbld', 14:24:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:24:23 INFO - 'WINDIR': 'C:\\windows', 14:24:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:24:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 14:24:23 INFO - Cleaning up... 14:24:23 INFO - Return code: 0 14:24:23 INFO - Running post-action listener: _resource_record_post_action 14:24:23 INFO - Running post-action listener: _start_resource_monitoring 14:24:23 INFO - Starting resource monitoring. 14:24:23 INFO - [mozharness: 2016-10-13 21:24:23.542000Z] Finished create-virtualenv step (success) 14:24:23 INFO - [mozharness: 2016-10-13 21:24:23.542000Z] Running install step. 14:24:23 INFO - Running pre-action listener: _resource_record_pre_action 14:24:23 INFO - Running main action method: install 14:24:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 14:24:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 14:24:23 INFO - Reading from file tmpfile_stdout 14:24:23 INFO - Using _rmtree_windows ... 14:24:23 INFO - Using _rmtree_windows ... 14:24:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:24:23 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 14:24:23 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 14:24:24 INFO - Reading from file tmpfile_stdout 14:24:24 INFO - Output received: 14:24:24 INFO - Usage: mozinstall-script.py [options] installer 14:24:24 INFO - Options: 14:24:24 INFO - -h, --help show this help message and exit 14:24:24 INFO - -d DEST, --destination=DEST 14:24:24 INFO - Directory to install application into. [default: 14:24:24 INFO - "C:\slave\test"] 14:24:24 INFO - --app=APP Application being installed. [default: firefox] 14:24:24 INFO - Using _rmtree_windows ... 14:24:24 INFO - Using _rmtree_windows ... 14:24:24 INFO - mkdir: C:\slave\test\build\application 14:24:24 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'] 14:24:24 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 14:24:26 INFO - Reading from file tmpfile_stdout 14:24:26 INFO - Output received: 14:24:26 INFO - C:\slave\test\build\application\firefox\firefox.exe 14:24:26 INFO - Using _rmtree_windows ... 14:24:26 INFO - Using _rmtree_windows ... 14:24:26 INFO - Running post-action listener: _resource_record_post_action 14:24:26 INFO - [mozharness: 2016-10-13 21:24:26.458000Z] Finished install step (success) 14:24:26 INFO - [mozharness: 2016-10-13 21:24:26.458000Z] Running run-tests step. 14:24:26 INFO - Running pre-action listener: _resource_record_pre_action 14:24:26 INFO - Running main action method: run_tests 14:24:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 14:24:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 14:24:26 INFO - Python 2.7.3 14:24:26 INFO - Return code: 0 14:24:26 INFO - grabbing minidump binary from tooltool 14:24:26 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')]} 14:24:26 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 0x01902E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C4520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 14:24:26 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 14:24:26 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 14:24:26 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 14:24:26 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 14:24:28 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp0efhbg 14:24:28 INFO - INFO - File integrity verified, renaming tmp0efhbg to win32-minidump_stackwalk.exe 14:24:28 INFO - Return code: 0 14:24:28 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 14:24:28 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 14:24:28 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 14:24:28 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 14:24:28 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 14:24:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 14:24:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 14:24:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:24:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:24:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:24:28 INFO - 'COMPUTERNAME': 'T-W732-IX-062', 14:24:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:24:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:24:28 INFO - 'DCLOCATION': 'SCL3', 14:24:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:24:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:24:28 INFO - 'HOMEDRIVE': 'C:', 14:24:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:24:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:24:28 INFO - 'KTS_VERSION': '1.19c', 14:24:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:24:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-062', 14:24:28 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 14:24:28 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 14:24:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:24:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:28 INFO - 'MOZ_NO_REMOTE': '1', 14:24:28 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 14:24:28 INFO - 'NO_EM_RESTART': '1', 14:24:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:24:28 INFO - 'OS': 'Windows_NT', 14:24:28 INFO - 'OURDRIVE': 'C:', 14:24:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 14:24:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:24:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:24:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:24:28 INFO - 'PROCESSOR_LEVEL': '6', 14:24:28 INFO - 'PROCESSOR_REVISION': '1e05', 14:24:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:24:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:24:28 INFO - 'PROMPT': '$P$G', 14:24:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 14:24:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:24:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:24:28 INFO - 'PWD': 'C:\\slave\\test', 14:24:28 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 14:24:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:24:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:24:28 INFO - 'SYSTEMDRIVE': 'C:', 14:24:28 INFO - 'SYSTEMROOT': 'C:\\windows', 14:24:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:28 INFO - 'TEST1': 'testie', 14:24:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:24:28 INFO - 'USERDOMAIN': 'T-W732-IX-062', 14:24:28 INFO - 'USERNAME': 'cltbld', 14:24:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:24:28 INFO - 'WINDIR': 'C:\\windows', 14:24:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:24:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:24:28 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 14:24:29 INFO - mozversion application_buildid: 20161013123018 14:24:29 INFO - mozversion application_changeset: 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 14:24:29 INFO - mozversion application_display_name: Nightly 14:24:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:24:29 INFO - mozversion application_name: Firefox 14:24:29 INFO - mozversion application_remotingname: firefox 14:24:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:24:29 INFO - mozversion application_vendor: Mozilla 14:24:29 INFO - mozversion application_version: 52.0a1 14:24:29 INFO - mozversion platform_buildid: 20161013123018 14:24:29 INFO - mozversion platform_changeset: 62ccb498db3ec05bef1b6427d5b1b4b0e501cad5 14:24:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:24:29 INFO - mozversion platform_version: 52.0a1 14:24:29 INFO - using testdate: 1476393869 14:24:29 INFO - actual date: 1476393869 14:24:29 INFO - starting webserver on 'localhost:49777' 14:24:29 INFO - SUITE-START | Running 2 tests 14:24:29 INFO - TEST-START | dromaeo_css 14:24:29 INFO - operating with platform_type : w7_ 14:24:29 INFO - Initialising browser for dromaeo_css test... 14:24:29 INFO - TEST-INFO | started process 3636 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjhdpmn\profile http://localhost:49777/getInfo.html) 14:24:37 INFO - PROCESS | 3636 | __metrics Screen width/height:1600/1200 14:24:37 INFO - PROCESS | 3636 | colorDepth:24 14:24:37 INFO - PROCESS | 3636 | Browser inner width/height: 1010/674 14:24:37 INFO - PROCESS | 3636 | __metrics 14:24:38 INFO - PROCESS | 3636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:24:38 INFO - PROCESS | 3636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:39 INFO - TEST-INFO | 3636: exit 0 14:24:39 INFO - Browser initialized. 14:24:39 INFO - Running cycle 1/1 for dromaeo_css test... 14:24:39 INFO - TEST-INFO | started process 3908 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjhdpmn\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:40 INFO - PROCESS | 3908 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 14:24:51 INFO - PROCESS | 3908 | RSS: Main: 123686912 14:24:51 INFO - PROCESS | 3908 | 14:25:41 INFO - PROCESS | 3908 | 1476393941996 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] 14:27:51 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49777/tests/dromaeo/cssquery-ext.html) 14:27:54 INFO - PROCESS | 3908 | RSS: Main: 360259584 14:27:54 INFO - PROCESS | 3908 | 14:30:56 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-ext.html (next: http://localhost:49777/tests/dromaeo/cssquery-jquery.html) 14:30:57 INFO - PROCESS | 3908 | RSS: Main: 200708096 14:30:57 INFO - PROCESS | 3908 | 14:33:59 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49777/tests/dromaeo/cssquery-mootools.html) 14:34:00 INFO - PROCESS | 3908 | RSS: Main: 146489344 14:34:00 INFO - PROCESS | 3908 | 14:37:01 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49777/tests/dromaeo/cssquery-prototype.html) 14:37:02 INFO - PROCESS | 3908 | RSS: Main: 147193856 14:37:02 INFO - PROCESS | 3908 | 14:40:05 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49777/tests/dromaeo/cssquery-yui.html) 14:40:05 INFO - PROCESS | 3908 | RSS: Main: 134287360 14:40:05 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | Cycle 1(1): loaded http://localhost:49777/tests/dromaeo/cssquery-yui.html (next: http://localhost:49777/tests/dromaeo/cssquery-dojo.html) 14:43:08 INFO - PROCESS | 3908 | RSS: Main: 184188928 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | __start_tp_report 14:43:08 INFO - PROCESS | 3908 | _x_x_mozilla_page_load 14:43:08 INFO - PROCESS | 3908 | _x_x_mozilla_page_load_details 14:43:08 INFO - PROCESS | 3908 | |i|pagename|runs| 14:43:08 INFO - PROCESS | 3908 | |0;dojo.html;2676.1168814291286;2687.596860470928;2687.8387296762194;2721.605367221753;2730.795190360723;4676.97661511692;4686.578207961283;4711.199096153654;4780.0917825613105;4799.064182484417;4693.8357157499495;4706.388169537964;4762.642801789871;4801.303810947411;4818.879528011801;4118.05284784499;4174.540800511942;4215.599518045796;4225.028243503996;4229.259879521087;4152.148809494047;4187.706860519765;4189.601987811153;4191.748495090289;4220.599043090901;4146.460960075186;4208.242516347056;4225.936610950823;4230.534641189467;4260.041490664591;4165.27107756143;4203;4285.357196420562;4291.270484017725;4299.656027517791;4122.319817230154;4172.207280616703;4237.449131612871;4240.809163587615;4249.150169966019;4620.861374158781;4684.156851766681;4723.834665786715;4766.189747742857;4793.520647935214;4850.199717046683;4955.4548999610015;5037.219231019198;5041.697498150123;5055.34280543527;4938.432080310774;4965.90068198634;5054;5066.797328106872;5082.949170508322;4568.748718820463;4578.153041687332;4610.2854057621125;4623.930641040387;4660.627149828006;5041;5050.873728156826;5128.487151284916;5146.845594632167;5147.485251474823;4830.372051633265;4832.734199618984;4920;4931.0630980113665;4936.432310284292;4892.486288939681;4915.729634870045;4977.776000080005;4980.526849949249;4985.675931064542;4921.655484116078;4957.876053098701;4959.801607935642;4961.330220420269;4966.950330496721;4953.801847926043;4980.477049909779;4982.90034199314;4984.626153038536;4988.201887697951;3283.195617073832;3309.6690330966712;3347.9956013195942;3349.7822641528223;3365.915852103668;3044.3302473455806;3056.0831750474767;3076.784625076223;3106.2389714519636;3110.23799169205;4648.535146485325;4682.976585117052;4762.928556071662;4766.404199475066;4772.830656489183;4949.554540091409;4963.081829861487;4972.7513624318635;5018.673786203885;5081.720505372166;4498.415206023196;4520.254158063948;4679.040796636749;4691.5308469152815;4692.671512994058;3823.4456003879836;4047.655949244347;4156.771377574202;4158.438610787565;4169.478815148106;5072.340595722532;5094.796208151706;5104.9489505104475;5121.8463446096675;5166.457521960216;4812.542808433193;4812.759362031955;5051.6716413433005;5053.065182941167;5133.666311689728;5139.331886854685;5151.974240128776;5231.529162375405;5249.422563517934;5274.446183150714;4901;4933.679310844784;4960.156773348467;5023.196288593954;5027.245913113063;4928.581070608895;4995.225740010304;5004.049230646166;5043.293938848491;5091.592672586258;4827.396575428072;4877.12211801879;4899.804007839789;4901.240307752304;4904.485029071896;4799.784009719501;4866.537678920497;4889.119958407378;4893.192623217129;4900.093482705711;4667;4785.808567657255;4794.376731024944;4801.7118972861745;4803.351547541037;4759.833405830779;4803.495632958489;4890.559849613552;4903.60771138301;4906.215005599087;4855.004724031635;4855.587275081658;4869.95130048695;4872.853814385574;4874.780634871368 14:43:08 INFO - PROCESS | 3908 | |1;ext.html;520.1002266079682;534.3320848938827;541.7426722306902;549.5713343591997;556.935952365478;29649.851750741243;31004.53493197604;42384.576154238486;43168.56831431685;43551;42091;42380.78809605948;42415.36376954348;42427.15145697092;42477.57522424775;8174.141715119916;8317.126701696325;8441.113683063282;8446.366522510805;8454.239118479336;8142.715004974827;8371.162883711611;8424.747257582252;8451.704190353339;8486.151384861496;8191.71329003485;8468.745937621881;8489.575521223946;8523.105073967237;8525.616347264388;8307.37694672899;8470.915290847104;8502.277306428961;8508.74473765788;8510.829783404297;8059.153788852144;8197.918020819776;8394.076651568323;8461.365397595144;8474.025487069004;37188;38039.61960380416;38942.80528597367;39278;39280.60719392826;2869;3059.265776213715;3062.6631070582216;3074.200707815984;3130.4365214261425;30771.230719232197;30972.225694357596;31105.22236944072;31610.367792644247;32073.839630801696;3557.5730912290433;3568.2863427314383;3577.338192434382;3592;3639.181184233553;312537;315224;315608;315653;316297;50781.492185078416;52962;53185.2022219667;53456.73271633617;53655.46344536504;30064.098077057723;30765.69234307673;55865;55982.44017559854;56169;13152.671183220496;14109.435622575187;14310.141391516543;14346.928265358607;14374.928125359307;9816.607335706634;10660.893391066145;10720.46397680113;10730.248882578144;10732.78534429307;17480.125993700265;17632.647347052985;17640.38258660941;17758;17802.643947120985;6745.9662701686175;6798.218204906499;6813;6827.795166145023;6850.588964662136;788.2039140468169;843.2579330189394;905.6603773584906;936.4287784451479;1029.9485025748832;26984.545178881446;28442.573361399594;28684;29198.85400573026;29541.704582953895;763.5380594740102;816.608028146493;842.4818736477023;936.424099179;949.6391371279008;13120.671983200496;15046.946833522281;15105.697886042217;15225.086494810346;15291;485577;491177.4263702331;491742;493866;496539;51116;51264.48735512597;51386.74306628518;51489.74255128775;51524.48475515197;1006.2352612014921;1019.1184625299211;1071.9624813131502;1082.1072615092423;1108.8503052088026;27753.861230693718;29152.85754990041;29897.402051958405;30027.699723003363;30070.84964575163;47067;47978.76010619925;48515.27227091667;52160;52276.73861630668;455746;460152;463034;464262;464674;23345.41636459102;23495.295141145794;23630.054797807894;24943.625845612693;37304.813475932446;43774.12451750883;44028.77985610051;44404.777976109915;44671.10657786891;44839.775801120784;36718;36936.26127477489;37948.81025594854;38450.807745961094;38840.805795971966;37125;37252;37730.2453950914;37792.811035944644;37904.241915160994;35502;37080.62919370772;37176.44235336418;38908;39017;31493.52759708652;33456;33971.320573588884;34089.147771305914;34414.82792586021;24752;25882.223533294033;25978.220653380427;26090;26232.475350493263 14:43:08 INFO - PROCESS | 3908 | |2;jquery.html;290.9229054300612;317.5903085020323;318.7816345803125;320.61846402780697;323.0921111676192;13217.20696758194;17933.193006314716;18168.909155454203;18294.817051829494;18314;17212;17363.47909562712;17637.47087587371;17687.91156044221;17828.55428614285;4720.0559888022535;5214.66104703195;5421.647592906448;5423.810166644168;5425.484578965012;5117.48825117489;5531.557475401958;5543.528800052;5570.387257401682;5573.191887176357;5484.972575137119;5490.450954904498;5494.780208791643;5497.175423686439;5527.53015993639;5241.816536421207;5243.108671525831;5339.973300133474;5381.246625472398;5403.432639572848;4613.677042607019;4623.42207224097;4625.1443482955765;4627.028324051953;4637.234856248715;21823.67264491034;21913;22243.11027558896;22333.989940905398;22420.66369004466;2013.395981205647;2051.589682063593;2089.4880754215314;2259.6271615183473;2720.8503532305717;14069.085509441853;14182.149071055666;14310.619570701427;14584;14839.406423743038;1753.4126067767313;1755.0698129991124;1774.0597483333854;1804.584945462538;1847.5288801355703;77044.22955770427;78377.21622783756;79722;79952.20047799563;80288.59855700743;8333.708320208763;8494.193051660082;8731.781705457288;8756.56217189138;8810.427322224035;9905.20758339331;10326.638567650096;10410;10581;10886.891131088587;6861.073755043005;6958.443324534025;7045.753398631024;7177.7487787927175;7179.425645948311;7285.672144753499;7382.335589796944;7499.8500029999095;7513.511621744678;7518.774436766906;5608.439156084359;5646.378898321182;5692.1461780733225;5695.572832037614;5708.172315014383;1448.3844366144347;1489.8063251777417;1489.970200595982;1494.6786440915039;1506.5103841251637;811.350919264586;840.1724301562956;951.9857202141974;964.5659453246068;970.7330484116783;18229.361972330906;18820.24719011224;18926.90536547336;19159.61680766377;19300.1314940828;782.5695867272977;845.7420486751599;852.7356519478982;857.1728282207699;869.6521391443473;10246.48767561619;10323.63867264642;10400.375977441377;10674.519646615921;10814.94592527048;397498;400218;403830;411138;412477;80358.59820700859;83151.58424207961;84917;85016.57491712502;87706.56146719225;822.5804839531881;839.5969934431384;840.2983508770245;854.7008547008497;857.8198578298502;16427.753583696493;16428.75527133742;16865.241064151898;16947.66104677924;16962.66074678524;32946.5058024125;33143.66856331406;36560.634393655724;38722.22555548817;40431.3935290965;50443.49556504388;52466.475335246156;53585;54771.726141369036;58503.4149658515;7045.365917067488;7045.506814522934;7078.327558881898;7175;7273.490855640054;12170.208936419105;12394.62816115518;12541.31022793755;12555.74888502243;12616.810747838606;9559.426434413957;10000.749981250527;10380.221483388927;10451.58193672275;10540.420276884844;10513.789724205626;10557.313774604627;10573.735656608338;10596.788064238826;10690.946545267223;10688.839667405151;10937.781244374908;11040.392778397265;11079;11210.43947802623;7208.927910720826;7227;7319.634018298957;7365.300296471826;7470.925290747023;7283.890741638986;9990.650327238513;10284.331518451278;10818.350898946088;10937.343759374146 14:43:08 INFO - PROCESS | 3908 | |3;mootools.html;1158.0735411670664;1167.3462860797954;1256.1583738894942;1532.7700844873275;1555.0825013242186;7245.963770181148;7713.537187768738;7723.459357844953;7725.072991241052;7740.264674855894;6683.53215274931;6868.896966545506;7395.741149059784;7592.696292148321;7624.96187519063;1866.9719954200664;1892.640398324314;1938.903054847259;1939.0789375046882;1939.6217737541115;1900.6768849295584;1908.6182763447296;1923.5383507958059;1935.235581945138;1936.8644194906362;1676.8910020848666;1726.30947620952;1889.1215584753088;1907.5135840360688;1936.5836345185783;1904.6190761847618;1917.3289348727974;1926.9325573604926;1928.517870532367;1931.990340048305;1824.9817501825078;1831.2491878329954;1838.6414649143424;1842.5393651587103;1847.7043673012388;7358.558486490828;7386.150592681856;7524;7535.547867127935;7552.924470755277;4396.340548917656;4406.184855801687;4513.232750432434;4520.11857687751;4532.29749388842;2857.1428571428696;2915.271182204449;2929.4434057529;2950.69017753135;2961.141269032;949.7483166960761;959.9904000959971;985.0445068283753;991.2862738828032;993.8558908958233;9533.713988580354;9606.183474404685;9825.508724563742;9836.114749672492;9905.60377584903;3583.1937813992163;3672.540932383452;3739.6447337502896;3744.6817020553012;3746.6440688134585;7590.278923502258;7746.845063098706;7755.7673269802;7762.961185194151;7770.456068075294;4308.978455107705;4342.044750154961;4401.867943961686;4403.207422663952;4404.427424434803;4124.278251306009;4205.053862880888;4214.388913607571;4218.114196018871;4220.177065472203;2937.0014195173317;2991.5961345218116;3051.862666180027;3052.160655819623;3065.095796739967;887.6183241206344;899.347972719786;907.5643691028212;913.141646851957;914.1681070226063;830.2278880641062;866.7443104284251;870.0690261420219;870.7170169694874;874.8162885794054;4913.385826771653;4984.800607975641;5012.223105418666;5080.491950804964;5221.086309895753;348.4407525920911;350.07230838278764;351.2553386819907;356.92504574039225;363.2462640021965;3366.5118557808983;3455.550778398792;3464.9826750866587;3515.472679098156;3616.3129005488872;8921.553922303989;9139.086091390807;9174.816503670023;9195.816083678423;9199.034101419255;9078.591463384164;9098.454092754457;9119.08809119096;9133.680321188727;9148.451092934445;7031;7077.363037326665;7160.498765086498;7168.964155179191;7184.604846733375;4833.879153021203;4898.804047838189;5013.922006768562;5018.1218286800395;5055.545000949932;8506.872396914176;8571.100034496289;8609.096044915445;8615.052344242004;8617.69838055665;8375.07874133857;8541.359398045048;8618.267447267053;8626.309895208242;8628.654853806029;5869.354371019099;5912.70436478183;5919.4376534230905;5924.6149000314845;5936.3470018297085;7146.392556632536;7293.416526677959;7317.524360916524;7320;7335.413166946738;5294.920576191437;6522.641254731036;6564.113844630913;6582.078509008838;6650.700718467584;6287.497000239879;6415.486761059197;6461.935380646133;6495.123158373559;6516.6415847128865;6070.817875463743;6149.72326245329;6150.292716337678;6151.169592105188;6153;4427.9114417710825;4486.955130448654;4505.324201369821;4546.227141385906;4601.28680054592;4272.978635106804;4272.995845976131;4319.805608747677;4367.45406824147;4458.71018383804 14:43:08 INFO - PROCESS | 3908 | |4;prototype.html;231.28532833544344;244.34393653041585;248.16121509298569;249.5296366349432;250.6153055060482;4942.566655669852;4956.835143741222;5052.317937078494;5085.6948583084995;5142.460041695619;4916.418880087956;5001.649884508086;5012.924806127911;5018.9749051254785;5024.52267034632;3666.834992425334;3711.109333759892;3714.8142592870386;3718.3121122592406;3720.479132921393;3517.3844577199015;3688.520492336006;3710;3710.573284072326;3717.219383929378;3547.7693949893173;3585.3008663310666;3620.855165793365;3651.4522821576843;3654.9400673804566;3642.7085833133287;3662.560492740875;3668.321360548307;3677.1358730698266;3680.8773324136127;3108.4715598348334;3159.526071089332;3160.336329370835;3164.699353561408;3170.952435713466;4936.80252789888;4947.084789313953;4948.134076536628;4959.20652695567;4959.70241785494;4108.198901214264;4284.5715428457215;4289.056407590357;4300.118475712503;4332.653387729006;5218.556422704075;5235.607329450307;5240.135377662681;5240.34495688039;5240.554552863011;3513.54323937889;3516.9120771980906;3534.3638145133864;3549.378858699717;3556.697680697144;5471.316085489314;5491.835244942658;5495.368032676253;5497.45025497451;5505.394406615269;3441.9311613768086;3620.8008559528953;3622.42041273395;3622.565292164957;3630.7640003399692;3573.4282514797505;3686.9123608535547;3687.8524859005875;3688.354537955847;3688.63113688629;4299;4303.203907277164;4307.978460107742;4310.741355518679;4314;5235.424103348629;5276.551493123127;5296.788128474818;5297.682139071668;5306.257124002643;1206.8715750773056;1235.1415766042694;1242.6210005948274;1254.1033161289722;1255.836741223635;838.2581415447373;855.8502262104107;856.4433118473042;857.8927634045745;863.3222920007764;4965.379327584052;4971.005798840247;4978.178600530944;4994.150994330972;4999;5390.137577987582;5396.64921780083;5397.217403476552;5412.864678383072;5414.160805075219;4841.8547443576745;4917.311576379238;4962.503749625009;4980.427250866197;4986.753311672082;2839.929001774972;2849.558318460642;2855.186271912495;2855.386091990232;2863.3128049268025;5519.39286678465;5529.640573362719;5532.806351777668;5541.695206763586;5552.167174923713;5385.353757549119;5481.095619222783;5490.1764735289225;5490.862728431741;5497.945020549823;5383.703896285742;5532.834014979557;5561.88762247544;5625.971870140705;5653.406392328746;5425.457454254543;5445.482679145475;5455.809046683347;5463.235147079492;5466.699331536804;5392.952981565403;5395.541378982673;5398.973005135106;5419.83740487786;5420.268263784339;5413.269208656781;5550.8057217997175;5641.110228073478;5672.688002159755;5680;3482.1990942082957;3499.8775042873376;3505.9824700876334;3507.4563442666426;3508.8070156141657;5411.621186516906;5435.755391007335;5486.503640843486;5488.368837583729;5490.203920431435;5320.494553017457;5322.654027488201;5327;5331.626786125089;5331.946680533145;5048.318477005704;5276.572776522689;5301.496357845998;5309.442508536548;5317.627766056339;5186.688798672092;5188;5188.610854185876;5195.350581177353;5205.713685747169;5085.186370180753;5144.511271429208;5238.057149713089;5241.135212689864;5242.554382877498;5165.276861239355;5206.828463595661;5211.062008838446;5216.321878155815;5225.425203227718 14:43:08 INFO - PROCESS | 3908 | |5;yui.html;611.1169360274403;641.2625480697197;643.9710213040413;648.2804087462916;697.2400083908539;14067.155970641768;14201.218932958684;14373.209473478955;14671.92664036681;14687.412503499847;12787.232766034045;12848.871511284884;12958.35208239589;13001;13030.08789384741;529.3726933583696;637.9776707815216;656.1404560026357;664.9069130321759;670.1689904518387;534.609734893528;615.5444970721657;659.5646873063779;663.1114306828848;669.5915491550151;666.2970566052812;689.6517258784307;689.6896396621515;694.6387878303279;697.7104501631817;682.0553533356288;683.2996178916626;688.1157712339669;688.703857341343;696.7944456385378;400.0917916329915;403.64075972384603;407.3279089502345;409.78281510799326;500.6645547483195;14210.715785684331;14597.6350591235;14600.488982885548;14622.488212912496;14624;12895.419706113154;12973.675658108528;13006.674833129153;13093.345332733325;13393.129446585941;13058.869411305956;13069.281189534571;13389.933050334783;13467.259300738455;13548.796768048487;4352.412424322708;4380.364847097185;4407.4050003249795;4414.381986521889;4426.490953540319;80334.19665803383;83158;83547;86316;86708.56645716731;10695.572177112828;10705.357678539156;10774.622888199032;10798.460076996276;10823.350598964087;20319.695204571944;20371.592568148553;20408.693869591967;20409.387718368474;20409.387718368474;7135.215126336099;7170.892436613351;7199.748008819666;7337.0828646419195;7392.667329970164;3750.3999360102252;4033.415154802537;4100.938485922714;4115.650169735606;4159.230542349615;3233.2725136844024;3451.137215696076;3451.154467155563;3452.223249768782;3468.271662950758;592.9771144775228;601.9398060193946;602.7558838670345;606.8937935861206;613.8894998900241;547.151914532473;653.6111013946694;660.5574265242203;677.0825531404876;713.464901324007;9004.189622933967;9129.726108216764;9197.862032069525;9758.75603109928;9773.41359518431;522.2922939417111;566.869619987397;598.4733434577544;625.8779537990048;649.9503302167061;4478.283474644041;4764.475907650156;4774.976125119352;4782.904341913212;4792.209285467929;91075;96261;97369;110899;115530.3598388276;69958.65020674864;81635;85698.14301857026;87320.5633971826;87468;26615.33461663474;32250.677493224768;73413.26586734064;77056.61471692717;80865.99999999882;9117.769101171521;9280.421012762828;9301.674441394789;9690.37012594179;9691.12779849817;46152;47968;48131;48717.269240960704;49244.75377623089;69405;69872;70517;70593;70679;12384.690382740504;12518.624441266413;12635.620931371706;12719.745605088032;13112;14525.128492290496;14641.853581464475;14654.988884983244;14709.55871323862;14855.777163342775;13127.606171814861;13407.664808379479;13691;13778;13928.442862285396;13966.650833729238;13985.510507132201;14133.363998620292;14207.005509614228;14516.854831451972;12825.358732063172;13048.412821423248;13317.667058323232;13408.128471649312;13416.530421435204;11740.823887641522;11793.587224447103;11816.054715622558;11900.642980710592;12112.333821639895;11803.23278986863;11931.4034298283;11970.820437693616;12091.939540302243;12183 14:43:08 INFO - PROCESS | 3908 | __end_tp_report 14:43:08 INFO - PROCESS | 3908 | __start_cc_report 14:43:08 INFO - PROCESS | 3908 | _x_x_mozilla_cycle_collect,2935 14:43:08 INFO - PROCESS | 3908 | __end_cc_report 14:43:08 INFO - PROCESS | 3908 | __startTimestamp1476394988941__endTimestamp 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | ------- Summary: start ------- 14:43:08 INFO - PROCESS | 3908 | Number of tests: 6 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#0] dojo.html Cycles:165 Average:4599.55 Median:4812.54 stddev:588.44 (12.2%) stddev-sans-first:570.57 14:43:08 INFO - PROCESS | 3908 | Values: 2676.1 2687.6 2687.8 2721.6 2730.8 4677.0 4686.6 4711.2 4780.1 4799.1 4693.8 4706.4 4762.6 4801.3 4818.9 4118.1 4174.5 4215.6 4225.0 4229.3 4152.1 4187.7 4189.6 4191.7 4220.6 4146.5 4208.2 4225.9 4230.5 4260.0 4165.3 4203.0 4285.4 4291.3 4299.7 4122.3 4172.2 4237.4 4240.8 4249.2 4620.9 4684.2 4723.8 4766.2 4793.5 4850.2 4955.5 5037.2 5041.7 5055.3 4938.4 4965.9 5054.0 5066.8 5082.9 4568.7 4578.2 4610.3 4623.9 4660.6 5041.0 5050.9 5128.5 5146.8 5147.5 4830.4 4832.7 4920.0 4931.1 4936.4 4892.5 4915.7 4977.8 4980.5 4985.7 4921.7 4957.9 4959.8 4961.3 4967.0 4953.8 4980.5 4982.9 4984.6 4988.2 3283.2 3309.7 3348.0 3349.8 3365.9 3044.3 3056.1 3076.8 3106.2 3110.2 4648.5 4683.0 4762.9 4766.4 4772.8 4949.6 4963.1 4972.8 5018.7 5081.7 4498.4 4520.3 4679.0 4691.5 4692.7 3823.4 4047.7 4156.8 4158.4 4169.5 5072.3 5094.8 5104.9 5121.8 5166.5 4812.5 4812.8 5051.7 5053.1 5133.7 5139.3 5152.0 5231.5 5249.4 5274.4 4901.0 4933.7 4960.2 5023.2 5027.2 4928.6 4995.2 5004.0 5043.3 5091.6 4827.4 4877.1 4899.8 4901.2 4904.5 4799.8 4866.5 4889.1 4893.2 4900.1 4667.0 4785.8 4794.4 4801.7 4803.4 4759.8 4803.5 4890.6 4903.6 4906.2 4855.0 4855.6 4870.0 4872.9 4874.8 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#1] ext.html Cycles:180 Average:56524.02 Median:38979.90 stddev:114181.15 (292.9%) stddev-sans-first:114424.03 14:43:08 INFO - PROCESS | 3908 | Values: 520.1 534.3 541.7 549.6 556.9 29649.9 31004.5 42384.6 43168.6 43551.0 42091.0 42380.8 42415.4 42427.2 42477.6 8174.1 8317.1 8441.1 8446.4 8454.2 8142.7 8371.2 8424.7 8451.7 8486.2 8191.7 8468.7 8489.6 8523.1 8525.6 8307.4 8470.9 8502.3 8508.7 8510.8 8059.2 8197.9 8394.1 8461.4 8474.0 37188.0 38039.6 38942.8 39278.0 39280.6 2869.0 3059.3 3062.7 3074.2 3130.4 30771.2 30972.2 31105.2 31610.4 32073.8 3557.6 3568.3 3577.3 3592.0 3639.2 312537.0 315224.0 315608.0 315653.0 316297.0 50781.5 52962.0 53185.2 53456.7 53655.5 30064.1 30765.7 55865.0 55982.4 56169.0 13152.7 14109.4 14310.1 14346.9 14374.9 9816.6 10660.9 10720.5 10730.2 10732.8 17480.1 17632.6 17640.4 17758.0 17802.6 6746.0 6798.2 6813.0 6827.8 6850.6 788.2 843.3 905.7 936.4 1029.9 26984.5 28442.6 28684.0 29198.9 29541.7 763.5 816.6 842.5 936.4 949.6 13120.7 15046.9 15105.7 15225.1 15291.0 485577.0 491177.4 491742.0 493866.0 496539.0 51116.0 51264.5 51386.7 51489.7 51524.5 1006.2 1019.1 1072.0 1082.1 1108.9 27753.9 29152.9 29897.4 30027.7 30070.8 47067.0 47978.8 48515.3 52160.0 52276.7 455746.0 460152.0 463034.0 464262.0 464674.0 23345.4 23495.3 23630.1 24943.6 37304.8 43774.1 44028.8 44404.8 44671.1 44839.8 36718.0 36936.3 37948.8 38450.8 38840.8 37125.0 37252.0 37730.2 37792.8 37904.2 35502.0 37080.6 37176.4 38908.0 39017.0 31493.5 33456.0 33971.3 34089.1 34414.8 24752.0 25882.2 25978.2 26090.0 26232.5 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#2] jquery.html Cycles:180 Average:25508.97 Median:208545.34 stddev:67161.65 (32.2%) stddev-sans-first:67323.37 14:43:08 INFO - PROCESS | 3908 | Values: 290.9 317.6 318.8 320.6 323.1 13217.2 17933.2 18168.9 18294.8 18314.0 17212.0 17363.5 17637.5 17687.9 17828.6 4720.1 5214.7 5421.6 5423.8 5425.5 5117.5 5531.6 5543.5 5570.4 5573.2 5485.0 5490.5 5494.8 5497.2 5527.5 5241.8 5243.1 5340.0 5381.2 5403.4 4613.7 4623.4 4625.1 4627.0 4637.2 21823.7 21913.0 22243.1 22334.0 22420.7 2013.4 2051.6 2089.5 2259.6 2720.9 14069.1 14182.1 14310.6 14584.0 14839.4 1753.4 1755.1 1774.1 1804.6 1847.5 77044.2 78377.2 79722.0 79952.2 80288.6 8333.7 8494.2 8731.8 8756.6 8810.4 9905.2 10326.6 10410.0 10581.0 10886.9 6861.1 6958.4 7045.8 7177.7 7179.4 7285.7 7382.3 7499.9 7513.5 7518.8 5608.4 5646.4 5692.1 5695.6 5708.2 1448.4 1489.8 1490.0 1494.7 1506.5 811.4 840.2 952.0 964.6 970.7 18229.4 18820.2 18926.9 19159.6 19300.1 782.6 845.7 852.7 857.2 869.7 10246.5 10323.6 10400.4 10674.5 10814.9 397498.0 400218.0 403830.0 411138.0 412477.0 80358.6 83151.6 84917.0 85016.6 87706.6 822.6 839.6 840.3 854.7 857.8 16427.8 16428.8 16865.2 16947.7 16962.7 32946.5 33143.7 36560.6 38722.2 40431.4 50443.5 52466.5 53585.0 54771.7 58503.4 7045.4 7045.5 7078.3 7175.0 7273.5 12170.2 12394.6 12541.3 12555.7 12616.8 9559.4 10000.7 10380.2 10451.6 10540.4 10513.8 10557.3 10573.7 10596.8 10690.9 10688.8 10937.8 11040.4 11079.0 11210.4 7208.9 7227.0 7319.6 7365.3 7470.9 7283.9 9990.7 10284.3 10818.4 10937.3 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#3] mootools.html Cycles:180 Average:4813.18 Median:5582.14 stddev:2747.66 (49.2%) stddev-sans-first:2741.63 14:43:08 INFO - PROCESS | 3908 | Values: 1158.1 1167.3 1256.2 1532.8 1555.1 7246.0 7713.5 7723.5 7725.1 7740.3 6683.5 6868.9 7395.7 7592.7 7625.0 1867.0 1892.6 1938.9 1939.1 1939.6 1900.7 1908.6 1923.5 1935.2 1936.9 1676.9 1726.3 1889.1 1907.5 1936.6 1904.6 1917.3 1926.9 1928.5 1932.0 1825.0 1831.2 1838.6 1842.5 1847.7 7358.6 7386.2 7524.0 7535.5 7552.9 4396.3 4406.2 4513.2 4520.1 4532.3 2857.1 2915.3 2929.4 2950.7 2961.1 949.7 960.0 985.0 991.3 993.9 9533.7 9606.2 9825.5 9836.1 9905.6 3583.2 3672.5 3739.6 3744.7 3746.6 7590.3 7746.8 7755.8 7763.0 7770.5 4309.0 4342.0 4401.9 4403.2 4404.4 4124.3 4205.1 4214.4 4218.1 4220.2 2937.0 2991.6 3051.9 3052.2 3065.1 887.6 899.3 907.6 913.1 914.2 830.2 866.7 870.1 870.7 874.8 4913.4 4984.8 5012.2 5080.5 5221.1 348.4 350.1 351.3 356.9 363.2 3366.5 3455.6 3465.0 3515.5 3616.3 8921.6 9139.1 9174.8 9195.8 9199.0 9078.6 9098.5 9119.1 9133.7 9148.5 7031.0 7077.4 7160.5 7169.0 7184.6 4833.9 4898.8 5013.9 5018.1 5055.5 8506.9 8571.1 8609.1 8615.1 8617.7 8375.1 8541.4 8618.3 8626.3 8628.7 5869.4 5912.7 5919.4 5924.6 5936.3 7146.4 7293.4 7317.5 7320.0 7335.4 5294.9 6522.6 6564.1 6582.1 6650.7 6287.5 6415.5 6461.9 6495.1 6516.6 6070.8 6149.7 6150.3 6151.2 6153.0 4427.9 4487.0 4505.3 4546.2 4601.3 4273.0 4273.0 4319.8 4367.5 4458.7 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#4] prototype.html Cycles:180 Average:4355.54 Median:5021.75 stddev:1357.88 (27.0%) stddev-sans-first:1325.94 14:43:08 INFO - PROCESS | 3908 | Values: 231.3 244.3 248.2 249.5 250.6 4942.6 4956.8 5052.3 5085.7 5142.5 4916.4 5001.6 5012.9 5019.0 5024.5 3666.8 3711.1 3714.8 3718.3 3720.5 3517.4 3688.5 3710.0 3710.6 3717.2 3547.8 3585.3 3620.9 3651.5 3654.9 3642.7 3662.6 3668.3 3677.1 3680.9 3108.5 3159.5 3160.3 3164.7 3171.0 4936.8 4947.1 4948.1 4959.2 4959.7 4108.2 4284.6 4289.1 4300.1 4332.7 5218.6 5235.6 5240.1 5240.3 5240.6 3513.5 3516.9 3534.4 3549.4 3556.7 5471.3 5491.8 5495.4 5497.5 5505.4 3441.9 3620.8 3622.4 3622.6 3630.8 3573.4 3686.9 3687.9 3688.4 3688.6 4299.0 4303.2 4308.0 4310.7 4314.0 5235.4 5276.6 5296.8 5297.7 5306.3 1206.9 1235.1 1242.6 1254.1 1255.8 838.3 855.9 856.4 857.9 863.3 4965.4 4971.0 4978.2 4994.2 4999.0 5390.1 5396.6 5397.2 5412.9 5414.2 4841.9 4917.3 4962.5 4980.4 4986.8 2839.9 2849.6 2855.2 2855.4 2863.3 5519.4 5529.6 5532.8 5541.7 5552.2 5385.4 5481.1 5490.2 5490.9 5497.9 5383.7 5532.8 5561.9 5626.0 5653.4 5425.5 5445.5 5455.8 5463.2 5466.7 5393.0 5395.5 5399.0 5419.8 5420.3 5413.3 5550.8 5641.1 5672.7 5680.0 3482.2 3499.9 3506.0 3507.5 3508.8 5411.6 5435.8 5486.5 5488.4 5490.2 5320.5 5322.7 5327.0 5331.6 5331.9 5048.3 5276.6 5301.5 5309.4 5317.6 5186.7 5188.0 5188.6 5195.4 5205.7 5085.2 5144.5 5238.1 5241.1 5242.6 5165.3 5206.8 5211.1 5216.3 5225.4 14:43:08 INFO - PROCESS | 3908 | 14:43:08 INFO - PROCESS | 3908 | [#5] yui.html Cycles:180 Average:19018.11 Median:4410.89 stddev:26723.34 (605.8%) stddev-sans-first:26762.57 14:43:08 INFO - PROCESS | 3908 | Values: 611.1 641.3 644.0 648.3 697.2 14067.2 14201.2 14373.2 14671.9 14687.4 12787.2 12848.9 12958.4 13001.0 13030.1 529.4 638.0 656.1 664.9 670.2 534.6 615.5 659.6 663.1 669.6 666.3 689.7 689.7 694.6 697.7 682.1 683.3 688.1 688.7 696.8 400.1 403.6 407.3 409.8 500.7 14210.7 14597.6 14600.5 14622.5 14624.0 12895.4 12973.7 13006.7 13093.3 13393.1 13058.9 13069.3 13389.9 13467.3 13548.8 4352.4 4380.4 4407.4 4414.4 4426.5 80334.2 83158.0 83547.0 86316.0 86708.6 10695.6 10705.4 10774.6 10798.5 10823.4 20319.7 20371.6 20408.7 20409.4 20409.4 7135.2 7170.9 7199.7 7337.1 7392.7 3750.4 4033.4 4100.9 4115.7 4159.2 3233.3 3451.1 3451.2 3452.2 3468.3 593.0 601.9 602.8 606.9 613.9 547.2 653.6 660.6 677.1 713.5 9004.2 9129.7 9197.9 9758.8 9773.4 522.3 566.9 598.5 625.9 650.0 4478.3 4764.5 4775.0 4782.9 4792.2 91075.0 96261.0 97369.0 110899.0 115530.4 69958.7 81635.0 85698.1 87320.6 87468.0 26615.3 32250.7 73413.3 77056.6 80866.0 9117.8 9280.4 9301.7 9690.4 9691.1 46152.0 47968.0 48131.0 48717.3 49244.8 69405.0 69872.0 70517.0 70593.0 70679.0 12384.7 12518.6 12635.6 12719.7 13112.0 14525.1 14641.9 14655.0 14709.6 14855.8 13127.6 13407.7 13691.0 13778.0 13928.4 13966.7 13985.5 14133.4 14207.0 14516.9 12825.4 13048.4 13317.7 13408.1 13416.5 11740.8 11793.6 11816.1 11900.6 12112.3 11803.2 11931.4 11970.8 12091.9 12183.0 14:43:08 INFO - PROCESS | 3908 | -------- Summary: end -------- 14:43:08 INFO - PROCESS | 3908 | 14:43:09 INFO - TEST-INFO | 3908: exit 0 14:43:10 INFO - TEST-OK | dromaeo_css | took 1120555ms 14:43:10 INFO - TEST-START | kraken 14:43:10 INFO - operating with platform_type : w7_ 14:43:10 INFO - Initialising browser for kraken test... 14:43:10 INFO - TEST-INFO | started process 3592 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpdcnki5\profile http://localhost:49777/getInfo.html) 14:43:14 INFO - PROCESS | 3592 | __metrics Screen width/height:1600/1200 14:43:14 INFO - PROCESS | 3592 | colorDepth:24 14:43:14 INFO - PROCESS | 3592 | Browser inner width/height: 1010/674 14:43:14 INFO - PROCESS | 3592 | __metrics 14:43:15 INFO - PROCESS | 3592 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:43:15 INFO - PROCESS | 3592 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:43:15 INFO - PROCESS | 3592 | console.error: 14:43:15 INFO - PROCESS | 3592 | Message: Error: Connection closed before committing the transaction. 14:43:15 INFO - PROCESS | 3592 | Stack: 14:43:15 INFO - PROCESS | 3592 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 14:43:15 INFO - PROCESS | 3592 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 14:43:15 INFO - PROCESS | 3592 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 14:43:15 INFO - PROCESS | 3592 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 14:43:15 INFO - PROCESS | 3592 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 14:43:15 INFO - PROCESS | 3592 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 14:43:15 INFO - PROCESS | 3592 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 14:43:15 INFO - PROCESS | 3592 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 14:43:15 INFO - PROCESS | 3592 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 14:43:15 INFO - PROCESS | 3592 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 14:43:15 INFO - PROCESS | 3592 | Barrier/this.client.addBlocker/promise13.1% 14:44:13 INFO - TinderboxPrint: I/O read bytes / time
91,993,600 / 1,207,300 14:44:13 INFO - TinderboxPrint: I/O write bytes / time
484,341,760 / 3,802,200 14:44:13 INFO - TinderboxPrint: CPU idle
8,265.7 (86.9%) 14:44:13 INFO - TinderboxPrint: CPU user
1,207.5 (12.7%) 14:44:13 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 36093952; Read time: 0; Write time: 369170 14:44:13 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 91993600; Write bytes: 411225088; Read time: 1207300; Write time: 3270820 14:44:13 INFO - Running post-run listener: _upload_blobber_files 14:44:13 INFO - Blob upload gear active. 14:44:13 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 14:44:13 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 14:44:13 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'] 14:44:13 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 14:44:14 INFO - (blobuploader) - INFO - Open directory for files ... 14:44:14 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 14:44:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:44:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:15 INFO - (blobuploader) - INFO - Done attempting. 14:44:15 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 14:44:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:44:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:16 INFO - (blobuploader) - INFO - Done attempting. 14:44:16 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 14:44:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:24 INFO - (blobuploader) - INFO - Done attempting. 14:44:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:44:24 INFO - Return code: 0 14:44:24 INFO - rmtree: C:\slave\test\build\uploaded_files.json 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 14:44:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4b33201d4a8e22d74851715bfebf923c5b349be3a600ef082b52cb22fe791d2d472aa756e88c54a2dc735382c71c8162642f58c1b8f8ad15c61335d9359ace2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c4f8c274e242289d09d8b922551a171182ba62df7eae2a384963dbe66200ce2220ba5bf5341fcf8c8ac18c80b6f0ea655b4361cd28e6a58be857fc136b4a3359", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4ccb14879542020470a219f3ea925710c0c9c0c23bcbd9dfdc81ab956a227db969d8d5197100ad25dee38c9c6ccbd5f66d3c735c8613cb620072841df203d75"} 14:44:24 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 14:44:24 INFO - Writing to file C:\slave\test\properties\blobber_files 14:44:24 INFO - Contents: 14:44:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4b33201d4a8e22d74851715bfebf923c5b349be3a600ef082b52cb22fe791d2d472aa756e88c54a2dc735382c71c8162642f58c1b8f8ad15c61335d9359ace2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c4f8c274e242289d09d8b922551a171182ba62df7eae2a384963dbe66200ce2220ba5bf5341fcf8c8ac18c80b6f0ea655b4361cd28e6a58be857fc136b4a3359", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4ccb14879542020470a219f3ea925710c0c9c0c23bcbd9dfdc81ab956a227db969d8d5197100ad25dee38c9c6ccbd5f66d3c735c8613cb620072841df203d75"} 14:44:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:44:24 INFO - Copying logs to upload dir... 14:44:24 INFO - mkdir: C:\slave\test\build\upload\logs 14:44:24 INFO - Copying logs to upload dir... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... 14:44:24 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1453.291000 ========= master_lag: 0.57 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 13 secs) (at 2016-10-13 14:44:24.712512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:24.713535) ========= '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-062 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-062 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-062 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4b33201d4a8e22d74851715bfebf923c5b349be3a600ef082b52cb22fe791d2d472aa756e88c54a2dc735382c71c8162642f58c1b8f8ad15c61335d9359ace2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c4f8c274e242289d09d8b922551a171182ba62df7eae2a384963dbe66200ce2220ba5bf5341fcf8c8ac18c80b6f0ea655b4361cd28e6a58be857fc136b4a3359", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4ccb14879542020470a219f3ea925710c0c9c0c23bcbd9dfdc81ab956a227db969d8d5197100ad25dee38c9c6ccbd5f66d3c735c8613cb620072841df203d75"} build_url:https://queue.taskcluster.net/v1/task/V0dlwotLS7eZ_5ZZeYn-DA/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/V0dlwotLS7eZ_5ZZeYn-DA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4b33201d4a8e22d74851715bfebf923c5b349be3a600ef082b52cb22fe791d2d472aa756e88c54a2dc735382c71c8162642f58c1b8f8ad15c61335d9359ace2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c4f8c274e242289d09d8b922551a171182ba62df7eae2a384963dbe66200ce2220ba5bf5341fcf8c8ac18c80b6f0ea655b4361cd28e6a58be857fc136b4a3359", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e4ccb14879542020470a219f3ea925710c0c9c0c23bcbd9dfdc81ab956a227db969d8d5197100ad25dee38c9c6ccbd5f66d3c735c8613cb620072841df203d75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:24.830134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:24.830528) ========= '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-062 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-062 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-062 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:24.942278) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:24.942658) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-13 14:44:25.402485) ========= ========= Total master_lag: 0.71 =========