builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-147 starttime: 1476277432.29 results: success (0) buildid: 20161012033004 builduid: b7fa378d531c4d11bdea6e8db349d62d revision: aa8629a25ab5aeb2678a0187e50336659d368b44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 06:03:52.292115) ========= master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 06:03:52.292562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 06:03:52.292873) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 06:03:52.446432) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-12 06:03:52.446783) ========= '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-147 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-147 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-147 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 --06:03: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% 2.46 GB/s 06:03:54 (2.46 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.190000 ========= master_lag: 2.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-12 06:03:55.886364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-12 06:03:55.886660) ========= '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-147 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-147 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-147 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=17.001000 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-10-12 06:04:12.932159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 06:04:12.932537) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev aa8629a25ab5aeb2678a0187e50336659d368b44 --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 aa8629a25ab5aeb2678a0187e50336659d368b44 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-147 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-147 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-147 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-12 06:04:13,844 truncating revision to first 12 chars 2016-10-12 06:04:13,844 Setting DEBUG logging. 2016-10-12 06:04:13,844 attempt 1/10 2016-10-12 06:04:13,844 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aa8629a25ab5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 06:04:14,355 unpacking tar archive at: fx-team-aa8629a25ab5/testing/mozharness/ 2016-10-12 06:04:14,500 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.297000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 06:04:14.262564) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 06:04:14.262885) ========= script_repo_revision: aa8629a25ab5aeb2678a0187e50336659d368b44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 06:04:14.263310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 06:04:14.263615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 06:04:14.273571) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2016-10-12 06:04:14.273868) ========= '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-147 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-147 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-147 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 06:04:15 INFO - MultiFileLogger online at 20161012 06:04:15 in C:\slave\test 06:04:15 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 06:04:15 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:04:15 INFO - {'append_to_log': False, 06:04:15 INFO - 'base_work_dir': 'C:\\slave\\test', 06:04:15 INFO - 'blob_upload_branch': 'Fx-Team', 06:04:15 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:04:15 INFO - 'branch': 'Fx-Team', 06:04:15 INFO - 'buildbot_json_path': 'buildprops.json', 06:04:15 INFO - 'config_files': ('talos/windows_config.py',), 06:04:15 INFO - 'default_actions': ('clobber', 06:04:15 INFO - 'read-buildbot-config', 06:04:15 INFO - 'download-and-extract', 06:04:15 INFO - 'populate-webroot', 06:04:15 INFO - 'create-virtualenv', 06:04:15 INFO - 'install', 06:04:15 INFO - 'run-tests'), 06:04:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:04:15 INFO - 'download_minidump_stackwalk': True, 06:04:15 INFO - 'download_symbols': 'ondemand', 06:04:15 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:04:15 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:04:15 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:04:15 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:04:15 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:04:15 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:04:15 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:04:15 INFO - 'C:/mozilla-build/tooltool.py'), 06:04:15 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:04:15 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:04:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:04:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:04:15 INFO - 'installer_path': 'installer.exe', 06:04:15 INFO - 'log_level': 'info', 06:04:15 INFO - 'log_name': 'talos', 06:04:15 INFO - 'log_to_console': True, 06:04:15 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:04:15 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:04:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:04:15 INFO - 'opt_config_files': (), 06:04:15 INFO - 'pip_index': False, 06:04:15 INFO - 'sps_profile': False, 06:04:15 INFO - 'sps_profile_interval': 0, 06:04:15 INFO - 'suite': 'dromaeojs', 06:04:15 INFO - 'system_bits': '32', 06:04:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:04:15 INFO - 'title': 'T-W732-IX-147', 06:04:15 INFO - 'use_talos_json': True, 06:04:15 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:04:15 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:04:15 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:04:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:04:15 INFO - 'work_dir': 'build'} 06:04:15 INFO - [mozharness: 2016-10-12 13:04:15.360000Z] Running clobber step. 06:04:15 INFO - Running pre-action listener: _resource_record_pre_action 06:04:15 INFO - Running main action method: clobber 06:04:15 INFO - rmtree: C:\slave\test\build 06:04:15 INFO - Using _rmtree_windows ... 06:04:15 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 06:06:26 INFO - Running post-action listener: _resource_record_post_action 06:06:26 INFO - [mozharness: 2016-10-12 13:06:26.830000Z] Finished clobber step (success) 06:06:26 INFO - [mozharness: 2016-10-12 13:06:26.830000Z] Running read-buildbot-config step. 06:06:26 INFO - Running pre-action listener: _resource_record_pre_action 06:06:26 INFO - Running main action method: read_buildbot_config 06:06:26 INFO - Using buildbot properties: 06:06:26 INFO - { 06:06:26 INFO - "project": "", 06:06:26 INFO - "product": "firefox", 06:06:26 INFO - "script_repo_revision": "production", 06:06:26 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 06:06:26 INFO - "repository": "", 06:06:26 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 06:06:26 INFO - "buildid": "20161012033004", 06:06:26 INFO - "pgo_build": "True", 06:06:26 INFO - "basedir": "C:\\slave\\test", 06:06:26 INFO - "buildnumber": 234, 06:06:26 INFO - "slavename": "t-w732-ix-147", 06:06:26 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/", 06:06:26 INFO - "platform": "win7_ix", 06:06:26 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 06:06:26 INFO - "branch": "fx-team", 06:06:26 INFO - "repo_path": "integration/fx-team", 06:06:26 INFO - "revision": "aa8629a25ab5aeb2678a0187e50336659d368b44", 06:06:26 INFO - "stage_platform": "win32-pgo", 06:06:26 INFO - "builduid": "b7fa378d531c4d11bdea6e8db349d62d", 06:06:26 INFO - "slavebuilddir": "test" 06:06:26 INFO - } 06:06:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 06:06:26 INFO - Running post-action listener: _resource_record_post_action 06:06:26 INFO - [mozharness: 2016-10-12 13:06:26.834000Z] Finished read-buildbot-config step (success) 06:06:26 INFO - [mozharness: 2016-10-12 13:06:26.834000Z] Running download-and-extract step. 06:06:26 INFO - Running pre-action listener: _resource_record_pre_action 06:06:26 INFO - Running main action method: download_and_extract 06:06:26 INFO - mkdir: C:\slave\test\build\tests 06:06: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')]} 06:06:26 INFO - trying https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 06:06:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/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 06:06:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/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 06:06:27 INFO - Downloaded 1218 bytes. 06:06:27 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:06:27 INFO - Using the following test package requirements: 06:06:27 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:06:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 06:06:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:06:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'jsshell-win32.zip'], 06:06:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 06:06:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:06:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 06:06:27 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 06:06:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 06:06:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:06:27 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 06:06:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:06:27 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 06:06:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:06:27 INFO - Fetch https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:06:28 INFO - Http code: 200 06:06:28 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 06:06:28 INFO - x-amz-cf-id: QEocpF_BPgLBIWvdqma4C2_OpUeG_JIsN6w7BW2phERfndMHQ91Bsg== 06:06:28 INFO - x-amz-version-id: zclrEnPuvV8TXIsUxjyDbEoqvdDoqNyX 06:06:29 INFO - Expected file size: 17267961 06:06:29 INFO - Obtained file size: 17267961 06:07:02 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 06:07:02 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 06:07:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:07:02 INFO - Fetch https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:07:03 INFO - Http code: 200 06:07:03 INFO - via: 1.1 296faebadd40feee8b2eb0e10d5786d2.cloudfront.net (CloudFront) 06:07:03 INFO - x-amz-cf-id: uO_GiTaNNgez8gI7NwDQfIvZOkCNtZfTcVzIfYo6m6UspG7aHz6FOA== 06:07:03 INFO - x-amz-version-id: zclrEnPuvV8TXIsUxjyDbEoqvdDoqNyX 06:07:03 INFO - Expected file size: 17267961 06:07:03 INFO - Obtained file size: 17267961 06:07:15 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 06:07:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 06:07:15 INFO - Fetch https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 06:07:16 INFO - Http code: 200 06:07:16 INFO - via: 1.1 96255a5b0caf50bcafcd101c1ff13691.cloudfront.net (CloudFront) 06:07:16 INFO - x-amz-cf-id: xBeb3_kIV7DGQz7QVrofMR6KwX3vZ37u1mymhf0oQVDylYxEJ4NNSA== 06:07:16 INFO - x-amz-version-id: HUKaqpe4KOdP153WWpE9U3kyJ7_OqTlX 06:07:16 INFO - Expected file size: 14409006 06:07:16 INFO - Obtained file size: 14409006 06:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:27 INFO - trying https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:07:27 INFO - Downloading https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 06:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/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 06:07:30 INFO - Downloaded 54622838 bytes. 06:07:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:07:30 INFO - mkdir: C:\slave\test\properties 06:07:30 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 06:07:30 INFO - Writing to file C:\slave\test\properties\build_url 06:07:30 INFO - Contents: 06:07:30 INFO - build_url:https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:07:30 INFO - Running post-action listener: _resource_record_post_action 06:07:30 INFO - Running post-action listener: set_extra_try_arguments 06:07:30 INFO - [mozharness: 2016-10-12 13:07:30.804000Z] Finished download-and-extract step (success) 06:07:30 INFO - [mozharness: 2016-10-12 13:07:30.804000Z] Running populate-webroot step. 06:07:30 INFO - Running pre-action listener: _resource_record_pre_action 06:07:30 INFO - Running main action method: populate_webroot 06:07:30 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:07:30 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:07:30 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:07:30 INFO - u'--tppagecycles', 06:07:30 INFO - u'7'], 06:07:30 INFO - u'tests': [u'tsvgm']}}, 06:07:30 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:07:30 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 06:07:30 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 06:07:30 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 06:07:30 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:07:30 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:07:30 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'damp', u'tps']}, 06:07:30 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'tests': [u'damp', u'tps']}, 06:07:30 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'dromaeo_dom']}, 06:07:30 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:07:30 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'basic_compositor_video']}, 06:07:30 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 06:07:30 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'other-e10s': {u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'a11yr', 06:07:30 INFO - u'ts_paint', 06:07:30 INFO - u'tpaint', 06:07:30 INFO - u'sessionrestore', 06:07:30 INFO - u'sessionrestore_no_auto_restore', 06:07:30 INFO - u'tabpaint']}, 06:07:30 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'tsvgx', 06:07:30 INFO - u'tsvgr_opacity', 06:07:30 INFO - u'tart', 06:07:30 INFO - u'tscrollx', 06:07:30 INFO - u'cart']}, 06:07:30 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 06:07:30 INFO - u'tsvgr_opacity', 06:07:30 INFO - u'tart', 06:07:30 INFO - u'tscrollx', 06:07:30 INFO - u'cart']}, 06:07:30 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'talos_options': [u'--disable-e10s'], 06:07:30 INFO - u'tests': [u'tp5o']}, 06:07:30 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'tests': [u'tp5o']}, 06:07:30 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'talos_options': [u'--disable-e10s', 06:07:30 INFO - u'--xperf_path', 06:07:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:30 INFO - u'tests': [u'tp5n']}, 06:07:30 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:30 INFO - u'talos_options': [u'--xperf_path', 06:07:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:30 INFO - u'tests': [u'tp5n']}}, 06:07:30 INFO - u'talos.zip': {u'path': u'', 06:07:30 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:30 INFO - Running post-action listener: _resource_record_post_action 06:07:30 INFO - [mozharness: 2016-10-12 13:07:30.876000Z] Finished populate-webroot step (success) 06:07:30 INFO - [mozharness: 2016-10-12 13:07:30.876000Z] Running create-virtualenv step. 06:07:30 INFO - Running pre-action listener: _resource_record_pre_action 06:07:30 INFO - Running main action method: create_virtualenv 06:07:30 INFO - Creating virtualenv C:\slave\test\build/venv 06:07:30 INFO - mkdir: C:\slave\test\build\venv\Scripts 06:07:30 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 06:07:30 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'! 06:07:30 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 06:07:30 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 06:07:33 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 06:07:40 INFO - Installing distribute......................................................................................................................................................................................done. 06:07:42 INFO - Return code: 0 06:07:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 06:07:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 06:07:42 INFO - Reading from file tmpfile_stdout 06:07:42 INFO - Output received: 06:07:42 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 06:07:42 INFO - Using _rmtree_windows ... 06:07:42 INFO - Using _rmtree_windows ... 06:07:42 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 06:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:42 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07:42 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 06:07:42 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 06:07:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:42 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:42 INFO - 'DCLOCATION': 'SCL3', 06:07:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:42 INFO - 'HOMEDRIVE': 'C:', 06:07:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:42 INFO - 'KTS_VERSION': '1.19c', 06:07:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:42 INFO - 'MOZ_NO_REMOTE': '1', 06:07:42 INFO - 'NO_EM_RESTART': '1', 06:07:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:42 INFO - 'OS': 'Windows_NT', 06:07:42 INFO - 'OURDRIVE': 'C:', 06:07:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:07:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:42 INFO - 'PROCESSOR_LEVEL': '6', 06:07:42 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:42 INFO - 'PROMPT': '$P$G', 06:07:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:42 INFO - 'PWD': 'C:\\slave\\test', 06:07:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:42 INFO - 'SYSTEMDRIVE': 'C:', 06:07:42 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:42 INFO - 'TEST1': 'testie', 06:07:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:42 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:42 INFO - 'USERNAME': 'cltbld', 06:07:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:42 INFO - 'WINDIR': 'C:\\windows', 06:07:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:07:43 INFO - Downloading/unpacking pip>=1.5 06:07:47 INFO - Running setup.py egg_info for package pip 06:07:47 INFO - warning: no files found matching 'pip\cacert.pem' 06:07:47 INFO - warning: no files found matching '*.html' under directory 'docs' 06:07:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:07:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:07:47 INFO - Installing collected packages: pip 06:07:47 INFO - Found existing installation: pip 0.8.2 06:07:47 INFO - Uninstalling pip: 06:07:47 INFO - Successfully uninstalled pip 06:07:47 INFO - Running setup.py install for pip 06:07:47 INFO - warning: no files found matching 'pip\cacert.pem' 06:07:47 INFO - warning: no files found matching '*.html' under directory 'docs' 06:07:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:07:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:07:47 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 06:07:47 INFO - Successfully installed pip 06:07:47 INFO - Cleaning up... 06:07:47 INFO - Return code: 0 06:07:47 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 06:07:47 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')]} 06:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:47 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')]} 06:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:47 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 06:07:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:07:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:47 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:47 INFO - 'DCLOCATION': 'SCL3', 06:07:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:47 INFO - 'HOMEDRIVE': 'C:', 06:07:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:47 INFO - 'KTS_VERSION': '1.19c', 06:07:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:47 INFO - 'MOZ_NO_REMOTE': '1', 06:07:47 INFO - 'NO_EM_RESTART': '1', 06:07:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:47 INFO - 'OS': 'Windows_NT', 06:07:47 INFO - 'OURDRIVE': 'C:', 06:07:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:07:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:47 INFO - 'PROCESSOR_LEVEL': '6', 06:07:47 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:47 INFO - 'PROMPT': '$P$G', 06:07:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:47 INFO - 'PWD': 'C:\\slave\\test', 06:07:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:47 INFO - 'SYSTEMDRIVE': 'C:', 06:07:47 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:47 INFO - 'TEST1': 'testie', 06:07:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:47 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:47 INFO - 'USERNAME': 'cltbld', 06:07:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:47 INFO - 'WINDIR': 'C:\\windows', 06:07:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:49 INFO - Downloading/unpacking psutil>=3.1.1 06:07:49 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 06:07:49 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 06:07:50 INFO - Installing collected packages: psutil 06:07:50 INFO - Successfully installed psutil 06:07:50 INFO - Cleaning up... 06:07:51 INFO - Return code: 0 06:07:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 06:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 06:07:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:07:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:51 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:51 INFO - 'DCLOCATION': 'SCL3', 06:07:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:51 INFO - 'HOMEDRIVE': 'C:', 06:07:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:51 INFO - 'KTS_VERSION': '1.19c', 06:07:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:51 INFO - 'MOZ_NO_REMOTE': '1', 06:07:51 INFO - 'NO_EM_RESTART': '1', 06:07:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:51 INFO - 'OS': 'Windows_NT', 06:07:51 INFO - 'OURDRIVE': 'C:', 06:07:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:07:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:51 INFO - 'PROCESSOR_LEVEL': '6', 06:07:51 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:51 INFO - 'PROMPT': '$P$G', 06:07:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:51 INFO - 'PWD': 'C:\\slave\\test', 06:07:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:51 INFO - 'SYSTEMDRIVE': 'C:', 06:07:51 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:51 INFO - 'TEST1': 'testie', 06:07:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:51 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:51 INFO - 'USERNAME': 'cltbld', 06:07:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:51 INFO - 'WINDIR': 'C:\\windows', 06:07:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07:51 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 06:07:51 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 06:07:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:52 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:07:52 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) 06:07:52 INFO - Installing collected packages: mozsystemmonitor 06:07:52 INFO - Running setup.py install for mozsystemmonitor 06:07:52 INFO - Successfully installed mozsystemmonitor 06:07:52 INFO - Cleaning up... 06:07:52 INFO - Return code: 0 06:07:52 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 06:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:52 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07:52 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 06:07:52 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 06:07:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:52 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:52 INFO - 'DCLOCATION': 'SCL3', 06:07:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:52 INFO - 'HOMEDRIVE': 'C:', 06:07:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:52 INFO - 'KTS_VERSION': '1.19c', 06:07:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:52 INFO - 'MOZ_NO_REMOTE': '1', 06:07:52 INFO - 'NO_EM_RESTART': '1', 06:07:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:52 INFO - 'OS': 'Windows_NT', 06:07:52 INFO - 'OURDRIVE': 'C:', 06:07:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:07:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:52 INFO - 'PROCESSOR_LEVEL': '6', 06:07:52 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:52 INFO - 'PROMPT': '$P$G', 06:07:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:52 INFO - 'PWD': 'C:\\slave\\test', 06:07:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:52 INFO - 'SYSTEMDRIVE': 'C:', 06:07:52 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:52 INFO - 'TEST1': 'testie', 06:07:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:52 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:52 INFO - 'USERNAME': 'cltbld', 06:07:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:52 INFO - 'WINDIR': 'C:\\windows', 06:07:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:53 INFO - Downloading/unpacking jsonschema==2.5.1 06:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07: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 06:07: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 06:07:54 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 06:07:54 INFO - warning: no files found matching '*.txt' 06:07:54 INFO - no previously-included directories found matching 'build' 06:07:54 INFO - no previously-included directories found matching 'dist' 06:07:54 INFO - no previously-included directories found matching '.git*' 06:07:54 INFO - Installing collected packages: jsonschema, functools32 06:07:54 INFO - Running setup.py install for functools32 06:07:54 INFO - warning: no files found matching '*.txt' 06:07:54 INFO - no previously-included directories found matching 'build' 06:07:54 INFO - no previously-included directories found matching 'dist' 06:07:54 INFO - no previously-included directories found matching '.git*' 06:07:54 INFO - Successfully installed jsonschema functools32 06:07:54 INFO - Cleaning up... 06:07:55 INFO - Return code: 0 06:07:55 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 06:07: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')]} 06:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07: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')]} 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07: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', '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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07: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', 'functools32==3.2.3-2'] in C:\slave\test\build 06:07: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 functools32==3.2.3-2 06:07:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:55 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:55 INFO - 'DCLOCATION': 'SCL3', 06:07:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:55 INFO - 'HOMEDRIVE': 'C:', 06:07:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:55 INFO - 'KTS_VERSION': '1.19c', 06:07:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:55 INFO - 'MOZ_NO_REMOTE': '1', 06:07:55 INFO - 'NO_EM_RESTART': '1', 06:07:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:55 INFO - 'OS': 'Windows_NT', 06:07:55 INFO - 'OURDRIVE': 'C:', 06:07: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;', 06:07:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:55 INFO - 'PROCESSOR_LEVEL': '6', 06:07:55 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:55 INFO - 'PROMPT': '$P$G', 06:07:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:55 INFO - 'PWD': 'C:\\slave\\test', 06:07:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:55 INFO - 'SYSTEMDRIVE': 'C:', 06:07:55 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:55 INFO - 'TEST1': 'testie', 06:07:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:55 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:55 INFO - 'USERNAME': 'cltbld', 06:07:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:55 INFO - 'WINDIR': 'C:\\windows', 06:07:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 06:07:55 INFO - Cleaning up... 06:07:55 INFO - Return code: 0 06:07:55 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 06:07: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')]} 06:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07: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')]} 06:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07: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', '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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:07: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', 'blobuploader==1.2.4'] in C:\slave\test\build 06:07: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 blobuploader==1.2.4 06:07:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:07:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:07:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:07:55 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:07:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:07:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:07:55 INFO - 'DCLOCATION': 'SCL3', 06:07:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:07:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:07:55 INFO - 'HOMEDRIVE': 'C:', 06:07:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:07:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:07:55 INFO - 'KTS_VERSION': '1.19c', 06:07:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:07:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:07:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:07:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:55 INFO - 'MOZ_NO_REMOTE': '1', 06:07:55 INFO - 'NO_EM_RESTART': '1', 06:07:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:07:55 INFO - 'OS': 'Windows_NT', 06:07:55 INFO - 'OURDRIVE': 'C:', 06:07: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;', 06:07:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:07:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:07:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:07:55 INFO - 'PROCESSOR_LEVEL': '6', 06:07:55 INFO - 'PROCESSOR_REVISION': '1e05', 06:07:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:07:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:07:55 INFO - 'PROMPT': '$P$G', 06:07:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:07:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:07:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:07:55 INFO - 'PWD': 'C:\\slave\\test', 06:07:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:07:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:07:55 INFO - 'SYSTEMDRIVE': 'C:', 06:07:55 INFO - 'SYSTEMROOT': 'C:\\windows', 06:07:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:55 INFO - 'TEST1': 'testie', 06:07:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:07:55 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:07:55 INFO - 'USERNAME': 'cltbld', 06:07:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:07:55 INFO - 'WINDIR': 'C:\\windows', 06:07:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:07:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:56 INFO - Downloading/unpacking blobuploader==1.2.4 06:07: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 06:07: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 06:07:57 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:07:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07: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 06:07: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 06:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 06:07:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07: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 06:07: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 06:07:57 INFO - Downloading docopt-0.6.1.tar.gz 06:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 06:07:58 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:58 INFO - Running setup.py install for blobuploader 06:07:58 INFO - Running setup.py install for requests 06:07:59 INFO - Running setup.py install for docopt 06:07:59 INFO - Successfully installed blobuploader requests docopt 06:07:59 INFO - Cleaning up... 06:08:00 INFO - Return code: 0 06:08:00 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:00 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:08:01 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 06:08:01 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 06:08:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:08:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:08:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:08:01 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:08:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:08:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:08:01 INFO - 'DCLOCATION': 'SCL3', 06:08:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:08:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:08:01 INFO - 'HOMEDRIVE': 'C:', 06:08:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:08:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:08:01 INFO - 'KTS_VERSION': '1.19c', 06:08:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:08:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:08:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:08:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:01 INFO - 'MOZ_NO_REMOTE': '1', 06:08:01 INFO - 'NO_EM_RESTART': '1', 06:08:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:08:01 INFO - 'OS': 'Windows_NT', 06:08:01 INFO - 'OURDRIVE': 'C:', 06:08:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:08:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:08:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:08:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:08:01 INFO - 'PROCESSOR_LEVEL': '6', 06:08:01 INFO - 'PROCESSOR_REVISION': '1e05', 06:08:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:08:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:08:01 INFO - 'PROMPT': '$P$G', 06:08:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:08:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:08:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:08:01 INFO - 'PWD': 'C:\\slave\\test', 06:08:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:08:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:08:01 INFO - 'SYSTEMDRIVE': 'C:', 06:08:01 INFO - 'SYSTEMROOT': 'C:\\windows', 06:08:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:01 INFO - 'TEST1': 'testie', 06:08:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:01 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:08:01 INFO - 'USERNAME': 'cltbld', 06:08:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:08:01 INFO - 'WINDIR': 'C:\\windows', 06:08:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:08:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tdyctz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c1kd3n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dy4uo0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ukiuio-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kzpxfu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ym1b8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zlgdy3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tcmnzc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fugwcq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8vs9w2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gnx6mb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gn1rco-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dtp2tg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owijqc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huubr2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:08:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:08:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6xuqhu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:08:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:08:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-52lhn5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:08:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:08:04 INFO - Running setup.py install for manifestparser 06:08:04 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 06:08:04 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 06:08:05 INFO - Running setup.py install for mozcrash 06:08:05 INFO - Running setup.py install for mozdebug 06:08:05 INFO - Running setup.py install for mozdevice 06:08:06 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 06:08:06 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 06:08:06 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 06:08:06 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 06:08:06 INFO - Running setup.py install for mozfile 06:08:07 INFO - Running setup.py install for mozhttpd 06:08:07 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Running setup.py install for mozinfo 06:08:07 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Running setup.py install for mozInstall 06:08:07 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 06:08:07 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 06:08:08 INFO - Running setup.py install for mozleak 06:08:08 INFO - Running setup.py install for mozlog 06:08:08 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 06:08:08 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 06:08:09 INFO - Running setup.py install for moznetwork 06:08:09 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 06:08:09 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 06:08:09 INFO - Running setup.py install for mozprocess 06:08:10 INFO - Running setup.py install for mozprofile 06:08:10 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 06:08:10 INFO - Running setup.py install for mozrunner 06:08:11 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 06:08:11 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 06:08:11 INFO - Running setup.py install for mozscreenshot 06:08:11 INFO - Running setup.py install for moztest 06:08:12 INFO - Running setup.py install for mozversion 06:08:12 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 06:08:12 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 06:08:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:08:12 INFO - Cleaning up... 06:08:12 INFO - Return code: 0 06:08:12 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:08:12 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')]} 06:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:12 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')]} 06:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:12 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:08:12 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 06:08:12 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 06:08:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:08:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:08:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:08:12 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:08:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:08:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:08:12 INFO - 'DCLOCATION': 'SCL3', 06:08:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:08:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:08:12 INFO - 'HOMEDRIVE': 'C:', 06:08:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:08:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:08:12 INFO - 'KTS_VERSION': '1.19c', 06:08:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:08:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:08:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:08:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:12 INFO - 'MOZ_NO_REMOTE': '1', 06:08:12 INFO - 'NO_EM_RESTART': '1', 06:08:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:08:12 INFO - 'OS': 'Windows_NT', 06:08:12 INFO - 'OURDRIVE': 'C:', 06:08:12 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;', 06:08:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:08:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:08:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:08:12 INFO - 'PROCESSOR_LEVEL': '6', 06:08:12 INFO - 'PROCESSOR_REVISION': '1e05', 06:08:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:08:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:08:12 INFO - 'PROMPT': '$P$G', 06:08:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:08:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:08:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:08:12 INFO - 'PWD': 'C:\\slave\\test', 06:08:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:08:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:08:12 INFO - 'SYSTEMDRIVE': 'C:', 06:08:12 INFO - 'SYSTEMROOT': 'C:\\windows', 06:08:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:12 INFO - 'TEST1': 'testie', 06:08:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:12 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:08:12 INFO - 'USERNAME': 'cltbld', 06:08:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:08:12 INFO - 'WINDIR': 'C:\\windows', 06:08:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:08:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rsp6o2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:08:13 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)) 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-luskv2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:08:13 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)) 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ryisab-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:08:13 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)) 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fwfqh4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:08:13 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)) 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cab4jd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:08:13 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)) 06:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewxcbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7mnvdp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6dfwxq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jct8ex-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ubnxik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vpzrd3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pzdfvw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:08:14 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)) 06:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d6w4_0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:08:15 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)) 06:08:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:08:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0apvwb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:08:15 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)) 06:08:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:08:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rwerpw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:08:15 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)) 06:08:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:08:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ywnuf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:08:15 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)) 06:08:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:08:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7mbfqs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:08:15 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)) 06:08:15 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)) 06:08:15 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)) 06:08:15 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)) 06:08:15 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)) 06:08:15 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)) 06:08:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:08:15 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 06:08:15 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 06:08:16 INFO - Downloading blessings-1.6.tar.gz 06:08:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 06:08:17 INFO - Installing collected packages: blessings 06:08:17 INFO - Running setup.py install for blessings 06:08:17 INFO - Successfully installed blessings 06:08:17 INFO - Cleaning up... 06:08:20 INFO - Return code: 0 06:08:20 INFO - Done creating virtualenv C:\slave\test\build/venv. 06:08:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:08:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:08:20 INFO - Reading from file tmpfile_stdout 06:08:20 INFO - Using _rmtree_windows ... 06:08:20 INFO - Using _rmtree_windows ... 06:08:20 INFO - Current package versions: 06:08:20 INFO - blessings == 1.6 06:08:20 INFO - blobuploader == 1.2.4 06:08:20 INFO - docopt == 0.6.1 06:08:20 INFO - functools32 == 3.2.3-2 06:08:20 INFO - jsonschema == 2.5.1 06:08:20 INFO - manifestparser == 1.1 06:08:20 INFO - mozInstall == 1.12 06:08:20 INFO - mozcrash == 0.17 06:08:20 INFO - mozdebug == 0.1 06:08:20 INFO - mozdevice == 0.48 06:08:20 INFO - mozfile == 1.2 06:08:20 INFO - mozhttpd == 0.7 06:08:20 INFO - mozinfo == 0.9 06:08:20 INFO - mozleak == 0.1 06:08:20 INFO - mozlog == 3.4 06:08:20 INFO - moznetwork == 0.27 06:08:20 INFO - mozprocess == 0.23 06:08:20 INFO - mozprofile == 0.28 06:08:20 INFO - mozrunner == 6.12 06:08:20 INFO - mozscreenshot == 0.1 06:08:20 INFO - mozsystemmonitor == 0.3 06:08:20 INFO - moztest == 0.7 06:08:20 INFO - mozversion == 1.4 06:08:20 INFO - psutil == 3.1.1 06:08:20 INFO - requests == 1.2.3 06:08:20 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:08:20 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')]} 06:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:20 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')]} 06:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:20 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:08:20 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 06:08:20 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 06:08:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:08:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:08:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:08:20 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:08:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:08:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:08:20 INFO - 'DCLOCATION': 'SCL3', 06:08:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:08:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:08:20 INFO - 'HOMEDRIVE': 'C:', 06:08:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:08:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:08:20 INFO - 'KTS_VERSION': '1.19c', 06:08:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:08:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:08:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:08:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:20 INFO - 'MOZ_NO_REMOTE': '1', 06:08:20 INFO - 'NO_EM_RESTART': '1', 06:08:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:08:20 INFO - 'OS': 'Windows_NT', 06:08:20 INFO - 'OURDRIVE': 'C:', 06:08:20 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;', 06:08:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:08:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:08:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:08:20 INFO - 'PROCESSOR_LEVEL': '6', 06:08:20 INFO - 'PROCESSOR_REVISION': '1e05', 06:08:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:08:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:08:20 INFO - 'PROMPT': '$P$G', 06:08:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:08:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:08:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:08:20 INFO - 'PWD': 'C:\\slave\\test', 06:08:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:08:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:08:20 INFO - 'SYSTEMDRIVE': 'C:', 06:08:20 INFO - 'SYSTEMROOT': 'C:\\windows', 06:08:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:20 INFO - 'TEST1': 'testie', 06:08:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:20 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:08:20 INFO - 'USERNAME': 'cltbld', 06:08:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:08:20 INFO - 'WINDIR': 'C:\\windows', 06:08:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:08:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 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)) 06:08:20 INFO - Cleaning up... 06:08:20 INFO - Return code: 0 06:08:20 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 06:08:20 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')]} 06:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:20 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')]} 06:08:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:20 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 0x01918640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0194FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01892B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-147', 'USERDOMAIN': 'T-W732-IX-147', '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-147', '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 06:08:20 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 06:08:20 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 06:08:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:08:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:08:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:08:20 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:08:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:08:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:08:20 INFO - 'DCLOCATION': 'SCL3', 06:08:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:08:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:08:20 INFO - 'HOMEDRIVE': 'C:', 06:08:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:08:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:08:20 INFO - 'KTS_VERSION': '1.19c', 06:08:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:08:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:08:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:08:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:20 INFO - 'MOZ_NO_REMOTE': '1', 06:08:20 INFO - 'NO_EM_RESTART': '1', 06:08:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:08:20 INFO - 'OS': 'Windows_NT', 06:08:20 INFO - 'OURDRIVE': 'C:', 06:08:20 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;', 06:08:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:08:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:08:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:08:20 INFO - 'PROCESSOR_LEVEL': '6', 06:08:20 INFO - 'PROCESSOR_REVISION': '1e05', 06:08:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:08:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:08:20 INFO - 'PROMPT': '$P$G', 06:08:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:08:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:08:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:08:20 INFO - 'PWD': 'C:\\slave\\test', 06:08:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:08:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:08:20 INFO - 'SYSTEMDRIVE': 'C:', 06:08:20 INFO - 'SYSTEMROOT': 'C:\\windows', 06:08:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:20 INFO - 'TEST1': 'testie', 06:08:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:20 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:08:20 INFO - 'USERNAME': 'cltbld', 06:08:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:08:20 INFO - 'WINDIR': 'C:\\windows', 06:08:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:08:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 06:08:21 INFO - Cleaning up... 06:08:21 INFO - Return code: 0 06:08:21 INFO - Running post-action listener: _resource_record_post_action 06:08:21 INFO - Running post-action listener: _start_resource_monitoring 06:08:21 INFO - Starting resource monitoring. 06:08:21 INFO - [mozharness: 2016-10-12 13:08:21.260000Z] Finished create-virtualenv step (success) 06:08:21 INFO - [mozharness: 2016-10-12 13:08:21.260000Z] Running install step. 06:08:21 INFO - Running pre-action listener: _resource_record_pre_action 06:08:21 INFO - Running main action method: install 06:08:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:08:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:08:21 INFO - Reading from file tmpfile_stdout 06:08:21 INFO - Using _rmtree_windows ... 06:08:21 INFO - Using _rmtree_windows ... 06:08:21 INFO - Detecting whether we're running mozinstall >=1.0... 06:08:21 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 06:08:21 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 06:08:21 INFO - Reading from file tmpfile_stdout 06:08:21 INFO - Output received: 06:08:21 INFO - Usage: mozinstall-script.py [options] installer 06:08:21 INFO - Options: 06:08:21 INFO - -h, --help show this help message and exit 06:08:21 INFO - -d DEST, --destination=DEST 06:08:21 INFO - Directory to install application into. [default: 06:08:21 INFO - "C:\slave\test"] 06:08:21 INFO - --app=APP Application being installed. [default: firefox] 06:08:21 INFO - Using _rmtree_windows ... 06:08:21 INFO - Using _rmtree_windows ... 06:08:21 INFO - mkdir: C:\slave\test\build\application 06:08:21 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'] 06:08:21 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 06:08:23 INFO - Reading from file tmpfile_stdout 06:08:23 INFO - Output received: 06:08:23 INFO - C:\slave\test\build\application\firefox\firefox.exe 06:08:23 INFO - Using _rmtree_windows ... 06:08:23 INFO - Using _rmtree_windows ... 06:08:23 INFO - Running post-action listener: _resource_record_post_action 06:08:23 INFO - [mozharness: 2016-10-12 13:08:23.878000Z] Finished install step (success) 06:08:23 INFO - [mozharness: 2016-10-12 13:08:23.878000Z] Running run-tests step. 06:08:23 INFO - Running pre-action listener: _resource_record_pre_action 06:08:23 INFO - Running main action method: run_tests 06:08:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 06:08:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 06:08:23 INFO - Python 2.7.3 06:08:23 INFO - Return code: 0 06:08:23 INFO - grabbing minidump binary from tooltool 06:08: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')]} 06:08:23 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 0x01969E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 06:08:23 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 06:08:23 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 06:08:23 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 06:08:23 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:08:24 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpn__tik 06:08:24 INFO - INFO - File integrity verified, renaming tmpn__tik to win32-minidump_stackwalk.exe 06:08:24 INFO - Return code: 0 06:08:24 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 06:08:24 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 06:08:24 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 06:08:24 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 06:08:24 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 06:08:24 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/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-147', '--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 06:08:24 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/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-147 --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 06:08:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:08:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:08:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:08:24 INFO - 'COMPUTERNAME': 'T-W732-IX-147', 06:08:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:08:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:08:24 INFO - 'DCLOCATION': 'SCL3', 06:08:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:08:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:08:24 INFO - 'HOMEDRIVE': 'C:', 06:08:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:08:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:08:24 INFO - 'KTS_VERSION': '1.19c', 06:08:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:08:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-147', 06:08:24 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:08:24 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 06:08:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:08:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:24 INFO - 'MOZ_NO_REMOTE': '1', 06:08:24 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:08:24 INFO - 'NO_EM_RESTART': '1', 06:08:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:08:24 INFO - 'OS': 'Windows_NT', 06:08:24 INFO - 'OURDRIVE': 'C:', 06:08:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:08:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:08:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:08:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:08:24 INFO - 'PROCESSOR_LEVEL': '6', 06:08:24 INFO - 'PROCESSOR_REVISION': '1e05', 06:08:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:08:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:08:24 INFO - 'PROMPT': '$P$G', 06:08:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:08:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:08:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:08:24 INFO - 'PWD': 'C:\\slave\\test', 06:08:24 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 06:08:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:08:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:08:24 INFO - 'SYSTEMDRIVE': 'C:', 06:08:24 INFO - 'SYSTEMROOT': 'C:\\windows', 06:08:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:24 INFO - 'TEST1': 'testie', 06:08:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:08:24 INFO - 'USERDOMAIN': 'T-W732-IX-147', 06:08:24 INFO - 'USERNAME': 'cltbld', 06:08:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:08:24 INFO - 'WINDIR': 'C:\\windows', 06:08:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:08:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:08:24 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/SheMfBEST_i-iJNzdDpegA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-147', '--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 06:08:25 INFO - mozversion application_buildid: 20161012033004 06:08:25 INFO - mozversion application_changeset: aa8629a25ab5aeb2678a0187e50336659d368b44 06:08:25 INFO - mozversion application_display_name: Nightly 06:08:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:25 INFO - mozversion application_name: Firefox 06:08:25 INFO - mozversion application_remotingname: firefox 06:08:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:08:25 INFO - mozversion application_vendor: Mozilla 06:08:25 INFO - mozversion application_version: 52.0a1 06:08:25 INFO - mozversion platform_buildid: 20161012033004 06:08:25 INFO - mozversion platform_changeset: aa8629a25ab5aeb2678a0187e50336659d368b44 06:08:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:08:25 INFO - mozversion platform_version: 52.0a1 06:08:25 INFO - using testdate: 1476277705 06:08:25 INFO - actual date: 1476277705 06:08:25 INFO - starting webserver on 'localhost:49738' 06:08:25 INFO - SUITE-START | Running 2 tests 06:08:25 INFO - TEST-START | dromaeo_css 06:08:25 INFO - operating with platform_type : w7_ 06:08:25 INFO - Initialising browser for dromaeo_css test... 06:08:26 INFO - TEST-INFO | started process 3720 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmfh06a\profile http://localhost:49738/getInfo.html) 06:08:33 INFO - PROCESS | 3720 | __metrics Screen width/height:1600/1200 06:08:33 INFO - PROCESS | 3720 | colorDepth:24 06:08:33 INFO - PROCESS | 3720 | Browser inner width/height: 1010/674 06:08:33 INFO - PROCESS | 3720 | __metrics 06:08:34 INFO - PROCESS | 3720 | 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" 06:08:34 INFO - PROCESS | 3720 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:08:35 INFO - TEST-INFO | 3720: exit 0 06:08:35 INFO - Browser initialized. 06:08:35 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:35 INFO - TEST-INFO | started process 2920 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmfh06a\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:35 INFO - PROCESS | 2920 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 06:08:46 INFO - PROCESS | 2920 | RSS: Main: 123666432 06:08:46 INFO - PROCESS | 2920 | 06:09:38 INFO - PROCESS | 2920 | 1476277778125 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] 06:11:48 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49738/tests/dromaeo/cssquery-ext.html) 06:11:49 INFO - PROCESS | 2920 | RSS: Main: 206233600 06:11:49 INFO - PROCESS | 2920 | 06:14:52 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-ext.html (next: http://localhost:49738/tests/dromaeo/cssquery-jquery.html) 06:14:53 INFO - PROCESS | 2920 | RSS: Main: 197230592 06:14:53 INFO - PROCESS | 2920 | 06:17:55 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49738/tests/dromaeo/cssquery-mootools.html) 06:17:55 INFO - PROCESS | 2920 | RSS: Main: 136257536 06:17:55 INFO - PROCESS | 2920 | 06:20:57 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49738/tests/dromaeo/cssquery-prototype.html) 06:20:58 INFO - PROCESS | 2920 | RSS: Main: 143171584 06:20:58 INFO - PROCESS | 2920 | 06:24:00 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49738/tests/dromaeo/cssquery-yui.html) 06:24:01 INFO - PROCESS | 2920 | RSS: Main: 133259264 06:24:01 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | Cycle 1(1): loaded http://localhost:49738/tests/dromaeo/cssquery-yui.html (next: http://localhost:49738/tests/dromaeo/cssquery-dojo.html) 06:27:04 INFO - PROCESS | 2920 | RSS: Main: 185040896 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | __start_tp_report 06:27:04 INFO - PROCESS | 2920 | _x_x_mozilla_page_load 06:27:04 INFO - PROCESS | 2920 | _x_x_mozilla_page_load_details 06:27:04 INFO - PROCESS | 2920 | |i|pagename|runs| 06:27:04 INFO - PROCESS | 2920 | |0;dojo.html;2722.6052222427747;2746.7939904507166;2760.323720688429;2764.3089227693076;2767.2113447667407;4683.508231635676;4708.199606066968;4752.405949256343;4789.42526896772;4792.329073929653;4667.0665866826685;4701.459332176801;4743.383360163182;4791.640626952975;4806.9038619227595;4083.8774836754947;4149.585041495857;4179.498460184767;4197.832086716528;4216.241076606209;3969.9603003970024;4153.958460415387;4164.479440069991;4168.854090106847;4170.35359519275;4141.834326626931;4151.4395556599775;4207.26372884744;4218.240716670998;4230.534641189467;4096.651784598313;4097.078157414588;4219.367094935754;4236.512801027875;4242.151569686075;4027.8791636250958;4039.212353591051;4066.2883995300704;4221.092465120014;4227.619514243702;4651.093036857814;4662.137504561667;4695.530446955311;4727.267273572602;4744.881377965544;4794.448638406593;4913.31213630092;4923.310736496894;5005.499450055002;5008.574271186953;4915.803367865281;4991.900161996739;5015.3229314042865;5036.647434679574;5046;4584.724916505021;4590.8163673453355;4658.697184682985;4714.175019371596;4724.4094488188975;5073.467285934998;5075.95942831726;5170.586353091819;5176.637635365489;5181.300524429229;4603.631709463235;4721.291806229093;4748.857534273978;4883.926741098886;4889.65772395936;5054.444011158769;5062.11413002723;5068.341115654941;5068.82259120929;5070.087384270867;4912.459629440758;4926.45808961014;4952.182889823211;5018;5036.6726162799305;4879.585235254972;4951.084049450863;4953.306537084811;5043.646944713835;5060.2662613920775;3334.4664853623303;3343.030521148888;3355.4799006154085;3362.6637336266176;3367.1582104473882;3071.355015446781;3092.582501362287;3106.176863131272;3107.7979931304394;3109.2226943264186;4660.86017419478;4662.277347011219;4664.743439110882;4676.579107880307;4679.040796636681;4941.530554597308;4971.204607262893;5012;5016.347874776328;5036.194208926628;4543.931841022388;4590.334401511762;4638.884027899329;4645.279981602857;4667;4138.627523522898;4147.91704165915;4155.065110350147;4164.333706606928;4185.037441388497;5122.206058061007;5181.041507321158;5236.450172731885;5292.391374991926;5301.125314323093;4889.731064791328;4998.625103117208;5092.465291144376;5106;5108.361454818148;5113.821016264413;5143.588512919032;5164.741762911764;5167.61242906776;5237.790488380575;4924.65527413092;5016.648834581544;5021.49785021495;5034.4462109167225;5034.823781167642;5000.599952003904;5022.372203474566;5068.797248110034;5108.3103780989095;5108.412532558803;4701.8589442315415;4715.81136754526;4761.619070474285;4872.90254194907;4974.527419895104;4733;4828.5412885775795;4926.975365123151;4929.926051109308;4932.062908047459;4323.416338794223;4678.087772884255;4728.31439441286;4821.783019764189;4826.855194344175;4549.363089167453;4773.474917758975;4829.106615276185;4924.729139897197;4933.062718083553;4716.834910778106;4733.597644200144;4764.857054288238;4801.807927682854;4846.5638092571835 06:27:04 INFO - PROCESS | 2920 | |1;ext.html;511.9846404607862;524.2162966365285;535.392329705784;537.441061296252;543.1716632135992;26867.32831679209;28407.43185136296;29910.850445747794;40553.59446405535;42393.57606423935;39150;39432.80283598585;39485.40771888424;40013;40139.397909031315;6741.797746067626;8437.493750374982;8503.149685031478;8563.785905352353;8594.914050859505;6686.6322352270845;9693.127618514332;10106.09045185933;10121.291509594332;10423.218258630595;10416.895831041706;10474.47627618616;10502.212334074937;10541.104006159485;10568.894311056907;6642.368974947372;9927.751806204866;10430.530626121805;10494.003069708366;10512.264141510097;6777.220619628756;9904.158146557551;10177.236707246911;10184.54169562364;10535.157187424988;37247;37582;37640.81179594112;37655.4351684725;37658.62341376579;2971.554266859967;3108.984455077733;3151.936961260762;3417.3848707232687;3458.515807786912;28400.431991360263;29630.85184574063;30011.249718756986;30185.547216791765;30344.54483182754;3306.9834650826833;3378.7972721636534;3387.4543881801865;3508.1054331145397;3688.4651725499652;300037;319234;319284;319839;322793;51760.48239517632;51927.48072519302;52732.73633631807;53036.469635303925;53113.7344313276;28550.28624284368;29135.85432072826;29199.270018249714;30083.69916300853;54103.72948135234;13786.172829630254;13889.374978125807;13911.304434778423;13914.791278130837;13948.163110213216;9240.214581760625;9340.673076442428;10437.060664540228;10522.526486308134;10571.94714026425;14779.113253204841;14988.850111498963;16718.331266749195;17153.82846171547;17156.65686686259;6628.668566571652;6691.5315927884585;6702.798916032527;6757.628330441775;6764.425023873026;747.9439042071866;791.5804623549528;862.7541150772;871.8953725552974;875.9518226497477;27251.318717032234;28503.57244641332;28555.857220713762;28742.856285718437;29857.701422985927;902.0979020979021;953.4327075390132;985.5121714751244;1019.4393083803878;1070.753726642861;14220.85779142216;14668.55994320172;14694;14953.551393458214;15031.173285469404;413751;453395;455396;459732;461202;47062;47108.293375599394;47323.5267647326;48112;48302.27546586804;1041.9322744021615;1048.7483004079118;1049.5486940615608;1133.3596517967321;1158.652404278713;24172.39569010789;28229.43541129207;28521;29549;29763.404731904808;45631.31553026641;45733.08533829371;46263.07473850571;46313.76843115763;46314.30528542007;419677;422896;423103;425063;428790;23089.769102309434;23142.42143946415;23192.768072319737;23254.186103486296;23281.18515851937;33546.832265838515;34351.65648343485;40469.39295910505;41047.38428923628;44369;35121.297574048884;35348;35947.1013224661;36104.819475902455;36882.262354752216;34684.65315346915;34902.476462852566;35587.075182278466;35630.465543016355;36961.81519092387;34366.48450273198;34711.30577388489;35009.82495087508;35109.12227194341;35407.468887966184;30485;31178.688213117577;32544;33168.66831331656;33258.83370583132;23849.68293739886;24932.37669058288;25148.060593641094;25323.113691020724;25497.490050199263 06:27:04 INFO - PROCESS | 2920 | |2;jquery.html;288.60028860028854;306.76532452673706;317.104180689552;323.5195734334511;324.73534069733165;13160.73678526429;17695.380661676845;18123.728144077817;18311.267549298012;18892.905535472302;16662.50012499624;17900.82099179008;18051.27794888203;18300;18346.357877474293;4677.345171675968;5374.865628359293;5423.674579525222;5496.203050557687;5558.86102847428;5437.510624043835;5488.670679759227;5560;5565.777368905259;5568.276124103861;5503.669779813224;5509.862253443656;5510.586705997046;5513.979913715955;5516.062269414209;5058.165402708567;5211.218317252405;5355.839324820223;5393.973030134844;5403.054465468527;4590.9540904591195;4691.88270293242;4705.670603057787;4709.387779588666;4710.057988402334;21729.348119556438;22409.775902240934;22489.775102248932;22519.662205066936;22796.77203227963;2142.2930432957087;2156.9352919412445;2194.2758889566403;2449;2746.368335282896;14254.429822807075;14263.928680356532;14357.56927292183;14479;14761.55715328542;1752.737089436582;1770.1503278426428;1782.8306310900443;1806.7560879281261;1839.8620103492292;82490.58754706188;83385.58307208486;83390.16609833884;83562.58218708928;84650.99999999939;8767.473951562926;8780.429272097294;9088.636454541876;9117.498537580497;9246.76883077915;10517.42154181512;11048.613298534512;11181.776364472666;11751.529938802352;11805.822912656316;7031.945208218705;7096.467764917652;7154.213036565973;7185.281471852877;7206.099237595301;6996.111050016467;7003.2296447390745;7098.929010709827;7106.147262328554;7116.110486189226;5705.429457054344;5852.239208902815;5860.472557469763;5865.501432378295;5929.525637948954;1567.1928956587367;1570.9239171167787;1586.1434825194497;1589.078334565949;1589.8171710253237;849.0490650471388;849.180540778152;894.3962825093166;904.755715956688;932.1889955738416;18576.44270671882;19032.52418689544;19220.423387298404;19243.711344329848;19363.709544356847;827.5324441690426;839.5927974932154;885.685581618529;910.5538286239695;914.3188324698142;8867.157620026217;10312.742181445525;10341.121004714683;10467.424291664032;10478.790424191626;430451;435299;441608;446463;446533;60459.69770151121;97224.02775972291;97544.51227743957;101175.99999999853;102401.48799255956;888.8666699995182;895.6238379880466;910.8087301666724;913.2009491694768;916.9128932751512;16533.83466165323;16581.08804015791;16795.91602041982;17050.14749262507;17145.571360716083;32875.835620821745;42842.35736464018;42996.78501607597;43911.34132987944;44788.552114478436;58965.410345895994;61059.38940610537;61458;61666.383336167855;64284;7064.187618423947;7167.569945803269;7229.674664639999;7471.8879216812875;7533.849323013399;10203.438810865473;12560.874391255971;12786.488540458278;12798;12922.741545168856;10381.636642717469;10460.320079194827;10802.243842930919;10933.398663073607;11130.109591232844;10158.74603134898;10892.673219803419;10940;11030.172737044593;11088.390138542134;11020.614278500214;11021.283616564897;11359.977602015859;11577.710557236136;11578.07375409982;7304.160021597372;7372.299631534996;7398.408127349907;7498.550086994798;7641.732539361095;7186.568805871664;10431.58273669075;10730.570777168827;10833.512491937725;11135.3875536843 06:27:04 INFO - PROCESS | 2920 | |3;mootools.html;1154.3074155506695;1182.4206138991897;1264.867188945161;1545.5363390982702;1560.6644571417144;7023.648817559121;7342.669579868905;7359.632018399072;7369.152547457044;7373.631318434077;6256.499480041597;6405.61566306022;6450.709718062687;6573.934260657392;6891.1041564596535;1913.8564607654412;1936.206155476255;1940.7088936659472;1941.0488860458415;1944.445832937613;1959.0792327606002;1983.0580474274748;1995.750531183602;2000.21991423345;2000.9099590518388;1667.9416220432288;1712.7002774514472;1897.7343171955938;1907.818757218062;1957.706344048397;1886.9528261793496;1924.9037548122678;1943.0770384067596;1945.873518221311;1953.9706904396446;1839.8988055656935;1840.3466769296974;1853.5551467647667;1853.6200078983777;1863.6552237836045;7039.26087760788;7171.713131474735;7175;7175.282471752836;7188.2811718828225;4088.6524645405175;4303.988562687766;4314;4328.50222224445;4334.328179132208;2700.4599080184043;2772.916812495628;2828.6888442271334;2829.8302101873746;2860.0275906191787;872.3806097670662;888.6845169964635;891.9509426981514;928.6517555916531;930.3952430919888;9039.186473217376;9249.583768730356;9309.44143351401;9324.673636422693;9334.113259240361;3681.668649821529;3823.311803875329;3855;3865.1110244643887;3871.5741268460447;7345.632718364061;7396.55620662762;7401.481896267317;7405.629718514053;7422.96288518554;4269.338252570857;4286.485621725413;4287.442632457761;4289.1850548395705;4293.957060429356;4049.3521036633997;4065.1463192729857;4066.1664358806374;4070.898227544335;4071.6131967463634;2818.8167769094944;2891.97108028917;2912.3301640622353;2929.765618750495;2939.529675251992;879.8020445399734;885.3050355470924;889.4485419040236;892.1524551675805;906.7687739626354;669.7689297192461;824.2581676491205;825.4840724547158;829.9004119505577;843.7300063979468;4634.884127896762;4660.5106463821485;4687.81248750053;4754.31062495943;4946.307516947631;341.173736544648;354.8243619408358;360.02612932018826;363.8617325416325;363.8738105563277;3179.554862319277;3190.1067701043744;3254.414205443043;3349.2129349602824;3408.488726690967;8433.283170930417;8550.615222315011;8671.132886711279;8719.433236839584;8733.606987685442;8417.747467575982;8639.438436501607;8653.091425400367;8662.82674346522;8673.869891951626;6594.307597702268;6805.761798337035;6810.489213308922;6816.386525212754;6822.590644561342;4647.512011238771;4681.976590117028;4697.647676424213;4715.104130215248;4744.383230180055;7827.373810095065;7908.52548847071;8100.311473524816;8113.7565873023905;8123.0658474276215;8123.4313598047565;8205.917940820755;8208.384371172306;8209.548474833942;8220.219079187707;5633.3521645009705;5792.21805056312;5808.099744539603;6070.817875463743;6325.462335701517;6854.760083397057;7015.824604384727;7059.2940705928995;7065.364117229474;7089.751858684921;6161.722722477589;6167.599106058092;6189.690515474298;6199.287081985449;6222.253329600296;5879.588428809942;5932.7033648318275;5980.983232850513;5981.7308221131025;6066.1204125402455;5664.631798933056;5846.970765146147;5861.853453663522;5863.1791549184;5866.560007999332;4057.1885622876134;4086.468759061303;4141.91716165681;4148.854790082332;4160.417541544247;4170.874873753793;4193.203291374629;4198;4202.85290014848;4252.702310838212 06:27:04 INFO - PROCESS | 2920 | |4;prototype.html;238.75646840222984;253.94032402385437;258.1249563978114;260.0624747784238;261.1044118672951;4918.188498897677;5112.977404519097;5198.272241886129;5230.607704422169;5246.1606143017125;5104.234364845275;5118.104331741949;5128.435872054071;5128.820491282806;5138.152204886189;3748.7563308385006;3757.1358587524887;3776.848926042955;3777.848886044555;3788.886333410002;3757.436384542327;3779.659830615244;3787.0721673190105;3795.5635101963353;3798.9810050949704;3712;3719.2561487702433;3755.929560075379;3757.887263382103;3759.473673685686;3546.1311978565286;3740.3454395480953;3757.9624203757885;3758.7220345082665;3765.3034188675183;3207;3228.80627162371;3236.4983427568527;3246.0748686624434;3253.154179913216;4962.925556116661;5026.843825920022;5033.219850923113;5033.546980771748;5040.243963405482;3950.980245098785;4226.598473145077;4277.850275240383;4287.09970906107;4296.957030429687;5249.580033597303;5249.928791656886;5264.894702105975;5266.60500462463;5285.86785330366;3301.4057469655454;3429.2455659754814;3446.827658617059;3474.287771006963;3498.142954976022;5514.476124768141;5515.145152501369;5537.197106419547;5550.862073274969;5552.750126244288;3543.9291214175573;3545.6986156177013;3553.289342131584;3565.5008298838184;3569.196930690574;3596.6583174598895;3596.9100772480374;3604.945925811115;3606.098475381155;3606.495090687306;4252.340887162495;4267.509236437787;4267.701260911768;4283.314669652903;4284.078923031563;5139.922901156486;5310.92033619496;5324.148136298251;5334.599905007062;5355.250264962909;1224.4428784902848;1249.9437525311382;1250.2873362183468;1253.6615113919372;1255.7425727725795;747.7756672998079;766.1954947305419;771.7106085218044;774.3031271855375;782.440555003164;4543.568361005699;4575.43775485729;4585.335126406652;4586.197415452282;4588.564086411786;4827.8551643450755;4835.516448355136;4848.345473361122;4864.391951006124;4876.780544875489;4555.954440455619;4567.862964111016;4849.369581954324;4991.575716064102;5010.423801262828;2651.57574788033;2842.7299406556344;2866.2834291427143;2871.8604639543255;2872.7127287271105;5732.05421105513;5740.598158128891;5742.397048309867;5747.137929310553;5750.539956803445;5516.338039435213;5543.168524721244;5559.861003474864;5613.298337707786;5615.606907516516;5541.695206763667;5713.2572765541045;5721.799737009184;5729.455701708331;5736.110902809987;5387.703676297841;5408.891822163456;5478.972605136949;5486.61593688438;5496.477834605706;5398.244245805512;5415.404305526467;5435.293411856592;5443.891122177455;5451.890962180655;5682.857928551819;5725;5727.971360143172;5744.597878148489;5744.885102298014;3377.4089534331883;3552.4138517144384;3570.91072723184;3574.3208790330796;3577.624349443271;5477.342718873601;5487.890242195054;5494.011078005998;5495.258140151188;5497.615166938276;5304.965531721277;5317.574594032545;5329.387120481039;5329.467053294639;5334.333208348957;5310.654807437504;5321;5336.706481143575;5344.35867695879;5351.9464805351445;4972.343005886395;5116.258142569381;5170.276161337491;5188.143956247328;5208;5164.483551644806;5274.340707411574;5309.840704778863;5313.362396512443;5340.839774806762;5156.226566015128;5228.6078544110105;5277.84166474991;5280.313559237275;5302.628815982998 06:27:04 INFO - PROCESS | 2920 | |5;yui.html;623.7099748616893;628.4846425930735;680.5270337115705;682.764446266038;702.8559145375199;13911.44354225831;14687.265636718148;15123.848761512381;15197.164155971417;15679.137647429387;14048.929755351235;14158.50445234417;14251.572452826405;14264.429422823074;14265.358058887348;504.1807063521777;639.9136116624254;654.9345065493436;655.3282885042821;657.9868402631945;532.7975369359638;641.2177143884453;647.7473785223766;648.4941745438565;659.0180630860008;678.6064082831971;686.3719696477717;690.9827254318632;691.4710246661308;697.0346070692102;543.3371287029818;671.358852296056;671.5064427645676;675.8850995330781;683.5317807302014;395.9188366384884;402.9879103626896;406.1430381894201;488.6799146558974;493.7383186910943;13511.932440337736;13690.110142840684;14001.649958751012;14050.789238161437;14078.507252246121;12616.306103164323;12817.295048772314;12879.613611591667;13250.46998120074;13361.599152025456;12788.232706037668;12953.417096230693;13017.674558136028;13144.934275328658;13278.07053506255;4237.131388065439;4296.484421869364;4374.737515749033;4379.211741886459;4389.714668546534;70068.6496567514;70605.94091088587;83356;85249.57375213146;85363.14636853614;10503.159747220205;11023.393713345682;11065.391403472726;11075.556977720962;11097.44512774358;19941.302054428026;20010.499737506678;20168.899155504423;20286.391408257776;20320.491987700425;7739.458237923404;7803.492772969739;7812.140664526897;7886.369090472748;8056.7180148694515;3989.4414781930554;4005.5674305313682;4021.8190181441905;4033.394990751352;4036.535798383165;2966.1842993177047;2995.0864986179427;3004.8948286809855;3019.230096325423;3050.9542356864663;547.7535109200876;551.3439007580965;559.046825163096;560.8009156749376;561.0546229603132;608.9908651370145;614.0543562913153;695.197047410242;708.3589351636674;714.7248309400918;9317.39436936597;9709.174720148867;9910.454924979196;10117.747056323504;10171.796564068678;552.3675391676508;628.8836565235446;653.9869202615921;690.2786588015535;694.3195668245148;4724.763761811896;4735.739534325577;4863.640445850317;4870.805167793249;4894.461609222983;105047.4747626257;109369.45315273372;109630;111069;112365.43817281025;97214.51392742991;98055;100466;100892;100925;89356.3193104195;89549.10450895538;89885.55057224672;89978.55010724904;91476;8789.252913502423;9014.18872301496;9224.169824715807;9276.211522020703;9508.572114254737;49704.25443618276;50121.24818127658;50176.749116254185;51044;51537.7423112882;68460.65769671119;73330;73623;73713.6314318425;73786.89319660101;11833.52665893389;11984.101192410779;12048.75902481963;12121.454534546143;12232.75534489323;13305.268210248141;13341.533046343331;13456.5290214842;13688.720605818971;14419.918506111873;12277;12560.748785024065;12714.173578717724;13120.671983200496;13181.934090329487;9406.576704048197;13791.724165516433;14186.503472378417;14197.432102715775;14505.927470362582;13604.727905442034;13810.861891381359;13834.23911684867;14083.225422601445;14142;11375.507257201318;11928.76142477163;12239.388030598257;12337.50649974027;12485;12078.637640870787;12178.817317740419;12239.449224784728;12309.75380492403;12386.009119270257 06:27:04 INFO - PROCESS | 2920 | __end_tp_report 06:27:04 INFO - PROCESS | 2920 | __start_cc_report 06:27:04 INFO - PROCESS | 2920 | _x_x_mozilla_cycle_collect,1144 06:27:04 INFO - PROCESS | 2920 | __end_cc_report 06:27:04 INFO - PROCESS | 2920 | __startTimestamp1476278824343__endTimestamp 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | ------- Summary: start ------- 06:27:04 INFO - PROCESS | 2920 | Number of tests: 6 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#0] dojo.html Cycles:165 Average:4591.73 Median:4752.41 stddev:587.97 (12.4%) stddev-sans-first:571.19 06:27:04 INFO - PROCESS | 2920 | Values: 2722.6 2746.8 2760.3 2764.3 2767.2 4683.5 4708.2 4752.4 4789.4 4792.3 4667.1 4701.5 4743.4 4791.6 4806.9 4083.9 4149.6 4179.5 4197.8 4216.2 3970.0 4154.0 4164.5 4168.9 4170.4 4141.8 4151.4 4207.3 4218.2 4230.5 4096.7 4097.1 4219.4 4236.5 4242.2 4027.9 4039.2 4066.3 4221.1 4227.6 4651.1 4662.1 4695.5 4727.3 4744.9 4794.4 4913.3 4923.3 5005.5 5008.6 4915.8 4991.9 5015.3 5036.6 5046.0 4584.7 4590.8 4658.7 4714.2 4724.4 5073.5 5076.0 5170.6 5176.6 5181.3 4603.6 4721.3 4748.9 4883.9 4889.7 5054.4 5062.1 5068.3 5068.8 5070.1 4912.5 4926.5 4952.2 5018.0 5036.7 4879.6 4951.1 4953.3 5043.6 5060.3 3334.5 3343.0 3355.5 3362.7 3367.2 3071.4 3092.6 3106.2 3107.8 3109.2 4660.9 4662.3 4664.7 4676.6 4679.0 4941.5 4971.2 5012.0 5016.3 5036.2 4543.9 4590.3 4638.9 4645.3 4667.0 4138.6 4147.9 4155.1 4164.3 4185.0 5122.2 5181.0 5236.5 5292.4 5301.1 4889.7 4998.6 5092.5 5106.0 5108.4 5113.8 5143.6 5164.7 5167.6 5237.8 4924.7 5016.6 5021.5 5034.4 5034.8 5000.6 5022.4 5068.8 5108.3 5108.4 4701.9 4715.8 4761.6 4872.9 4974.5 4733.0 4828.5 4927.0 4929.9 4932.1 4323.4 4678.1 4728.3 4821.8 4826.9 4549.4 4773.5 4829.1 4924.7 4933.1 4716.8 4733.6 4764.9 4801.8 4846.6 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#1] ext.html Cycles:180 Average:53070.76 Median:18912.50 stddev:106213.18 (561.6%) stddev-sans-first:106437.83 06:27:04 INFO - PROCESS | 2920 | Values: 512.0 524.2 535.4 537.4 543.2 26867.3 28407.4 29910.9 40553.6 42393.6 39150.0 39432.8 39485.4 40013.0 40139.4 6741.8 8437.5 8503.1 8563.8 8594.9 6686.6 9693.1 10106.1 10121.3 10423.2 10416.9 10474.5 10502.2 10541.1 10568.9 6642.4 9927.8 10430.5 10494.0 10512.3 6777.2 9904.2 10177.2 10184.5 10535.2 37247.0 37582.0 37640.8 37655.4 37658.6 2971.6 3109.0 3151.9 3417.4 3458.5 28400.4 29630.9 30011.2 30185.5 30344.5 3307.0 3378.8 3387.5 3508.1 3688.5 300037.0 319234.0 319284.0 319839.0 322793.0 51760.5 51927.5 52732.7 53036.5 53113.7 28550.3 29135.9 29199.3 30083.7 54103.7 13786.2 13889.4 13911.3 13914.8 13948.2 9240.2 9340.7 10437.1 10522.5 10571.9 14779.1 14988.9 16718.3 17153.8 17156.7 6628.7 6691.5 6702.8 6757.6 6764.4 747.9 791.6 862.8 871.9 876.0 27251.3 28503.6 28555.9 28742.9 29857.7 902.1 953.4 985.5 1019.4 1070.8 14220.9 14668.6 14694.0 14953.6 15031.2 413751.0 453395.0 455396.0 459732.0 461202.0 47062.0 47108.3 47323.5 48112.0 48302.3 1041.9 1048.7 1049.5 1133.4 1158.7 24172.4 28229.4 28521.0 29549.0 29763.4 45631.3 45733.1 46263.1 46313.8 46314.3 419677.0 422896.0 423103.0 425063.0 428790.0 23089.8 23142.4 23192.8 23254.2 23281.2 33546.8 34351.7 40469.4 41047.4 44369.0 35121.3 35348.0 35947.1 36104.8 36882.3 34684.7 34902.5 35587.1 35630.5 36961.8 34366.5 34711.3 35009.8 35109.1 35407.5 30485.0 31178.7 32544.0 33168.7 33258.8 23849.7 24932.4 25148.1 25323.1 25497.5 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#2] jquery.html Cycles:180 Average:27261.88 Median:242759.67 stddev:73120.90 (30.1%) stddev-sans-first:73297.97 06:27:04 INFO - PROCESS | 2920 | Values: 288.6 306.8 317.1 323.5 324.7 13160.7 17695.4 18123.7 18311.3 18892.9 16662.5 17900.8 18051.3 18300.0 18346.4 4677.3 5374.9 5423.7 5496.2 5558.9 5437.5 5488.7 5560.0 5565.8 5568.3 5503.7 5509.9 5510.6 5514.0 5516.1 5058.2 5211.2 5355.8 5394.0 5403.1 4591.0 4691.9 4705.7 4709.4 4710.1 21729.3 22409.8 22489.8 22519.7 22796.8 2142.3 2156.9 2194.3 2449.0 2746.4 14254.4 14263.9 14357.6 14479.0 14761.6 1752.7 1770.2 1782.8 1806.8 1839.9 82490.6 83385.6 83390.2 83562.6 84651.0 8767.5 8780.4 9088.6 9117.5 9246.8 10517.4 11048.6 11181.8 11751.5 11805.8 7031.9 7096.5 7154.2 7185.3 7206.1 6996.1 7003.2 7098.9 7106.1 7116.1 5705.4 5852.2 5860.5 5865.5 5929.5 1567.2 1570.9 1586.1 1589.1 1589.8 849.0 849.2 894.4 904.8 932.2 18576.4 19032.5 19220.4 19243.7 19363.7 827.5 839.6 885.7 910.6 914.3 8867.2 10312.7 10341.1 10467.4 10478.8 430451.0 435299.0 441608.0 446463.0 446533.0 60459.7 97224.0 97544.5 101176.0 102401.5 888.9 895.6 910.8 913.2 916.9 16533.8 16581.1 16795.9 17050.1 17145.6 32875.8 42842.4 42996.8 43911.3 44788.6 58965.4 61059.4 61458.0 61666.4 64284.0 7064.2 7167.6 7229.7 7471.9 7533.8 10203.4 12560.9 12786.5 12798.0 12922.7 10381.6 10460.3 10802.2 10933.4 11130.1 10158.7 10892.7 10940.0 11030.2 11088.4 11020.6 11021.3 11360.0 11577.7 11578.1 7304.2 7372.3 7398.4 7498.6 7641.7 7186.6 10431.6 10730.6 10833.5 11135.4 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#3] mootools.html Cycles:180 Average:4603.61 Median:5648.99 stddev:2580.48 (45.7%) stddev-sans-first:2574.69 06:27:04 INFO - PROCESS | 2920 | Values: 1154.3 1182.4 1264.9 1545.5 1560.7 7023.6 7342.7 7359.6 7369.2 7373.6 6256.5 6405.6 6450.7 6573.9 6891.1 1913.9 1936.2 1940.7 1941.0 1944.4 1959.1 1983.1 1995.8 2000.2 2000.9 1667.9 1712.7 1897.7 1907.8 1957.7 1887.0 1924.9 1943.1 1945.9 1954.0 1839.9 1840.3 1853.6 1853.6 1863.7 7039.3 7171.7 7175.0 7175.3 7188.3 4088.7 4304.0 4314.0 4328.5 4334.3 2700.5 2772.9 2828.7 2829.8 2860.0 872.4 888.7 892.0 928.7 930.4 9039.2 9249.6 9309.4 9324.7 9334.1 3681.7 3823.3 3855.0 3865.1 3871.6 7345.6 7396.6 7401.5 7405.6 7423.0 4269.3 4286.5 4287.4 4289.2 4294.0 4049.4 4065.1 4066.2 4070.9 4071.6 2818.8 2892.0 2912.3 2929.8 2939.5 879.8 885.3 889.4 892.2 906.8 669.8 824.3 825.5 829.9 843.7 4634.9 4660.5 4687.8 4754.3 4946.3 341.2 354.8 360.0 363.9 363.9 3179.6 3190.1 3254.4 3349.2 3408.5 8433.3 8550.6 8671.1 8719.4 8733.6 8417.7 8639.4 8653.1 8662.8 8673.9 6594.3 6805.8 6810.5 6816.4 6822.6 4647.5 4682.0 4697.6 4715.1 4744.4 7827.4 7908.5 8100.3 8113.8 8123.1 8123.4 8205.9 8208.4 8209.5 8220.2 5633.4 5792.2 5808.1 6070.8 6325.5 6854.8 7015.8 7059.3 7065.4 7089.8 6161.7 6167.6 6189.7 6199.3 6222.3 5879.6 5932.7 5981.0 5981.7 6066.1 5664.6 5847.0 5861.9 5863.2 5866.6 4057.2 4086.5 4141.9 4148.9 4160.4 4170.9 4193.2 4198.0 4202.9 4252.7 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#4] prototype.html Cycles:180 Average:4359.45 Median:5036.90 stddev:1372.01 (27.2%) stddev-sans-first:1340.55 06:27:04 INFO - PROCESS | 2920 | Values: 238.8 253.9 258.1 260.1 261.1 4918.2 5113.0 5198.3 5230.6 5246.2 5104.2 5118.1 5128.4 5128.8 5138.2 3748.8 3757.1 3776.8 3777.8 3788.9 3757.4 3779.7 3787.1 3795.6 3799.0 3712.0 3719.3 3755.9 3757.9 3759.5 3546.1 3740.3 3758.0 3758.7 3765.3 3207.0 3228.8 3236.5 3246.1 3253.2 4962.9 5026.8 5033.2 5033.5 5040.2 3951.0 4226.6 4277.9 4287.1 4297.0 5249.6 5249.9 5264.9 5266.6 5285.9 3301.4 3429.2 3446.8 3474.3 3498.1 5514.5 5515.1 5537.2 5550.9 5552.8 3543.9 3545.7 3553.3 3565.5 3569.2 3596.7 3596.9 3604.9 3606.1 3606.5 4252.3 4267.5 4267.7 4283.3 4284.1 5139.9 5310.9 5324.1 5334.6 5355.3 1224.4 1249.9 1250.3 1253.7 1255.7 747.8 766.2 771.7 774.3 782.4 4543.6 4575.4 4585.3 4586.2 4588.6 4827.9 4835.5 4848.3 4864.4 4876.8 4556.0 4567.9 4849.4 4991.6 5010.4 2651.6 2842.7 2866.3 2871.9 2872.7 5732.1 5740.6 5742.4 5747.1 5750.5 5516.3 5543.2 5559.9 5613.3 5615.6 5541.7 5713.3 5721.8 5729.5 5736.1 5387.7 5408.9 5479.0 5486.6 5496.5 5398.2 5415.4 5435.3 5443.9 5451.9 5682.9 5725.0 5728.0 5744.6 5744.9 3377.4 3552.4 3570.9 3574.3 3577.6 5477.3 5487.9 5494.0 5495.3 5497.6 5305.0 5317.6 5329.4 5329.5 5334.3 5310.7 5321.0 5336.7 5344.4 5351.9 4972.3 5116.3 5170.3 5188.1 5208.0 5164.5 5274.3 5309.8 5313.4 5340.8 5156.2 5228.6 5277.8 5280.3 5302.6 06:27:04 INFO - PROCESS | 2920 | 06:27:04 INFO - PROCESS | 2920 | [#5] yui.html Cycles:180 Average:20546.42 Median:2221.34 stddev:29838.14 (1343.2%) stddev-sans-first:29884.34 06:27:04 INFO - PROCESS | 2920 | Values: 623.7 628.5 680.5 682.8 702.9 13911.4 14687.3 15123.8 15197.2 15679.1 14048.9 14158.5 14251.6 14264.4 14265.4 504.2 639.9 654.9 655.3 658.0 532.8 641.2 647.7 648.5 659.0 678.6 686.4 691.0 691.5 697.0 543.3 671.4 671.5 675.9 683.5 395.9 403.0 406.1 488.7 493.7 13511.9 13690.1 14001.6 14050.8 14078.5 12616.3 12817.3 12879.6 13250.5 13361.6 12788.2 12953.4 13017.7 13144.9 13278.1 4237.1 4296.5 4374.7 4379.2 4389.7 70068.6 70605.9 83356.0 85249.6 85363.1 10503.2 11023.4 11065.4 11075.6 11097.4 19941.3 20010.5 20168.9 20286.4 20320.5 7739.5 7803.5 7812.1 7886.4 8056.7 3989.4 4005.6 4021.8 4033.4 4036.5 2966.2 2995.1 3004.9 3019.2 3051.0 547.8 551.3 559.0 560.8 561.1 609.0 614.1 695.2 708.4 714.7 9317.4 9709.2 9910.5 10117.7 10171.8 552.4 628.9 654.0 690.3 694.3 4724.8 4735.7 4863.6 4870.8 4894.5 105047.5 109369.5 109630.0 111069.0 112365.4 97214.5 98055.0 100466.0 100892.0 100925.0 89356.3 89549.1 89885.6 89978.6 91476.0 8789.3 9014.2 9224.2 9276.2 9508.6 49704.3 50121.2 50176.7 51044.0 51537.7 68460.7 73330.0 73623.0 73713.6 73786.9 11833.5 11984.1 12048.8 12121.5 12232.8 13305.3 13341.5 13456.5 13688.7 14419.9 12277.0 12560.7 12714.2 13120.7 13181.9 9406.6 13791.7 14186.5 14197.4 14505.9 13604.7 13810.9 13834.2 14083.2 14142.0 11375.5 11928.8 12239.4 12337.5 12485.0 12078.6 12178.8 12239.4 12309.8 12386.0 06:27:04 INFO - PROCESS | 2920 | -------- Summary: end -------- 06:27:04 INFO - PROCESS | 2920 | 06:27:05 INFO - TEST-INFO | 2920: exit 0 06:27:05 INFO - TEST-OK | dromaeo_css | took 1119817ms 06:27:05 INFO - TEST-START | kraken 06:27:05 INFO - operating with platform_type : w7_ 06:27:05 INFO - Initialising browser for kraken test... 06:27:05 INFO - TEST-INFO | started process 3536 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjffusz\profile http://localhost:49738/getInfo.html) 06:27:10 INFO - PROCESS | 3536 | __metrics Screen width/height:1600/1200 06:27:10 INFO - PROCESS | 3536 | colorDepth:24 06:27:10 INFO - PROCESS | 3536 | Browser inner width/height: 1010/674 06:27:10 INFO - PROCESS | 3536 | __metrics 06:27:10 INFO - PROCESS | 3536 | 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" 06:27:10 INFO - PROCESS | 3536 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:27:11 INFO - PROCESS | 3536 | console.error: 06:27:11 INFO - PROCESS | 3536 | Message: Error: Connection closed before committing the transaction. 06:27:11 INFO - PROCESS | 3536 | Stack: 06:27:11 INFO - PROCESS | 3536 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 06:27:11 INFO - PROCESS | 3536 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:27:11 INFO - PROCESS | 3536 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:27:11 INFO - PROCESS | 3536 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:27:11 INFO - PROCESS | 3536 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:27:11 INFO - PROCESS | 3536 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 06:27:11 INFO - PROCESS | 3536 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:27:11 INFO - PROCESS | 3536 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 06:27:11 INFO - PROCESS | 3536 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 06:27:11 INFO - PROCESS | 3536 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 06:27:11 INFO - PROCESS | 3536 | Barrier/this.client.addBlocker/promise13.2% 06:28:08 INFO - TinderboxPrint: I/O read bytes / time
98,064,384 / 1,046,160 06:28:08 INFO - TinderboxPrint: I/O write bytes / time
482,414,080 / 3,912,880 06:28:08 INFO - TinderboxPrint: CPU idle
8,245.4 (86.8%) 06:28:08 INFO - TinderboxPrint: CPU user
1,212.1 (12.8%) 06:28:08 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 34055168; Read time: 0; Write time: 100150 06:28:08 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 98064384; Write bytes: 409555968; Read time: 1046160; Write time: 3656650 06:28:09 INFO - Running post-run listener: _upload_blobber_files 06:28:09 INFO - Blob upload gear active. 06:28:09 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 06:28:09 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 06:28:09 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'] 06:28:09 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 06:28:10 INFO - (blobuploader) - INFO - Open directory for files ... 06:28:10 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 06:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:10 INFO - (blobuploader) - INFO - Done attempting. 06:28:10 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 06:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:11 INFO - (blobuploader) - INFO - Done attempting. 06:28:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 06:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:28:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:19 INFO - (blobuploader) - INFO - Done attempting. 06:28:19 INFO - (blobuploader) - INFO - Iteration through files over. 06:28:19 INFO - Return code: 0 06:28:19 INFO - rmtree: C:\slave\test\build\uploaded_files.json 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 06:28:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/730fd6a2fce3fc0576b4fdcbe3b9907ee64e6b5ac5b92ae5d6ce61a3b05b3bb7af0b2441ecbb8e5816845c8c970ebbff791644303fc88d5d73e1d02e55aa18c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8471e36308f06e5afa346abfdaa43bb055468cdad449cd142c4fdc1daaf2006b09d6de57e4210d3d4b425f7bb350b5cbac55c6046cd9eaf98369c4770d80fee2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/48fa7f74a674fc90e305e0f3af86a07a94e64a93c257cc9237e522e7846dae562474abdab62bbcf758aa9564baa7aa039d4785f94173608ba2571e86100d3509"} 06:28:19 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 06:28:19 INFO - Writing to file C:\slave\test\properties\blobber_files 06:28:19 INFO - Contents: 06:28:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/730fd6a2fce3fc0576b4fdcbe3b9907ee64e6b5ac5b92ae5d6ce61a3b05b3bb7af0b2441ecbb8e5816845c8c970ebbff791644303fc88d5d73e1d02e55aa18c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8471e36308f06e5afa346abfdaa43bb055468cdad449cd142c4fdc1daaf2006b09d6de57e4210d3d4b425f7bb350b5cbac55c6046cd9eaf98369c4770d80fee2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/48fa7f74a674fc90e305e0f3af86a07a94e64a93c257cc9237e522e7846dae562474abdab62bbcf758aa9564baa7aa039d4785f94173608ba2571e86100d3509"} 06:28:19 INFO - Running post-run listener: copy_logs_to_upload_dir 06:28:19 INFO - Copying logs to upload dir... 06:28:19 INFO - mkdir: C:\slave\test\build\upload\logs 06:28:19 INFO - Copying logs to upload dir... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... 06:28:19 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1444.768000 ========= master_lag: 3.08 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 7 secs) (at 2016-10-12 06:28:22.121616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 06:28:22.122402) ========= '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-147 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-147 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-147 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/730fd6a2fce3fc0576b4fdcbe3b9907ee64e6b5ac5b92ae5d6ce61a3b05b3bb7af0b2441ecbb8e5816845c8c970ebbff791644303fc88d5d73e1d02e55aa18c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8471e36308f06e5afa346abfdaa43bb055468cdad449cd142c4fdc1daaf2006b09d6de57e4210d3d4b425f7bb350b5cbac55c6046cd9eaf98369c4770d80fee2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/48fa7f74a674fc90e305e0f3af86a07a94e64a93c257cc9237e522e7846dae562474abdab62bbcf758aa9564baa7aa039d4785f94173608ba2571e86100d3509"} build_url:https://queue.taskcluster.net/v1/task/SheMfBEST_i-iJNzdDpegA/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/SheMfBEST_i-iJNzdDpegA/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/730fd6a2fce3fc0576b4fdcbe3b9907ee64e6b5ac5b92ae5d6ce61a3b05b3bb7af0b2441ecbb8e5816845c8c970ebbff791644303fc88d5d73e1d02e55aa18c3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8471e36308f06e5afa346abfdaa43bb055468cdad449cd142c4fdc1daaf2006b09d6de57e4210d3d4b425f7bb350b5cbac55c6046cd9eaf98369c4770d80fee2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/48fa7f74a674fc90e305e0f3af86a07a94e64a93c257cc9237e522e7846dae562474abdab62bbcf758aa9564baa7aa039d4785f94173608ba2571e86100d3509"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 06:28:22.240549) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 06:28:22.240903) ========= '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-147 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-147 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-147 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-12 06:28:22.354070) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-12 06:28:22.354408) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-12 06:28:22.878843) ========= ========= Total master_lag: 5.44 =========