builder: larch_win7_ix_test-dromaeojs slave: t-w732-ix-013 starttime: 1478030363.4 results: success (0) buildid: 20161101094706 builduid: c4159b0d2f15445fb3a19f72b8a8304f revision: cd036958a55340fe0d82775162cbd1bd7973331e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:23.404856) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:23.405278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:23.405562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:23.426543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:23.426835) ========= '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-013 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-013 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-013 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 --12:59:23-- 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% 20.16 MB/s 12:59:24 (20.16 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.722000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:24.161361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-11-01 12:59:24.161671) ========= '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-013 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-013 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-013 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.504000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2016-11-01 12:59:41.673539) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-01 12:59:41.673880) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo projects/larch --rev cd036958a55340fe0d82775162cbd1bd7973331e --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev cd036958a55340fe0d82775162cbd1bd7973331e --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-013 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-013 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-013 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-01 12:59:42,381 truncating revision to first 12 chars 2016-11-01 12:59:42,381 Setting DEBUG logging. 2016-11-01 12:59:42,381 attempt 1/10 2016-11-01 12:59:42,381 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/cd036958a553?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-01 12:59:43,270 unpacking tar archive at: larch-cd036958a553/testing/mozharness/ 2016-11-01 12:59:43,505 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.147000 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-01 12:59:43.956414) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:43.956732) ========= script_repo_revision: cd036958a55340fe0d82775162cbd1bd7973331e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:43.957102) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:43.957424) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 12:59:43.967027) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 37 secs) (at 2016-11-01 12:59:43.967291) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Larch-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Larch-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-013 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-013 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-013 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 12:59:44 INFO - MultiFileLogger online at 20161101 12:59:44 in C:\slave\test 12:59:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch-Non-PGO 12:59:44 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 12:59:44 INFO - {'append_to_log': False, 12:59:44 INFO - 'base_work_dir': 'C:\\slave\\test', 12:59:44 INFO - 'blob_upload_branch': 'Larch-Non-PGO', 12:59:44 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 12:59:44 INFO - 'branch': 'Larch-Non-PGO', 12:59:44 INFO - 'buildbot_json_path': 'buildprops.json', 12:59:44 INFO - 'config_files': ('talos/windows_config.py',), 12:59:44 INFO - 'default_actions': ('clobber', 12:59:44 INFO - 'read-buildbot-config', 12:59:44 INFO - 'download-and-extract', 12:59:44 INFO - 'populate-webroot', 12:59:44 INFO - 'create-virtualenv', 12:59:44 INFO - 'install', 12:59:44 INFO - 'run-tests'), 12:59:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:59:44 INFO - 'download_minidump_stackwalk': True, 12:59:44 INFO - 'download_symbols': 'ondemand', 12:59:44 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 12:59:44 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 12:59:44 INFO - 'hg': 'c:/mozilla-build/hg/hg', 12:59:44 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 12:59:44 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 12:59:44 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 12:59:44 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 12:59:44 INFO - 'C:/mozilla-build/tooltool.py'), 12:59:44 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 12:59:44 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 12:59:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:59:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:59:44 INFO - 'installer_path': 'installer.exe', 12:59:44 INFO - 'log_level': 'info', 12:59:44 INFO - 'log_name': 'talos', 12:59:44 INFO - 'log_to_console': True, 12:59:44 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 12:59:44 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 12:59:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 12:59:44 INFO - 'opt_config_files': (), 12:59:44 INFO - 'pip_index': False, 12:59:44 INFO - 'sps_profile': False, 12:59:44 INFO - 'sps_profile_interval': 0, 12:59:44 INFO - 'suite': 'dromaeojs', 12:59:44 INFO - 'system_bits': '32', 12:59:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:59:44 INFO - 'title': 'T-W732-IX-013', 12:59:44 INFO - 'use_talos_json': True, 12:59:44 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 12:59:44 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 12:59:44 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 12:59:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:59:44 INFO - 'work_dir': 'build'} 12:59:44 INFO - [mozharness: 2016-11-01 19:59:44.395000Z] Running clobber step. 12:59:44 INFO - Running pre-action listener: _resource_record_pre_action 12:59:44 INFO - Running main action method: clobber 12:59:44 INFO - rmtree: C:\slave\test\build 12:59:44 INFO - Using _rmtree_windows ... 12:59:44 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 13:01:12 INFO - Running post-action listener: _resource_record_post_action 13:01:12 INFO - [mozharness: 2016-11-01 20:01:12.554000Z] Finished clobber step (success) 13:01:12 INFO - [mozharness: 2016-11-01 20:01:12.554000Z] Running read-buildbot-config step. 13:01:12 INFO - Running pre-action listener: _resource_record_pre_action 13:01:12 INFO - Running main action method: read_buildbot_config 13:01:12 INFO - Using buildbot properties: 13:01:12 INFO - { 13:01:12 INFO - "project": "", 13:01:12 INFO - "product": "firefox", 13:01:12 INFO - "script_repo_revision": "production", 13:01:12 INFO - "builddir": "larch_win7_ix_test-dromaeojs", 13:01:12 INFO - "repository": "", 13:01:12 INFO - "buildername": "Windows 7 32-bit larch talos dromaeojs", 13:01:12 INFO - "buildid": "20161101094706", 13:01:12 INFO - "pgo_build": "False", 13:01:12 INFO - "basedir": "C:\\slave\\test", 13:01:12 INFO - "buildnumber": 1, 13:01:12 INFO - "slavename": "t-w732-ix-013", 13:01:12 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 13:01:12 INFO - "platform": "win7_ix", 13:01:12 INFO - "scheduler": "tests-larch-win32-talos", 13:01:12 INFO - "branch": "larch", 13:01:12 INFO - "repo_path": "projects/larch", 13:01:12 INFO - "revision": "cd036958a55340fe0d82775162cbd1bd7973331e", 13:01:12 INFO - "stage_platform": "win32", 13:01:12 INFO - "builduid": "c4159b0d2f15445fb3a19f72b8a8304f", 13:01:12 INFO - "slavebuilddir": "test" 13:01:12 INFO - } 13:01:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 13:01:12 INFO - Running post-action listener: _resource_record_post_action 13:01:12 INFO - [mozharness: 2016-11-01 20:01:12.558000Z] Finished read-buildbot-config step (success) 13:01:12 INFO - [mozharness: 2016-11-01 20:01:12.558000Z] Running download-and-extract step. 13:01:12 INFO - Running pre-action listener: _resource_record_pre_action 13:01:12 INFO - Running main action method: download_and_extract 13:01:12 INFO - mkdir: C:\slave\test\build\tests 13:01: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')]} 13:01:12 INFO - trying https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 13:01:12 INFO - Downloading https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/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 13:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/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 13:01:13 INFO - Downloaded 1274 bytes. 13:01:13 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 13:01:13 INFO - Using the following test package requirements: 13:01:13 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 13:01:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 13:01:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 13:01:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'jsshell-win32.zip'], 13:01:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 13:01:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 13:01:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 13:01:13 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 13:01:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 13:01:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 13:01:13 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 13:01:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 13:01:13 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 13:01:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 13:01:13 INFO - Fetch https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 13:01:14 INFO - Http code: 200 13:01:14 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 13:01:14 INFO - x-amz-cf-id: saVB2_EKTEUXPoliHcRdmGrxkAAz823T9MVvIYIylV0bf6fciD4xNw== 13:01:14 INFO - x-amz-version-id: db0MKM4nNlYuDqBERoIGmHusoPbkksSW 13:01:14 INFO - Expected file size: 17161671 13:01:14 INFO - Obtained file size: 17161671 13:01:50 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 13:01:50 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 13:01:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 13:01:50 INFO - Fetch https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 13:01:50 INFO - Http code: 200 13:01:50 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 13:01:50 INFO - x-amz-cf-id: 3SfliAF_pSP2ZTXHMyWE9a04An2mjojT5LQu339qBCZ0nxGo-mV2TQ== 13:01:50 INFO - x-amz-version-id: db0MKM4nNlYuDqBERoIGmHusoPbkksSW 13:01:51 INFO - Expected file size: 17161671 13:01:51 INFO - Obtained file size: 17161671 13:02:03 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 13:02:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 13:02:03 INFO - Fetch https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 13:02:03 INFO - Http code: 200 13:02:03 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 13:02:03 INFO - x-amz-cf-id: bCsZfcDp9-Nei2XfMPPKIUFFyfthqYFcxOtXTWePQT4yNSNDQjfMdw== 13:02:03 INFO - x-amz-version-id: rpPnMJcivmYNr5QjZ5tC_T85oI3vuQDo 13:02:03 INFO - Expected file size: 14409038 13:02:03 INFO - Obtained file size: 14409038 13:02:07 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')]} 13:02:07 INFO - trying https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:02:07 INFO - Downloading https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 13:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/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 13:02:10 INFO - Downloaded 53109748 bytes. 13:02:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:02:10 INFO - mkdir: C:\slave\test\properties 13:02:10 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 13:02:10 INFO - Writing to file C:\slave\test\properties\build_url 13:02:10 INFO - Contents: 13:02:10 INFO - build_url:https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 13:02:11 INFO - Running post-action listener: _resource_record_post_action 13:02:11 INFO - Running post-action listener: set_extra_try_arguments 13:02:11 INFO - [mozharness: 2016-11-01 20:02:11.422000Z] Finished download-and-extract step (success) 13:02:11 INFO - [mozharness: 2016-11-01 20:02:11.422000Z] Running populate-webroot step. 13:02:11 INFO - Running pre-action listener: _resource_record_pre_action 13:02:11 INFO - Running main action method: populate_webroot 13:02:11 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 13:02:11 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 13:02:11 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 13:02:11 INFO - u'--tppagecycles', 13:02:11 INFO - u'7'], 13:02:11 INFO - u'tests': [u'tsvgm']}}, 13:02:11 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 13:02:11 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 13:02:11 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 13:02:11 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 13:02:11 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 13:02:11 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 13:02:11 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'damp', u'tps']}, 13:02:11 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'tests': [u'damp', u'tps']}, 13:02:11 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'dromaeo_dom']}, 13:02:11 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 13:02:11 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'basic_compositor_video']}, 13:02:11 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 13:02:11 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'other-e10s': {u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'a11yr', 13:02:11 INFO - u'ts_paint', 13:02:11 INFO - u'tpaint', 13:02:11 INFO - u'sessionrestore', 13:02:11 INFO - u'sessionrestore_no_auto_restore', 13:02:11 INFO - u'tabpaint']}, 13:02:11 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'tsvgx', 13:02:11 INFO - u'tsvgr_opacity', 13:02:11 INFO - u'tart', 13:02:11 INFO - u'tscrollx', 13:02:11 INFO - u'cart']}, 13:02:11 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 13:02:11 INFO - u'tsvgr_opacity', 13:02:11 INFO - u'tart', 13:02:11 INFO - u'tscrollx', 13:02:11 INFO - u'cart']}, 13:02:11 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'talos_options': [u'--disable-e10s'], 13:02:11 INFO - u'tests': [u'tp5o']}, 13:02:11 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'tests': [u'tp5o']}, 13:02:11 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'talos_options': [u'--disable-e10s', 13:02:11 INFO - u'--xperf_path', 13:02:11 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:02:11 INFO - u'tests': [u'tp5n']}, 13:02:11 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:02:11 INFO - u'talos_options': [u'--xperf_path', 13:02:11 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:02:11 INFO - u'tests': [u'tp5n']}}, 13:02:11 INFO - u'talos.zip': {u'path': u'', 13:02:11 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:02:11 INFO - Running post-action listener: _resource_record_post_action 13:02:11 INFO - [mozharness: 2016-11-01 20:02:11.504000Z] Finished populate-webroot step (success) 13:02:11 INFO - [mozharness: 2016-11-01 20:02:11.504000Z] Running create-virtualenv step. 13:02:11 INFO - Running pre-action listener: _resource_record_pre_action 13:02:11 INFO - Running main action method: create_virtualenv 13:02:11 INFO - Creating virtualenv C:\slave\test\build/venv 13:02:11 INFO - mkdir: C:\slave\test\build\venv\Scripts 13:02:11 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 13:02:11 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'! 13:02:11 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 13:02:11 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 13:02:20 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 13:02:26 INFO - Installing distribute......................................................................................................................................................................................done. 13:02:27 INFO - Return code: 0 13:02:27 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 13:02:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 13:02:28 INFO - Reading from file tmpfile_stdout 13:02:28 INFO - Output received: 13:02:28 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 13:02:28 INFO - Using _rmtree_windows ... 13:02:28 INFO - Using _rmtree_windows ... 13:02:28 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 13:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:28 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:28 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 13:02:28 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 13:02:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:28 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:28 INFO - 'DCLOCATION': 'SCL3', 13:02:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:28 INFO - 'HOMEDRIVE': 'C:', 13:02:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:28 INFO - 'KTS_VERSION': '1.19c', 13:02:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:28 INFO - 'MOZ_NO_REMOTE': '1', 13:02:28 INFO - 'NO_EM_RESTART': '1', 13:02:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:28 INFO - 'OS': 'Windows_NT', 13:02:28 INFO - 'OURDRIVE': 'C:', 13:02:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:02:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:28 INFO - 'PROCESSOR_LEVEL': '6', 13:02:28 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:28 INFO - 'PROMPT': '$P$G', 13:02:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:28 INFO - 'PWD': 'C:\\slave\\test', 13:02:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:28 INFO - 'SYSTEMDRIVE': 'C:', 13:02:28 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:28 INFO - 'TEST1': 'testie', 13:02:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:28 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:28 INFO - 'USERNAME': 'cltbld', 13:02:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:28 INFO - 'WINDIR': 'C:\\windows', 13:02:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:29 INFO - Ignoring indexes: http://pypi.python.org/simple/ 13:02:29 INFO - Downloading/unpacking pip>=1.5 13:02:33 INFO - Running setup.py egg_info for package pip 13:02:33 INFO - warning: no files found matching 'pip\cacert.pem' 13:02:33 INFO - warning: no files found matching '*.html' under directory 'docs' 13:02:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 13:02:33 INFO - no previously-included directories found matching 'docs\_build\_sources' 13:02:33 INFO - Installing collected packages: pip 13:02:33 INFO - Found existing installation: pip 0.8.2 13:02:33 INFO - Uninstalling pip: 13:02:33 INFO - Successfully uninstalled pip 13:02:33 INFO - Running setup.py install for pip 13:02:33 INFO - warning: no files found matching 'pip\cacert.pem' 13:02:33 INFO - warning: no files found matching '*.html' under directory 'docs' 13:02:33 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 13:02:33 INFO - no previously-included directories found matching 'docs\_build\_sources' 13:02:33 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 13:02:33 INFO - Successfully installed pip 13:02:33 INFO - Cleaning up... 13:02:33 INFO - Return code: 0 13:02:33 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 13:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:33 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 13:02:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:02:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:33 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:33 INFO - 'DCLOCATION': 'SCL3', 13:02:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:33 INFO - 'HOMEDRIVE': 'C:', 13:02:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:33 INFO - 'KTS_VERSION': '1.19c', 13:02:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:33 INFO - 'MOZ_NO_REMOTE': '1', 13:02:33 INFO - 'NO_EM_RESTART': '1', 13:02:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:33 INFO - 'OS': 'Windows_NT', 13:02:33 INFO - 'OURDRIVE': 'C:', 13:02:33 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:02:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:33 INFO - 'PROCESSOR_LEVEL': '6', 13:02:33 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:33 INFO - 'PROMPT': '$P$G', 13:02:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:33 INFO - 'PWD': 'C:\\slave\\test', 13:02:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:33 INFO - 'SYSTEMDRIVE': 'C:', 13:02:33 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:33 INFO - 'TEST1': 'testie', 13:02:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:33 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:33 INFO - 'USERNAME': 'cltbld', 13:02:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:33 INFO - 'WINDIR': 'C:\\windows', 13:02:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:35 INFO - Downloading/unpacking psutil>=3.1.1 13:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:36 INFO - Installing collected packages: psutil 13:02:36 INFO - Successfully installed psutil 13:02:36 INFO - Cleaning up... 13:02:37 INFO - Return code: 0 13:02:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 13:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:37 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:37 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 13:02:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:02:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:37 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:37 INFO - 'DCLOCATION': 'SCL3', 13:02:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:37 INFO - 'HOMEDRIVE': 'C:', 13:02:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:37 INFO - 'KTS_VERSION': '1.19c', 13:02:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:37 INFO - 'MOZ_NO_REMOTE': '1', 13:02:37 INFO - 'NO_EM_RESTART': '1', 13:02:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:37 INFO - 'OS': 'Windows_NT', 13:02:37 INFO - 'OURDRIVE': 'C:', 13:02:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:02:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:37 INFO - 'PROCESSOR_LEVEL': '6', 13:02:37 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:37 INFO - 'PROMPT': '$P$G', 13:02:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:37 INFO - 'PWD': 'C:\\slave\\test', 13:02:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:37 INFO - 'SYSTEMDRIVE': 'C:', 13:02:37 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:37 INFO - 'TEST1': 'testie', 13:02:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:37 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:37 INFO - 'USERNAME': 'cltbld', 13:02:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:37 INFO - 'WINDIR': 'C:\\windows', 13:02:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:02:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 13:02:38 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) 13:02:38 INFO - Installing collected packages: mozsystemmonitor 13:02:38 INFO - Running setup.py install for mozsystemmonitor 13:02:38 INFO - Successfully installed mozsystemmonitor 13:02:38 INFO - Cleaning up... 13:02:39 INFO - Return code: 0 13:02:39 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 13:02:39 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')]} 13:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:39 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')]} 13:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:39 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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:39 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 13:02:39 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 13:02:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:39 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:39 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:39 INFO - 'DCLOCATION': 'SCL3', 13:02:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:39 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:39 INFO - 'HOMEDRIVE': 'C:', 13:02:39 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:39 INFO - 'KTS_VERSION': '1.19c', 13:02:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:39 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:39 INFO - 'MOZ_NO_REMOTE': '1', 13:02:39 INFO - 'NO_EM_RESTART': '1', 13:02:39 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:39 INFO - 'OS': 'Windows_NT', 13:02:39 INFO - 'OURDRIVE': 'C:', 13:02:39 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;', 13:02:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:39 INFO - 'PROCESSOR_LEVEL': '6', 13:02:39 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:39 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:39 INFO - 'PROMPT': '$P$G', 13:02:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:39 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:39 INFO - 'PWD': 'C:\\slave\\test', 13:02:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:39 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:39 INFO - 'SYSTEMDRIVE': 'C:', 13:02:39 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:39 INFO - 'TEST1': 'testie', 13:02:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:39 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:39 INFO - 'USERNAME': 'cltbld', 13:02:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:39 INFO - 'WINDIR': 'C:\\windows', 13:02:39 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:39 INFO - Downloading/unpacking jsonschema==2.5.1 13:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:02:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:02:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:02:40 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 13:02:40 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 13:02:40 INFO - Downloading functools32-3.2.3-2.tar.gz 13:02:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 13:02:41 INFO - warning: no files found matching '*.txt' 13:02:41 INFO - no previously-included directories found matching 'build' 13:02:41 INFO - no previously-included directories found matching 'dist' 13:02:41 INFO - no previously-included directories found matching '.git*' 13:02:41 INFO - Installing collected packages: jsonschema, functools32 13:02:41 INFO - Running setup.py install for functools32 13:02:41 INFO - warning: no files found matching '*.txt' 13:02:41 INFO - no previously-included directories found matching 'build' 13:02:41 INFO - no previously-included directories found matching 'dist' 13:02:41 INFO - no previously-included directories found matching '.git*' 13:02:41 INFO - Successfully installed jsonschema functools32 13:02:41 INFO - Cleaning up... 13:02:41 INFO - Return code: 0 13:02:41 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 13:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:41 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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:41 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 13:02:41 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 13:02:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:41 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:41 INFO - 'DCLOCATION': 'SCL3', 13:02:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:41 INFO - 'HOMEDRIVE': 'C:', 13:02:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:41 INFO - 'KTS_VERSION': '1.19c', 13:02:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:41 INFO - 'MOZ_NO_REMOTE': '1', 13:02:41 INFO - 'NO_EM_RESTART': '1', 13:02:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:41 INFO - 'OS': 'Windows_NT', 13:02:41 INFO - 'OURDRIVE': 'C:', 13:02:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:02:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:41 INFO - 'PROCESSOR_LEVEL': '6', 13:02:41 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:41 INFO - 'PROMPT': '$P$G', 13:02:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:41 INFO - 'PWD': 'C:\\slave\\test', 13:02:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:41 INFO - 'SYSTEMDRIVE': 'C:', 13:02:41 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:41 INFO - 'TEST1': 'testie', 13:02:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:41 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:41 INFO - 'USERNAME': 'cltbld', 13:02:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:41 INFO - 'WINDIR': 'C:\\windows', 13:02:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 13:02:42 INFO - Cleaning up... 13:02:42 INFO - Return code: 0 13:02:42 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 13:02: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')]} 13:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02: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')]} 13:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02: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', '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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02: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', 'blobuploader==1.2.4'] in C:\slave\test\build 13:02: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 blobuploader==1.2.4 13:02:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:42 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:42 INFO - 'DCLOCATION': 'SCL3', 13:02:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:42 INFO - 'HOMEDRIVE': 'C:', 13:02:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:42 INFO - 'KTS_VERSION': '1.19c', 13:02:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:42 INFO - 'MOZ_NO_REMOTE': '1', 13:02:42 INFO - 'NO_EM_RESTART': '1', 13:02:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:42 INFO - 'OS': 'Windows_NT', 13:02:42 INFO - 'OURDRIVE': 'C:', 13:02: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;', 13:02:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:42 INFO - 'PROCESSOR_LEVEL': '6', 13:02:42 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:42 INFO - 'PROMPT': '$P$G', 13:02:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:42 INFO - 'PWD': 'C:\\slave\\test', 13:02:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:42 INFO - 'SYSTEMDRIVE': 'C:', 13:02:42 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:42 INFO - 'TEST1': 'testie', 13:02:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:42 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:42 INFO - 'USERNAME': 'cltbld', 13:02:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:42 INFO - 'WINDIR': 'C:\\windows', 13:02:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:02:42 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 13:02:42 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 13:02:43 INFO - Downloading blobuploader-1.2.4.tar.gz 13:02:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 13:02:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:02:43 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 13:02:43 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 13:02:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 13:02:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:02:44 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 13:02:44 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 13:02:44 INFO - Downloading docopt-0.6.1.tar.gz 13:02:44 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 13:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 13:02:44 INFO - Running setup.py install for blobuploader 13:02:44 INFO - Running setup.py install for requests 13:02:45 INFO - Running setup.py install for docopt 13:02:45 INFO - Successfully installed blobuploader requests docopt 13:02:45 INFO - Cleaning up... 13:02:47 INFO - Return code: 0 13:02:47 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:02: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')]} 13:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02: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')]} 13:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:47 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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:47 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 13:02:47 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 13:02:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:47 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:47 INFO - 'DCLOCATION': 'SCL3', 13:02:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:47 INFO - 'HOMEDRIVE': 'C:', 13:02:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:47 INFO - 'KTS_VERSION': '1.19c', 13:02:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:47 INFO - 'MOZ_NO_REMOTE': '1', 13:02:47 INFO - 'NO_EM_RESTART': '1', 13:02:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:47 INFO - 'OS': 'Windows_NT', 13:02:47 INFO - 'OURDRIVE': 'C:', 13:02: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;', 13:02:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:47 INFO - 'PROCESSOR_LEVEL': '6', 13:02:47 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:47 INFO - 'PROMPT': '$P$G', 13:02:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:47 INFO - 'PWD': 'C:\\slave\\test', 13:02:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:47 INFO - 'SYSTEMDRIVE': 'C:', 13:02:47 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:47 INFO - 'TEST1': 'testie', 13:02:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:47 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:47 INFO - 'USERNAME': 'cltbld', 13:02:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:47 INFO - 'WINDIR': 'C:\\windows', 13:02:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 13:02:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ia45rh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 13:02:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 13:02:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-foj7uh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 13:02:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 13:02:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d4k0l_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 13:02:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 13:02:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxm8hm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcuklj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3hvpdk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vodfap-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zjuw9b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wsc4cw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tane2p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 13:02:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 13:02:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9_trhn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-by_57r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zpfwom-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hjhh2n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jfidj4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcu3ef-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 13:02:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 13:02:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-head4e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 13:02:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:02:49 INFO - Running setup.py install for manifestparser 13:02:50 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 13:02:50 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 13:02:50 INFO - Running setup.py install for mozcrash 13:02:50 INFO - Running setup.py install for mozdebug 13:02:51 INFO - Running setup.py install for mozdevice 13:02:51 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 13:02:51 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 13:02:51 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 13:02:51 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 13:02:51 INFO - Running setup.py install for mozfile 13:02:52 INFO - Running setup.py install for mozhttpd 13:02:52 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Running setup.py install for mozinfo 13:02:52 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Running setup.py install for mozInstall 13:02:52 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 13:02:52 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 13:02:53 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 13:02:53 INFO - Running setup.py install for mozleak 13:02:53 INFO - Running setup.py install for mozlog 13:02:53 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 13:02:53 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 13:02:54 INFO - Running setup.py install for moznetwork 13:02:54 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 13:02:54 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 13:02:54 INFO - Running setup.py install for mozprocess 13:02:55 INFO - Running setup.py install for mozprofile 13:02:55 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 13:02:55 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 13:02:55 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 13:02:55 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 13:02:55 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 13:02:55 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 13:02:56 INFO - Running setup.py install for mozrunner 13:02:56 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 13:02:56 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 13:02:56 INFO - Running setup.py install for mozscreenshot 13:02:57 INFO - Running setup.py install for moztest 13:02:57 INFO - Running setup.py install for mozversion 13:02:57 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 13:02:57 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 13:02:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:02:57 INFO - Cleaning up... 13:02:57 INFO - Return code: 0 13:02:57 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:02:57 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')]} 13:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:02:57 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')]} 13:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:02:57 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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:02:57 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 13:02:57 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 13:02:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:02:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:02:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:02:57 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:02:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:02:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:02:57 INFO - 'DCLOCATION': 'SCL3', 13:02:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:02:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:02:57 INFO - 'HOMEDRIVE': 'C:', 13:02:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:02:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:02:57 INFO - 'KTS_VERSION': '1.19c', 13:02:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:02:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:02:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:02:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:02:57 INFO - 'MOZ_NO_REMOTE': '1', 13:02:57 INFO - 'NO_EM_RESTART': '1', 13:02:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:02:57 INFO - 'OS': 'Windows_NT', 13:02:57 INFO - 'OURDRIVE': 'C:', 13:02:57 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;', 13:02:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:02:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:02:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:02:57 INFO - 'PROCESSOR_LEVEL': '6', 13:02:57 INFO - 'PROCESSOR_REVISION': '1e05', 13:02:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:02:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:02:57 INFO - 'PROMPT': '$P$G', 13:02:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:02:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:02:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:02:57 INFO - 'PWD': 'C:\\slave\\test', 13:02:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:02:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:02:57 INFO - 'SYSTEMDRIVE': 'C:', 13:02:57 INFO - 'SYSTEMROOT': 'C:\\windows', 13:02:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:57 INFO - 'TEST1': 'testie', 13:02:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:02:57 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:02:57 INFO - 'USERNAME': 'cltbld', 13:02:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:02:57 INFO - 'WINDIR': 'C:\\windows', 13:02:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:02:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-amgdpt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 13:02:58 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)) 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4rn_uc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 13:02:58 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)) 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g1hr7m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 13:02:58 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)) 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5cwfpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 13:02:58 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)) 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xearcs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 13:02:58 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)) 13:02:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 13:02:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n4inql-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcc2qd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xsfxxm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-czkzhm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3aq1n3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mbf2cp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 13:02:59 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)) 13:02:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 13:02:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dyrzgc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 13:03:00 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)) 13:03:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 13:03:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nvrzxd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 13:03:00 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)) 13:03:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 13:03:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sze7pu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 13:03:00 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)) 13:03:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 13:03:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ihv2ta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 13:03:00 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)) 13:03:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 13:03:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z8e2bn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 13:03:00 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)) 13:03:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 13:03:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eh1pul-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 13:03:00 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)) 13:03:00 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)) 13:03:00 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)) 13:03:00 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)) 13:03:00 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)) 13:03:00 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)) 13:03:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 13:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:03:01 INFO - Downloading blessings-1.6.tar.gz 13:03:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 13:03:02 INFO - Installing collected packages: blessings 13:03:02 INFO - Running setup.py install for blessings 13:03:02 INFO - Successfully installed blessings 13:03:02 INFO - Cleaning up... 13:03:05 INFO - Return code: 0 13:03:05 INFO - Done creating virtualenv C:\slave\test\build/venv. 13:03:05 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 13:03:05 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 13:03:05 INFO - Reading from file tmpfile_stdout 13:03:05 INFO - Using _rmtree_windows ... 13:03:05 INFO - Using _rmtree_windows ... 13:03:05 INFO - Current package versions: 13:03:05 INFO - blessings == 1.6 13:03:05 INFO - blobuploader == 1.2.4 13:03:05 INFO - docopt == 0.6.1 13:03:05 INFO - functools32 == 3.2.3-2 13:03:05 INFO - jsonschema == 2.5.1 13:03:05 INFO - manifestparser == 1.1 13:03:05 INFO - mozInstall == 1.12 13:03:05 INFO - mozcrash == 0.17 13:03:05 INFO - mozdebug == 0.1 13:03:05 INFO - mozdevice == 0.48 13:03:05 INFO - mozfile == 1.2 13:03:05 INFO - mozhttpd == 0.7 13:03:05 INFO - mozinfo == 0.9 13:03:05 INFO - mozleak == 0.1 13:03:05 INFO - mozlog == 3.4 13:03:05 INFO - moznetwork == 0.27 13:03:05 INFO - mozprocess == 0.23 13:03:05 INFO - mozprofile == 0.28 13:03:05 INFO - mozrunner == 6.12 13:03:05 INFO - mozscreenshot == 0.1 13:03:05 INFO - mozsystemmonitor == 0.3 13:03:05 INFO - moztest == 0.7 13:03:05 INFO - mozversion == 1.4 13:03:05 INFO - psutil == 3.1.1 13:03:05 INFO - requests == 1.2.3 13:03:05 INFO - Installing None into virtualenv C:\slave\test\build/venv 13:03:05 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')]} 13:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:05 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')]} 13:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:05 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 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:03:05 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 13:03:05 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 13:03:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:03:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:03:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:03:05 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:03:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:03:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:03:05 INFO - 'DCLOCATION': 'SCL3', 13:03:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:03:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:03:05 INFO - 'HOMEDRIVE': 'C:', 13:03:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:03:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:03:05 INFO - 'KTS_VERSION': '1.19c', 13:03:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:03:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:03:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:03:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:05 INFO - 'MOZ_NO_REMOTE': '1', 13:03:05 INFO - 'NO_EM_RESTART': '1', 13:03:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:03:05 INFO - 'OS': 'Windows_NT', 13:03:05 INFO - 'OURDRIVE': 'C:', 13:03:05 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;', 13:03:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:03:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:03:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:03:05 INFO - 'PROCESSOR_LEVEL': '6', 13:03:05 INFO - 'PROCESSOR_REVISION': '1e05', 13:03:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:03:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:03:05 INFO - 'PROMPT': '$P$G', 13:03:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:03:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:03:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:03:05 INFO - 'PWD': 'C:\\slave\\test', 13:03:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:03:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:03:05 INFO - 'SYSTEMDRIVE': 'C:', 13:03:05 INFO - 'SYSTEMROOT': 'C:\\windows', 13:03:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:05 INFO - 'TEST1': 'testie', 13:03:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:05 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:03:05 INFO - 'USERNAME': 'cltbld', 13:03:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:03:05 INFO - 'WINDIR': 'C:\\windows', 13:03:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:03:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 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)) 13:03:06 INFO - Cleaning up... 13:03:06 INFO - Return code: 0 13:03:06 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 13:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:03:06 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BF640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01862E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0131C410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-013', 'USERDOMAIN': 'T-W732-IX-013', '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-013', '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 13:03:06 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 13:03:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:03:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:03:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:03:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:03:06 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:03:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:03:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:03:06 INFO - 'DCLOCATION': 'SCL3', 13:03:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:03:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:03:06 INFO - 'HOMEDRIVE': 'C:', 13:03:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:03:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:03:06 INFO - 'KTS_VERSION': '1.19c', 13:03:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:03:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:03:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:03:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:06 INFO - 'MOZ_NO_REMOTE': '1', 13:03:06 INFO - 'NO_EM_RESTART': '1', 13:03:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:03:06 INFO - 'OS': 'Windows_NT', 13:03:06 INFO - 'OURDRIVE': 'C:', 13:03:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 13:03:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:03:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:03:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:03:06 INFO - 'PROCESSOR_LEVEL': '6', 13:03:06 INFO - 'PROCESSOR_REVISION': '1e05', 13:03:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:03:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:03:06 INFO - 'PROMPT': '$P$G', 13:03:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:03:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:03:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:03:06 INFO - 'PWD': 'C:\\slave\\test', 13:03:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:03:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:03:06 INFO - 'SYSTEMDRIVE': 'C:', 13:03:06 INFO - 'SYSTEMROOT': 'C:\\windows', 13:03:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:06 INFO - 'TEST1': 'testie', 13:03:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:06 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:03:06 INFO - 'USERNAME': 'cltbld', 13:03:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:03:06 INFO - 'WINDIR': 'C:\\windows', 13:03:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:03:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 13:03:06 INFO - Cleaning up... 13:03:06 INFO - Return code: 0 13:03:06 INFO - Running post-action listener: _resource_record_post_action 13:03:06 INFO - Running post-action listener: _start_resource_monitoring 13:03:06 INFO - Starting resource monitoring. 13:03:06 INFO - [mozharness: 2016-11-01 20:03:06.645000Z] Finished create-virtualenv step (success) 13:03:06 INFO - [mozharness: 2016-11-01 20:03:06.645000Z] Running install step. 13:03:06 INFO - Running pre-action listener: _resource_record_pre_action 13:03:06 INFO - Running main action method: install 13:03:06 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 13:03:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 13:03:06 INFO - Reading from file tmpfile_stdout 13:03:06 INFO - Using _rmtree_windows ... 13:03:06 INFO - Using _rmtree_windows ... 13:03:06 INFO - Detecting whether we're running mozinstall >=1.0... 13:03:06 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 13:03:06 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 13:03:07 INFO - Reading from file tmpfile_stdout 13:03:07 INFO - Output received: 13:03:07 INFO - Usage: mozinstall-script.py [options] installer 13:03:07 INFO - Options: 13:03:07 INFO - -h, --help show this help message and exit 13:03:07 INFO - -d DEST, --destination=DEST 13:03:07 INFO - Directory to install application into. [default: 13:03:07 INFO - "C:\slave\test"] 13:03:07 INFO - --app=APP Application being installed. [default: firefox] 13:03:07 INFO - Using _rmtree_windows ... 13:03:07 INFO - Using _rmtree_windows ... 13:03:07 INFO - mkdir: C:\slave\test\build\application 13:03:07 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'] 13:03:07 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 13:03:09 INFO - Reading from file tmpfile_stdout 13:03:09 INFO - Output received: 13:03:09 INFO - C:\slave\test\build\application\firefox\firefox.exe 13:03:09 INFO - Using _rmtree_windows ... 13:03:09 INFO - Using _rmtree_windows ... 13:03:09 INFO - Running post-action listener: _resource_record_post_action 13:03:09 INFO - [mozharness: 2016-11-01 20:03:09.225000Z] Finished install step (success) 13:03:09 INFO - [mozharness: 2016-11-01 20:03:09.225000Z] Running run-tests step. 13:03:09 INFO - Running pre-action listener: _resource_record_pre_action 13:03:09 INFO - Running main action method: run_tests 13:03:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 13:03:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 13:03:09 INFO - Python 2.7.3 13:03:09 INFO - Return code: 0 13:03:09 INFO - grabbing minidump binary from tooltool 13:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:03:09 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 0x0186FE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EDE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 13:03:09 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 13:03:09 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 13:03:09 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 13:03:09 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 13:03:09 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpxffwci 13:03:09 INFO - INFO - File integrity verified, renaming tmpxffwci to win32-minidump_stackwalk.exe 13:03:09 INFO - Return code: 0 13:03:09 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 13:03:09 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 13:03:09 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 13:03:09 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 13:03:09 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 13:03:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-013', '--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 13:03:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Larch-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-013 --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 13:03:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 13:03:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 13:03:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 13:03:09 INFO - 'COMPUTERNAME': 'T-W732-IX-013', 13:03:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 13:03:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 13:03:09 INFO - 'DCLOCATION': 'SCL3', 13:03:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 13:03:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 13:03:09 INFO - 'HOMEDRIVE': 'C:', 13:03:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 13:03:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 13:03:09 INFO - 'KTS_VERSION': '1.19c', 13:03:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 13:03:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-013', 13:03:09 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 13:03:09 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 13:03:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 13:03:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 13:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:03:09 INFO - 'MOZ_NO_REMOTE': '1', 13:03:09 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 13:03:09 INFO - 'NO_EM_RESTART': '1', 13:03:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 13:03:09 INFO - 'OS': 'Windows_NT', 13:03:09 INFO - 'OURDRIVE': 'C:', 13:03:09 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;', 13:03:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 13:03:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 13:03:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 13:03:09 INFO - 'PROCESSOR_LEVEL': '6', 13:03:09 INFO - 'PROCESSOR_REVISION': '1e05', 13:03:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 13:03:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 13:03:09 INFO - 'PROMPT': '$P$G', 13:03:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 13:03:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 13:03:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 13:03:09 INFO - 'PWD': 'C:\\slave\\test', 13:03:09 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 13:03:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 13:03:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 13:03:09 INFO - 'SYSTEMDRIVE': 'C:', 13:03:09 INFO - 'SYSTEMROOT': 'C:\\windows', 13:03:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:09 INFO - 'TEST1': 'testie', 13:03:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 13:03:09 INFO - 'USERDOMAIN': 'T-W732-IX-013', 13:03:09 INFO - 'USERNAME': 'cltbld', 13:03:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 13:03:09 INFO - 'WINDIR': 'C:\\windows', 13:03:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 13:03:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 13:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 13:03:09 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Larch-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-013', '--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 13:03:11 INFO - mozversion application_buildid: 20161101094706 13:03:11 INFO - mozversion application_changeset: cd036958a55340fe0d82775162cbd1bd7973331e 13:03:11 INFO - mozversion application_display_name: Nightly 13:03:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:03:11 INFO - mozversion application_name: Firefox 13:03:11 INFO - mozversion application_remotingname: firefox 13:03:11 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:03:11 INFO - mozversion application_vendor: Mozilla 13:03:11 INFO - mozversion application_version: 52.0a1 13:03:11 INFO - mozversion platform_buildid: 20161101094706 13:03:11 INFO - mozversion platform_changeset: cd036958a55340fe0d82775162cbd1bd7973331e 13:03:11 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:03:11 INFO - mozversion platform_version: 52.0a1 13:03:11 INFO - using testdate: 1478030591 13:03:11 INFO - actual date: 1478030591 13:03:11 INFO - starting webserver on 'localhost:49579' 13:03:11 INFO - SUITE-START | Running 2 tests 13:03:11 INFO - TEST-START | dromaeo_css 13:03:11 INFO - operating with platform_type : w7_ 13:03:11 INFO - Initialising browser for dromaeo_css test... 13:03:11 INFO - TEST-INFO | started process 4092 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxmcknd\profile http://localhost:49579/getInfo.html) 13:03:21 INFO - PROCESS | 4092 | __metrics Screen width/height:1600/1200 13:03:21 INFO - PROCESS | 4092 | colorDepth:24 13:03:21 INFO - PROCESS | 4092 | Browser inner width/height: 1010/674 13:03:21 INFO - PROCESS | 4092 | __metrics 13:03:22 INFO - PROCESS | 4092 | 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" 13:03:22 INFO - PROCESS | 4092 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:03:23 INFO - TEST-INFO | 4092: exit 0 13:03:23 INFO - Browser initialized. 13:03:23 INFO - Running cycle 1/1 for dromaeo_css test... 13:03:23 INFO - TEST-INFO | started process 3404 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpxmcknd\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:03:24 INFO - PROCESS | 3404 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 13:03:35 INFO - PROCESS | 3404 | RSS: Main: 141377536 13:03:35 INFO - PROCESS | 3404 | 13:04:26 INFO - PROCESS | 3404 | 1478030666847 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] 13:06:40 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49579/tests/dromaeo/cssquery-ext.html) 13:06:43 INFO - PROCESS | 3404 | RSS: Main: 267112448 13:06:43 INFO - PROCESS | 3404 | 13:09:46 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-ext.html (next: http://localhost:49579/tests/dromaeo/cssquery-jquery.html) 13:09:46 INFO - PROCESS | 3404 | RSS: Main: 204988416 13:09:46 INFO - PROCESS | 3404 | 13:12:49 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49579/tests/dromaeo/cssquery-mootools.html) 13:12:50 INFO - PROCESS | 3404 | RSS: Main: 160088064 13:12:50 INFO - PROCESS | 3404 | 13:15:52 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49579/tests/dromaeo/cssquery-prototype.html) 13:15:53 INFO - PROCESS | 3404 | RSS: Main: 162607104 13:15:53 INFO - PROCESS | 3404 | 13:18:56 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49579/tests/dromaeo/cssquery-yui.html) 13:18:56 INFO - PROCESS | 3404 | RSS: Main: 140914688 13:18:56 INFO - PROCESS | 3404 | 13:21:59 INFO - PROCESS | 3404 | Cycle 1(1): loaded http://localhost:49579/tests/dromaeo/cssquery-yui.html (next: http://localhost:49579/tests/dromaeo/cssquery-dojo.html) 13:21:59 INFO - PROCESS | 3404 | RSS: Main: 193835008 13:21:59 INFO - PROCESS | 3404 | 13:21:59 INFO - PROCESS | 3404 | __start_tp_report 13:21:59 INFO - PROCESS | 3404 | _x_x_mozilla_page_load 13:21:59 INFO - PROCESS | 3404 | _x_x_mozilla_page_load_details 13:21:59 INFO - PROCESS | 3404 | |i|pagename|runs| 13:21:59 INFO - PROCESS | 3404 | |0;dojo.html;2319.8956046977887;2353.5528249632575;2355.211004313555;2361.5985282501974;2364.3379853640986;3965.405189221618;3970.9873982134586;3982.263281292963;4069.145479449312;4073.1446396256783;3913.315169845273;3936.2914675358425;3985.3822657488063;3988.0229343810734;4074.5517993020817;3499.9650003499955;3514.9648503514895;3543.6279190684995;3547.0600290922926;3571.39286321326;3491.5635545556806;3498.685118339349;3499.0727457223857;3516.1736991806906;3531.5762108546883;3464.7574669773126;3494;3541.0793193769678;3543.2913417316513;3552.4671299305182;3429.2455659755065;3450.87921922734;3480.617132115465;3526.1008442847165;3526.982365088184;2483.788877945377;3444.9655503444897;3456.4469684850555;3505.631908649606;3513.4554144107706;3866.420036994445;3889.2804831106055;3960.702947278937;3976.701747368959;3984.183242435294;4061.4923134608175;4092.2224777292518;4186.037211441386;4219.07180420307;4221.050263690676;4148.709590328678;4161.812718427648;4191.685623578243;4200.180964711852;4210.789460526992;3878.2825177342283;3947.4276229947072;3967.0677390813426;3980.980095099506;3981.402789581586;4167.729097608646;4196.5803419658405;4273.252180868336;4298.849540266076;4307.117041006586;4023.2556976959354;4068;4077.0215148364773;4085.305498065336;4105.692073094529;4131.132462182915;4218.6625069994325;4230.259704551752;4237.406763053175;4238.067625122468;4121.196366708523;4193.832246710097;4195.475565554306;4215.662746980234;4215.915681686349;4050.432939388488;4110.383442483651;4119.134981653886;4211.284081706117;4218.578142185818;2894.4645240630553;2993.7006299369887;3002.1894088595955;3014.487537118695;3015.62304711911;2787.1777825541108;2792.1204820481485;2810.2271875234474;2812.774978001755;2816.6338376010985;3969.364901615728;3977.880663580097;3994.7003974702016;4005.5193376794973;4061.5126184858004;4116.341385378325;4139.110091330378;4174.8492249413985;4244.787760611957;4268.978655106766;3937.940930886039;3967.940480892731;4020.6984476164403;4023.4970628671417;4028.597140285948;3588.4258518636893;3614.367485689994;3659.652333028358;3660.926781464356;3666.4683620874825;4244.087521182837;4296.162248361539;4376.759278239728;4399.516053234206;4406.625436837905;4130.876073717794;4147.315692910756;4156.729812562174;4163.10493243954;4195.7063005590535;4217.34631132175;4294.0767734937135;4310.590493903074;4323.978380108079;4330.35044743291;4047.838086476626;4072.450219220367;4094.3039683253323;4113.773742444194;4122.196171746481;3935.7244992850224;4073.246449406869;4129.587041295847;4132.938005929854;4137.77242251679;3970.1067259867455;4041.7372870764484;4048.0487085535465;4048.0891799344913;4054.594540545922;3997.2205419942834;4010.9197816044098;4017.3371393720877;4058.7564746115327;4085.754854708727;3922.0783115968297;3933.272344616255;3989.940150897797;3999.4000899865255;4015.698822588259;3891.4357418174773;4122.072533680018;4123.422720819028;4126.09225970286;4134.131832315247;3963.4054891765313;3974.9863784734707;3995.800209989547;4027.617376349242;4083.244599749056 13:21:59 INFO - PROCESS | 3404 | |1;ext.html;546.104388802364;548.736606428914;563.129964205303;570.4637640617816;579.9942000579993;34183.14542136445;34840.47739283913;35687;38396.80801595995;38437.23125537488;36546.26907461849;36627.81686091572;36825.263494730156;36854.447183292206;36881.26237475256;6350.301466838644;6483.51373646976;7067.929320706791;7202.639868006605;7203.423726101899;9318;9327.533623318841;9551.850833562463;9831.557579908886;9855.802883942353;9216.815663686722;9368.250539956822;9404.717858464257;9426.104520070583;9447.433154010747;6400.199975003125;6525.053867189231;9462.195713364372;9489.81020379592;9595.328327017112;9393.483358415284;9407.670731524433;9459.52702364879;9480.715578532656;9492.193163581103;33415.832920835484;33862.661373386196;33971.830140849386;33974;34026;2607.9478410431875;2637.1429285482104;2659.056035107548;2915.5189393750006;3046.31457921968;24338.71906189846;24751.87624061886;25109.246722598353;25388.49223015548;25853.224403267934;3166.4617015107488;3183.692507703676;3185.1240908750046;3186.1556687477837;3274.1159886830424;205316;205650;205658;206216;206414;41656;43596.346054809204;44383.77808110939;44421.77789111034;44456.77771611121;26226.213213603623;26929.05748298839;27346.863265683944;46678.29982550265;46755.532444675795;10660.733481662865;10879.83680244797;10885.510152043176;10888.128949684005;10895.400752958505;6922.619255940972;7100.68046937889;7537.095548534211;7579.393648508106;7713.65288562016;15547;15743.134127622861;15760.448384306494;15774.60563485899;15801.36794528206;6146.4775494082605;6204.255489341308;6246.781362652286;6247.562670613013;6253.187085678833;742.5804420502399;836.9539675317916;886.0519244408421;886.7738726624669;906.8458362078462;23385.766142338358;24528.264152075466;24576.877115614665;24616.630750538756;24777;827.7433995461427;834.7620928035602;852.4373913217247;860.3461369359206;913.6888564909376;12759.42582583786;13113.934430327787;13242.337883105805;13281.070325077151;13312.933435332761;297799;298208;298414;298471;299307;41586.1682766343;41804.372934406005;41928.790356048026;41969;42129;939.5067589515559;966.6520052780993;1027.5735569738474;1044.7649278912031;1053.794510070529;24382;24993.250202493953;25326.873365633055;25429.61855572131;25461.599612021517;35210;35849.64150358463;36163.81918090393;36417.089572760895;36454.453183201746;225879;236058;236101;310397;404353;33522.497162542095;33887.05315410384;33889.7971631853;34064.104551113436;34098.65901341054;37034.444483332234;37369.25261494809;37598;37961.81019094887;38007.04982375463;30852.537211942286;31101.844490777403;31552.52671209888;32779.67220327766;33247;31554.211144721565;31673.36653266968;32986.17534561655;33310.83344583261;33414.83292583522;29679.40641187121;30609.23476913006;30989.69010309868;31332.216694581904;31419.371612568077;27880.44239115247;29266.561001585418;29403.705962940097;29439.26401839971;29799.55300670535;21136.788632113483;22756.886215568815;22793.29050321266;22850.029496165393;22942.655860162446 13:22:00 INFO - PROCESS | 3404 | |2;jquery.html;270.7617296778834;301.3355551010022;304.7668533571818;309.089730742962;311.6914254887662;11068.39123848188;11934.820977685331;15059.62350941227;15354.692906141898;15630.9839860409;13943.163410195395;14675.119492830438;15002.549923502313;15006.699866002673;15013.699726005474;4769.666123371366;4997.900041999158;4999.3000979862845;4999.725015124167;5000.399952005764;3495.563054618173;3662.3773958427;4935.185694360426;5666.9433305667035;5950.97024514879;5935.080062590306;5942.168096466498;5942.405759424067;5944.108383742451;5950.494207992326;5773.336066352381;5833.38749431312;5911.763529458816;5923.466887980103;5938.8218353449465;5054.342935418409;5089.185730283174;5102.821401250939;5105.2086926526445;5108.386993560796;19280.132394042303;19417.126229319572;19516.512087197934;19640.410787676392;19687.80312196874;1582.541063091702;1599.9600009999726;1826.5616252099533;2203.394066631673;2292.255017119426;12422.37888105591;12574.68563285916;12637.988960883107;12660.670629583945;12780.360981950866;1561.6486290584528;1575.369852059174;1576.8817338699644;1596.3933705192069;1611.299084898073;60650;61211.38788612146;61271.69364153151;61323.69338153325;62535.37464625341;8068.596570171468;8118.715844945398;8491.787705307417;8545.786355341166;8610.277944411142;9864.605415783431;10500.212484063726;10567.260291779656;10633.361998280128;11296.322220666787;5462.153366228162;5492.862678433071;5584.46947539983;5591.580631452576;5606.831795046155;5120.283160357553;5327.813526526553;5368.141097424394;5379.139337706027;5392.004958165504;5401.972990135103;5420.159875219348;5439.592030597721;5502.9174562381595;5506.174073888948;1409.37987285594;1499.1754535005703;1501.114342537908;1513.2055670772932;1520.7946927164692;696.1924167965136;834.6869923778584;862.7670528957146;864.0970186155482;895.6193617712446;15393.922425430112;15818.60453488649;15991.28039238237;16096.195190240442;16506;749.0636704119851;759.2976496740492;783.9921600783919;797.3979645367826;803.1807556292549;8941.284697224206;9146.131117543822;9232.53837308132;9320.114589114024;9406.764830879147;267678;275606;276447;278120;278837;61423;63125.368746312866;63490.68254658697;63706;64550.677246613464;804.1636697834186;816.2408959667566;822.0874828939991;823.0082750285891;825.7233826668013;13985.650358741113;14163.716725665636;14283.143011418932;14401.279936002948;14521.92739036298;25284;27007.594886077117;32636.673633263363;33437.49843752297;33579.83210083934;42051.57948420599;42396;42699.7865010673;42830.78584607057;42941;6610.206775187008;6689.46484281266;6729.495287853529;6773.6951837168435;6782.220044694924;10668.359898406121;10879.74882888478;10955.233133680566;10964.013238808546;11235.157363197957;8227.08180744671;8558.828823423371;8579.356548259031;8613.440126391764;8680.348973826862;8968.955155224183;9145;9424.246060315021;9433.95283023608;9473.857892131762;8889.555522223734;8962.73111806647;8966.820663586823;9052.140046695553;9141.085891410805;6508.967455162694;6563.573367731082;6651.933480665131;6803.081583986296;6960.234374218933;8378.790530236793;8418.410711250153;8607.784805379915;8668.393212475066;8897.154770297071 13:22:00 INFO - PROCESS | 3404 | |3;mootools.html;1172.1619042384698;1175.952961881525;1213.8907498325152;1368.363710874443;1410.9647258818534;5644.604877658566;5965.552583556239;5970.373110823366;5982.670953097578;6048.939510604892;4884.340614017106;5072.4673909239555;5809.244798176242;5955.017422125344;5963.731632076556;1716.5880188754702;1729.325563030419;1732.6448078144012;1733.8092809790976;1737.9826201737947;1705.4542546385162;1722.9310827566883;1730.1262862254544;1740.2516917725436;1740.6692728381568;1424.1810958698738;1447.7900704397855;1641.8111917129504;1698.5074328444737;1704.2586474883403;1676.9916150419292;1684.3431061885813;1707.3085400412854;1719.1919797695064;1721.7417387391865;1691.3826453344564;1691.4502786594282;1715.082430899463;1717.132847911809;1724.5085150732107;5697.6011679182475;5740.82777516671;5833.941660583382;5871.089981052901;5881.941180588225;3367.4275373186615;3464.5149679044953;3466.012186526853;3473.096994781375;3474.2530355973586;2174.521605246843;2244.517428752826;2280.45269135408;2289.862608243494;2294.139697613395;668.3516988521176;694.1462001737891;707.3351050013023;714.9740122923621;716.0440811516579;7007.264237255065;7059.929400705979;7142.464315176382;7363.558186508827;7372;2901.7098290171234;2989.461896858543;2991.685872983305;2993;2999.1302522268297;5931.73307201177;5934.9406505934385;5967.910481342783;5970.432808883149;5971.552133589998;3423.606285277225;3427.2974040321674;3428.194374322032;3428.451447768345;3433.776804507699;3243.9675603244136;3254.9349013019605;3255.4628486299493;3258.5600943872746;3259.5273685315497;2390.426297688542;2425.6604075429454;2457.4347899983104;2496.90012399502;2499.5875680512877;724.4421795217759;742.524784138152;745.3329270303049;751.4289140253337;752.6086435053851;725.1950335128004;732.183615268982;737.4579683932305;743.1750756660103;761.147514783435;3815.4086116651965;4051.4125451809323;4053.2704113259897;4090.3455447128913;4179.122384299331;742.7140550887947;750.6284389227259;764.2853931573885;767.3285874859498;771.5139462138928;2753.559430491152;2817.3942602340594;2840.5597132444504;2850.843203623779;2853.300941269361;6331.176946996861;6591.934080659228;6824.863502729918;6830.077939478107;6831.316868313129;6693.732250709917;6789.932100679029;6872.484563657746;6915.827104322432;6946.027556142144;5434.864128396822;5483.506484416422;5570.860728481739;5604.41153678866;5605.831825045255;4138.213739389506;4147.336426171919;4161.334186530141;4200.3828526018315;4274.8076336565555;5912.172295878667;6135.877282454415;6165.815025549241;6215.129881816459;6263.686815659108;6000;6345.904811427925;6354.523410744305;6398.296187419473;6408.58344207625;5073.086844368049;5165.483451654804;5165.896682066413;5170.6380553360905;5173.275741396133;5432.29380180574;5447.400785913625;5449.782008719607;5466.098093814635;5469.535089517435;4988.975055124701;5081.974590127025;5093.8726531837;5119.667221630582;5124.077666020003;4890.290907818276;4916.803327866988;5069.493050694901;5076.6446348755235;5127.820526281562;4622.306654001792;4853.247746599282;4884.291777692286;4897.191963326011;4899.510048995072;3507.9476157153263;3563.0736008637423;3573.964260357363;3582.7671201371827;3610.29599228148;3605.8557657694446;3627.056965189017;3627.981860090683;3664.4686520454916;3702.0374702576987 13:22:00 INFO - PROCESS | 3404 | |4;prototype.html;222.25433670037035;237.81688100162864;239.2201423359847;243.3381203127493;244.38415193711847;4168.9166216675685;4221.852235171769;4295.957040429598;4384.956150438495;4391.714538554992;4294.248506511356;4301.096769678371;4314.719543229688;4318.8272469101275;4335.761533115678;3232.6767323267723;3259.511073339;3261.347730453907;3264.102371847749;3266.3630592034565;3245.2860370718404;3252.121927079687;3254.2027203335215;3257.0554539183727;3268.5750852389274;2996.280892585775;3072.2780146665514;3093.5978322818;3141.6387115481784;3165.2719874428894;3035.833029183394;3117.9688203117908;3135.200523866411;3162.0988018414873;3172.317951640397;2643.1674022682987;2741.0817376179;2742.3418379588757;2742.5474796658527;2746.3110187234906;4180.623743863067;4183.686223533216;4192.937105943413;4199.559046300155;4201.76890271035;3667.7799332040163;3687.2809802088336;3689.7786132832116;3700.7409481336317;3709.2581483703366;4422.977885110554;4425.557444255581;4433.8448154314765;4436.245838207513;4447.533009034069;2982.6569944456232;3050.2526880914097;3057.678943710923;3088.583041289442;3090.397372512361;4378.321360189176;4544.272916333404;4617.2381557043045;4629;4639.095376401603;3083.275430273884;3101.7828751987595;3125.4217969675683;3126.937461250762;3129.5149251865996;3109.16052665779;3136.325689976666;3136.3727254548726;3151.700588444094;3158.463061279588;3532.823358832048;3617.565892092966;3658.9451158232646;3678.2091850252323;3687.2669845172727;4439.112177564435;4454;4461.330800379969;4467.709598876063;4482.013956929471;948.5684013773854;987.4420952162005;1045.9738506537396;1075.8708954925303;1088.867577719179;635.3233805996599;696.5228818259509;726.4914559808155;726.5931078596003;727.8107692000118;4199.349100889367;4255.319148936155;4259.467566554181;4275.337322714984;4290.806913688892;4522.4573051234065;4550.726956382628;4564.79458424365;4586.518415566317;4589.79345929434;4187.20443115807;4242.172776308652;4273.0385663225525;4288.35674648799;4296.5273819879785;2332.3819187915215;2410.457647029404;2420.0803694595943;2443.438009257881;2443.6701045359005;4767.475577686521;4777.737224452619;4781.808727650855;4782.593479554207;4804.639652026112;4701.294805779161;4715.646326525524;4719.598834099072;4720.843393368578;4734.945959855873;4749.717576254461;4780.306855506002;4791.760411979457;4799.640026997919;4805.495422980676;4390.319500477431;4591.747453890068;4624.560666736789;4640.605548528413;4658.324542941323;4502.797374118238;4569.931451028171;4571.862844114682;4573.314002899591;4573.862784116349;4777.808887644455;4793.712377257236;4794.976025119852;4795.544423279783;4811.807527698853;2925.0786002409172;3089.4747892857818;3126.1871913302252;3130.3739252149207;3144.355407141576;4385.649148068211;4501.324801279703;4532.70537415067;4600.7469589172915;4601.4248218972625;4434.623057040188;4434.911301774011;4447;4451.777411129496;4453.510113887534;4413.4041904343785;4453.710508816916;4466.307722303049;4468.151051300242;4472.329150627432;4350.977520282668;4378.233809083461;4379.386885835922;4379.846705365297;4394.846180383671;4245.426867372988;4464.352668862932;4492.550744925481;4501.122281155274;4501.707389019703;4417.160739459492;4420.889477763081;4455.376247325442;4455.777211139495;4461.063176732922 13:22:00 INFO - PROCESS | 3404 | |5;yui.html;527.8917821846521;573.3607028163597;638.8945823939049;647.8639485708003;651.2282944710519;11351.375674337909;12310.19983701061;12316.568920087788;12325.568605098822;12361.69095772604;11333.829992550098;11523.366214858179;11627.825582616268;11798.82301765472;11804.114691398157;498.31408707068925;499.5628824778319;619.6870580356914;632.4940047961635;637.0698779781525;611.9847003824896;632.7057918068098;633.18635553314;638.8498702804837;639.0605809460086;664.8138521214067;670.7350596514374;673.3266733266734;673.4982438083634;674.5514233035051;514.0977584339498;526.2606038515892;652.1489456259579;669.2738378858944;669.4844969373579;473.49572705068994;478.57167834788015;478.8467690339093;480.75962018990504;486.934263874376;11923.224990375684;11930.821037684442;11953.043756499459;11956.641300760904;11966.940165299118;10538.315009524356;10790.460476976119;10820.08029317509;11119;11158.944205279004;9159.679411220643;9833.26250531206;10434.478276086165;10717.249792514527;11055.72360690981;3806.6764325032404;3817.6373244541724;3828.272628200633;3889.7082718796205;4042.272390969624;47178;48027;48178;48211;48550.51449485495;8328.5002899825;8391.95804020976;8532.829343413097;8682.262007729287;8868.113188681209;15039.097654140787;15334.156621385708;15972.121533315547;16000.199990000687;16462.259198336105;6094.847628809315;6213.906791398133;6271.7804876830005;6343.968280158662;6370.267419246846;3252.7235185009067;3302.719268862126;3303.5209894565633;3411.437112876348;3437.3984552703178;2277.3281881844896;2344.4607740219503;2365.4086478380405;2422.200673777649;2428.526437344736;408.97137200395684;413.5864135864136;417.38852580969024;425.8828822073954;428.04759410311806;522.871896385388;523.4712939930697;543.6167501911143;566.2440641743236;578.2107423367073;7360.484766066324;7609.6575654095695;7797.805054873674;7929.286364227247;8002.679892804223;569.5817414637605;578.1212556913468;578.5718568259542;585.5140233606097;598.2402349016725;3360.8655653774063;3562.0560551453887;3606.851234717693;3648.890533284006;3697.556293244828;40627;44590.77704611521;54666.45333546693;63002;63312.366876331565;53613;54618.45381546213;54937;55251.171232431545;55590;37793;51872;52181.95636087257;52298.477015229364;52615.21077183922;7066.752663656953;7098.3256590623805;7110.786676399716;7255.891161632474;7414;33546.161345966546;33854;34940;35374;37092.443613345284;45476.772616138034;46935.29597055978;47500;47545;47564.52435475739;8726.30189584819;8760.036395996563;8985.415947963362;9148.085191480799;9284.907150928675;10174.236932229964;10229.539670714985;10413.895861041596;10423.11403530678;10571.418571978615;9394.530273486162;9894.653687121205;9988.300818942604;10012.44931528773;10146.898531014594;9834;10007.599696012368;10036.899631003596;10426.478676066317;10687.145028397865;9268.907310926805;9696.418214907128;9788.119069283799;9943.502824858873;10233.948830256099;8637.654493820177;8881.555922203735;8916.687915922912;9157.954210229173;9236.815263694823;7773.650185741542;8066.637001335072;8283.95858020706;8427.080795979398;8501.107383724868 13:22:00 INFO - PROCESS | 3404 | __end_tp_report 13:22:00 INFO - PROCESS | 3404 | __start_cc_report 13:22:00 INFO - PROCESS | 3404 | _x_x_mozilla_cycle_collect,2429 13:22:00 INFO - PROCESS | 3404 | __end_cc_report 13:22:00 INFO - PROCESS | 3404 | __startTimestamp1478031719977__endTimestamp 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | ------- Summary: start ------- 13:22:00 INFO - PROCESS | 3404 | Number of tests: 6 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#0] dojo.html Cycles:165 Average:3862.58 Median:4020.70 stddev:459.07 (11.4%) stddev-sans-first:444.24 13:22:00 INFO - PROCESS | 3404 | Values: 2319.9 2353.6 2355.2 2361.6 2364.3 3965.4 3971.0 3982.3 4069.1 4073.1 3913.3 3936.3 3985.4 3988.0 4074.6 3500.0 3515.0 3543.6 3547.1 3571.4 3491.6 3498.7 3499.1 3516.2 3531.6 3464.8 3494.0 3541.1 3543.3 3552.5 3429.2 3450.9 3480.6 3526.1 3527.0 2483.8 3445.0 3456.4 3505.6 3513.5 3866.4 3889.3 3960.7 3976.7 3984.2 4061.5 4092.2 4186.0 4219.1 4221.1 4148.7 4161.8 4191.7 4200.2 4210.8 3878.3 3947.4 3967.1 3981.0 3981.4 4167.7 4196.6 4273.3 4298.8 4307.1 4023.3 4068.0 4077.0 4085.3 4105.7 4131.1 4218.7 4230.3 4237.4 4238.1 4121.2 4193.8 4195.5 4215.7 4215.9 4050.4 4110.4 4119.1 4211.3 4218.6 2894.5 2993.7 3002.2 3014.5 3015.6 2787.2 2792.1 2810.2 2812.8 2816.6 3969.4 3977.9 3994.7 4005.5 4061.5 4116.3 4139.1 4174.8 4244.8 4269.0 3937.9 3967.9 4020.7 4023.5 4028.6 3588.4 3614.4 3659.7 3660.9 3666.5 4244.1 4296.2 4376.8 4399.5 4406.6 4130.9 4147.3 4156.7 4163.1 4195.7 4217.3 4294.1 4310.6 4324.0 4330.4 4047.8 4072.5 4094.3 4113.8 4122.2 3935.7 4073.2 4129.6 4132.9 4137.8 3970.1 4041.7 4048.0 4048.1 4054.6 3997.2 4010.9 4017.3 4058.8 4085.8 3922.1 3933.3 3989.9 3999.4 4015.7 3891.4 4122.1 4123.4 4126.1 4134.1 3963.4 3975.0 3995.8 4027.6 4083.2 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#1] ext.html Cycles:180 Average:40257.60 Median:33972.92 stddev:70412.64 (207.3%) stddev-sans-first:70547.03 13:22:00 INFO - PROCESS | 3404 | Values: 546.1 548.7 563.1 570.5 580.0 34183.1 34840.5 35687.0 38396.8 38437.2 36546.3 36627.8 36825.3 36854.4 36881.3 6350.3 6483.5 7067.9 7202.6 7203.4 9318.0 9327.5 9551.9 9831.6 9855.8 9216.8 9368.3 9404.7 9426.1 9447.4 6400.2 6525.1 9462.2 9489.8 9595.3 9393.5 9407.7 9459.5 9480.7 9492.2 33415.8 33862.7 33971.8 33974.0 34026.0 2607.9 2637.1 2659.1 2915.5 3046.3 24338.7 24751.9 25109.2 25388.5 25853.2 3166.5 3183.7 3185.1 3186.2 3274.1 205316.0 205650.0 205658.0 206216.0 206414.0 41656.0 43596.3 44383.8 44421.8 44456.8 26226.2 26929.1 27346.9 46678.3 46755.5 10660.7 10879.8 10885.5 10888.1 10895.4 6922.6 7100.7 7537.1 7579.4 7713.7 15547.0 15743.1 15760.4 15774.6 15801.4 6146.5 6204.3 6246.8 6247.6 6253.2 742.6 837.0 886.1 886.8 906.8 23385.8 24528.3 24576.9 24616.6 24777.0 827.7 834.8 852.4 860.3 913.7 12759.4 13113.9 13242.3 13281.1 13312.9 297799.0 298208.0 298414.0 298471.0 299307.0 41586.2 41804.4 41928.8 41969.0 42129.0 939.5 966.7 1027.6 1044.8 1053.8 24382.0 24993.3 25326.9 25429.6 25461.6 35210.0 35849.6 36163.8 36417.1 36454.5 225879.0 236058.0 236101.0 310397.0 404353.0 33522.5 33887.1 33889.8 34064.1 34098.7 37034.4 37369.3 37598.0 37961.8 38007.0 30852.5 31101.8 31552.5 32779.7 33247.0 31554.2 31673.4 32986.2 33310.8 33414.8 29679.4 30609.2 30989.7 31332.2 31419.4 27880.4 29266.6 29403.7 29439.3 29799.6 21136.8 22756.9 22793.3 22850.0 22942.7 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#2] jquery.html Cycles:180 Average:19511.40 Median:5385.57 stddev:45797.63 (850.4%) stddev-sans-first:45903.32 13:22:00 INFO - PROCESS | 3404 | Values: 270.8 301.3 304.8 309.1 311.7 11068.4 11934.8 15059.6 15354.7 15631.0 13943.2 14675.1 15002.5 15006.7 15013.7 4769.7 4997.9 4999.3 4999.7 5000.4 3495.6 3662.4 4935.2 5666.9 5951.0 5935.1 5942.2 5942.4 5944.1 5950.5 5773.3 5833.4 5911.8 5923.5 5938.8 5054.3 5089.2 5102.8 5105.2 5108.4 19280.1 19417.1 19516.5 19640.4 19687.8 1582.5 1600.0 1826.6 2203.4 2292.3 12422.4 12574.7 12638.0 12660.7 12780.4 1561.6 1575.4 1576.9 1596.4 1611.3 60650.0 61211.4 61271.7 61323.7 62535.4 8068.6 8118.7 8491.8 8545.8 8610.3 9864.6 10500.2 10567.3 10633.4 11296.3 5462.2 5492.9 5584.5 5591.6 5606.8 5120.3 5327.8 5368.1 5379.1 5392.0 5402.0 5420.2 5439.6 5502.9 5506.2 1409.4 1499.2 1501.1 1513.2 1520.8 696.2 834.7 862.8 864.1 895.6 15393.9 15818.6 15991.3 16096.2 16506.0 749.1 759.3 784.0 797.4 803.2 8941.3 9146.1 9232.5 9320.1 9406.8 267678.0 275606.0 276447.0 278120.0 278837.0 61423.0 63125.4 63490.7 63706.0 64550.7 804.2 816.2 822.1 823.0 825.7 13985.7 14163.7 14283.1 14401.3 14521.9 25284.0 27007.6 32636.7 33437.5 33579.8 42051.6 42396.0 42699.8 42830.8 42941.0 6610.2 6689.5 6729.5 6773.7 6782.2 10668.4 10879.7 10955.2 10964.0 11235.2 8227.1 8558.8 8579.4 8613.4 8680.3 8969.0 9145.0 9424.2 9434.0 9473.9 8889.6 8962.7 8966.8 9052.1 9141.1 6509.0 6563.6 6651.9 6803.1 6960.2 8378.8 8418.4 8607.8 8668.4 8897.2 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#3] mootools.html Cycles:180 Average:3782.89 Median:4898.35 stddev:1992.09 (40.7%) stddev-sans-first:1988.02 13:22:00 INFO - PROCESS | 3404 | Values: 1172.2 1176.0 1213.9 1368.4 1411.0 5644.6 5965.6 5970.4 5982.7 6048.9 4884.3 5072.5 5809.2 5955.0 5963.7 1716.6 1729.3 1732.6 1733.8 1738.0 1705.5 1722.9 1730.1 1740.3 1740.7 1424.2 1447.8 1641.8 1698.5 1704.3 1677.0 1684.3 1707.3 1719.2 1721.7 1691.4 1691.5 1715.1 1717.1 1724.5 5697.6 5740.8 5833.9 5871.1 5881.9 3367.4 3464.5 3466.0 3473.1 3474.3 2174.5 2244.5 2280.5 2289.9 2294.1 668.4 694.1 707.3 715.0 716.0 7007.3 7059.9 7142.5 7363.6 7372.0 2901.7 2989.5 2991.7 2993.0 2999.1 5931.7 5934.9 5967.9 5970.4 5971.6 3423.6 3427.3 3428.2 3428.5 3433.8 3244.0 3254.9 3255.5 3258.6 3259.5 2390.4 2425.7 2457.4 2496.9 2499.6 724.4 742.5 745.3 751.4 752.6 725.2 732.2 737.5 743.2 761.1 3815.4 4051.4 4053.3 4090.3 4179.1 742.7 750.6 764.3 767.3 771.5 2753.6 2817.4 2840.6 2850.8 2853.3 6331.2 6591.9 6824.9 6830.1 6831.3 6693.7 6789.9 6872.5 6915.8 6946.0 5434.9 5483.5 5570.9 5604.4 5605.8 4138.2 4147.3 4161.3 4200.4 4274.8 5912.2 6135.9 6165.8 6215.1 6263.7 6000.0 6345.9 6354.5 6398.3 6408.6 5073.1 5165.5 5165.9 5170.6 5173.3 5432.3 5447.4 5449.8 5466.1 5469.5 4989.0 5082.0 5093.9 5119.7 5124.1 4890.3 4916.8 5069.5 5076.6 5127.8 4622.3 4853.2 4884.3 4897.2 4899.5 3507.9 3563.1 3574.0 3582.8 3610.3 3605.9 3627.1 3628.0 3664.5 3702.0 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#4] prototype.html Cycles:180 Average:3716.15 Median:4327.29 stddev:1145.77 (26.5%) stddev-sans-first:1118.57 13:22:00 INFO - PROCESS | 3404 | Values: 222.3 237.8 239.2 243.3 244.4 4168.9 4221.9 4296.0 4385.0 4391.7 4294.2 4301.1 4314.7 4318.8 4335.8 3232.7 3259.5 3261.3 3264.1 3266.4 3245.3 3252.1 3254.2 3257.1 3268.6 2996.3 3072.3 3093.6 3141.6 3165.3 3035.8 3118.0 3135.2 3162.1 3172.3 2643.2 2741.1 2742.3 2742.5 2746.3 4180.6 4183.7 4192.9 4199.6 4201.8 3667.8 3687.3 3689.8 3700.7 3709.3 4423.0 4425.6 4433.8 4436.2 4447.5 2982.7 3050.3 3057.7 3088.6 3090.4 4378.3 4544.3 4617.2 4629.0 4639.1 3083.3 3101.8 3125.4 3126.9 3129.5 3109.2 3136.3 3136.4 3151.7 3158.5 3532.8 3617.6 3658.9 3678.2 3687.3 4439.1 4454.0 4461.3 4467.7 4482.0 948.6 987.4 1046.0 1075.9 1088.9 635.3 696.5 726.5 726.6 727.8 4199.3 4255.3 4259.5 4275.3 4290.8 4522.5 4550.7 4564.8 4586.5 4589.8 4187.2 4242.2 4273.0 4288.4 4296.5 2332.4 2410.5 2420.1 2443.4 2443.7 4767.5 4777.7 4781.8 4782.6 4804.6 4701.3 4715.6 4719.6 4720.8 4734.9 4749.7 4780.3 4791.8 4799.6 4805.5 4390.3 4591.7 4624.6 4640.6 4658.3 4502.8 4569.9 4571.9 4573.3 4573.9 4777.8 4793.7 4795.0 4795.5 4811.8 2925.1 3089.5 3126.2 3130.4 3144.4 4385.6 4501.3 4532.7 4600.7 4601.4 4434.6 4434.9 4447.0 4451.8 4453.5 4413.4 4453.7 4466.3 4468.2 4472.3 4351.0 4378.2 4379.4 4379.8 4394.8 4245.4 4464.4 4492.6 4501.1 4501.7 4417.2 4420.9 4455.4 4455.8 4461.1 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - PROCESS | 3404 | [#5] yui.html Cycles:180 Average:13177.66 Median:26193.05 stddev:16449.09 (62.8%) stddev-sans-first:16467.80 13:22:00 INFO - PROCESS | 3404 | Values: 527.9 573.4 638.9 647.9 651.2 11351.4 12310.2 12316.6 12325.6 12361.7 11333.8 11523.4 11627.8 11798.8 11804.1 498.3 499.6 619.7 632.5 637.1 612.0 632.7 633.2 638.8 639.1 664.8 670.7 673.3 673.5 674.6 514.1 526.3 652.1 669.3 669.5 473.5 478.6 478.8 480.8 486.9 11923.2 11930.8 11953.0 11956.6 11966.9 10538.3 10790.5 10820.1 11119.0 11158.9 9159.7 9833.3 10434.5 10717.2 11055.7 3806.7 3817.6 3828.3 3889.7 4042.3 47178.0 48027.0 48178.0 48211.0 48550.5 8328.5 8392.0 8532.8 8682.3 8868.1 15039.1 15334.2 15972.1 16000.2 16462.3 6094.8 6213.9 6271.8 6344.0 6370.3 3252.7 3302.7 3303.5 3411.4 3437.4 2277.3 2344.5 2365.4 2422.2 2428.5 409.0 413.6 417.4 425.9 428.0 522.9 523.5 543.6 566.2 578.2 7360.5 7609.7 7797.8 7929.3 8002.7 569.6 578.1 578.6 585.5 598.2 3360.9 3562.1 3606.9 3648.9 3697.6 40627.0 44590.8 54666.5 63002.0 63312.4 53613.0 54618.5 54937.0 55251.2 55590.0 37793.0 51872.0 52182.0 52298.5 52615.2 7066.8 7098.3 7110.8 7255.9 7414.0 33546.2 33854.0 34940.0 35374.0 37092.4 45476.8 46935.3 47500.0 47545.0 47564.5 8726.3 8760.0 8985.4 9148.1 9284.9 10174.2 10229.5 10413.9 10423.1 10571.4 9394.5 9894.7 9988.3 10012.4 10146.9 9834.0 10007.6 10036.9 10426.5 10687.1 9268.9 9696.4 9788.1 9943.5 10233.9 8637.7 8881.6 8916.7 9158.0 9236.8 7773.7 8066.6 8284.0 8427.1 8501.1 13:22:00 INFO - PROCESS | 3404 | -------- Summary: end -------- 13:22:00 INFO - PROCESS | 3404 | 13:22:00 INFO - TEST-INFO | 3404: exit 0 13:22:01 INFO - TEST-OK | dromaeo_css | took 1130261ms 13:22:01 INFO - TEST-START | kraken 13:22:01 INFO - operating with platform_type : w7_ 13:22:01 INFO - Initialising browser for kraken test... 13:22:01 INFO - TEST-INFO | started process 2064 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptbzvya\profile http://localhost:49579/getInfo.html) 13:22:07 INFO - PROCESS | 2064 | __metrics Screen width/height:1600/1200 13:22:07 INFO - PROCESS | 2064 | colorDepth:24 13:22:07 INFO - PROCESS | 2064 | Browser inner width/height: 1010/674 13:22:07 INFO - PROCESS | 2064 | __metrics 13:22:08 INFO - PROCESS | 2064 | 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" 13:22:08 INFO - PROCESS | 2064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:22:09 INFO - PROCESS | 2064 | console.error: 13:22:09 INFO - PROCESS | 2064 | Message: Error: Connection closed before committing the transaction. 13:22:09 INFO - PROCESS | 2064 | Stack: 13:22:09 INFO - PROCESS | 2064 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 13:22:09 INFO - PROCESS | 2064 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 13:22:09 INFO - PROCESS | 2064 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 13:22:09 INFO - PROCESS | 2064 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 13:22:09 INFO - PROCESS | 2064 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 13:22:09 INFO - PROCESS | 2064 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 13:22:09 INFO - PROCESS | 2064 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 13:22:09 INFO - PROCESS | 2064 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 13:22:09 INFO - PROCESS | 2064 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 13:22:09 INFO - PROCESS | 2064 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 13:22:09 INFO - PROCESS | 2064 | Barrier/this.client.addBlocker/promise13.1% 13:23:10 INFO - TinderboxPrint: I/O read bytes / time
221,301,248 / 3,177,880 13:23:10 INFO - TinderboxPrint: I/O write bytes / time
470,904,320 / 3,159,630 13:23:10 INFO - TinderboxPrint: CPU idle
8,357.0 (86.9%) 13:23:10 INFO - TinderboxPrint: CPU user
1,223.5 (12.7%) 13:23:10 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 43979776; Read time: 0; Write time: 44020 13:23:10 INFO - run-tests - Wall time: 1201s; CPU: 13.0%; Read bytes: 221282304; Write bytes: 399755776; Read time: 3167970; Write time: 3085140 13:23:10 INFO - Running post-run listener: _upload_blobber_files 13:23:10 INFO - Blob upload gear active. 13:23:10 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 13:23:10 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 13:23:10 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', 'Larch-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 13:23:10 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 Larch-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 13:23:11 INFO - (blobuploader) - INFO - Open directory for files ... 13:23:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 13:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:12 INFO - (blobuploader) - INFO - Done attempting. 13:23:12 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 13:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:23:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:13 INFO - (blobuploader) - INFO - Done attempting. 13:23:13 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 13:23:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:23:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:23:21 INFO - (blobuploader) - INFO - Done attempting. 13:23:21 INFO - (blobuploader) - INFO - Iteration through files over. 13:23:21 INFO - Return code: 0 13:23:21 INFO - rmtree: C:\slave\test\build\uploaded_files.json 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 13:23:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/38b3a69db488956011d7abab5feae0375a83ced2d1431061151ad9e334949d05ef6c4cf115c3ee12ff9cbac76d665ed593acd7366d7cac2d1d808462d2c4b356", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e9fa38681c5d9f9831cf66b064bc51e135572afc4eb1057583f5bce026aece4a1985303ed0a84f0b6501d19069a674d31db48e46064fe07db9a55f795acc883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/862cef8a073710befe29ab1a35037cb640bfcadbf85319063a60cc27a3047f922c4784394f39930ff4f239626ceb990dac77a717f94954655aca9dddf350db68"} 13:23:21 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 13:23:21 INFO - Writing to file C:\slave\test\properties\blobber_files 13:23:21 INFO - Contents: 13:23:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/38b3a69db488956011d7abab5feae0375a83ced2d1431061151ad9e334949d05ef6c4cf115c3ee12ff9cbac76d665ed593acd7366d7cac2d1d808462d2c4b356", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e9fa38681c5d9f9831cf66b064bc51e135572afc4eb1057583f5bce026aece4a1985303ed0a84f0b6501d19069a674d31db48e46064fe07db9a55f795acc883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/862cef8a073710befe29ab1a35037cb640bfcadbf85319063a60cc27a3047f922c4784394f39930ff4f239626ceb990dac77a717f94954655aca9dddf350db68"} 13:23:21 INFO - Running post-run listener: copy_logs_to_upload_dir 13:23:21 INFO - Copying logs to upload dir... 13:23:21 INFO - mkdir: C:\slave\test\build\upload\logs 13:23:21 INFO - Copying logs to upload dir... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... 13:23:21 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1417.522000 ========= master_lag: -0.02 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 37 secs) (at 2016-11-01 13:23:21.466836) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 13:23:21.472732) ========= '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-013 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-013 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-013 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/Larch-Non-PGO/sha512/38b3a69db488956011d7abab5feae0375a83ced2d1431061151ad9e334949d05ef6c4cf115c3ee12ff9cbac76d665ed593acd7366d7cac2d1d808462d2c4b356", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e9fa38681c5d9f9831cf66b064bc51e135572afc4eb1057583f5bce026aece4a1985303ed0a84f0b6501d19069a674d31db48e46064fe07db9a55f795acc883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/862cef8a073710befe29ab1a35037cb640bfcadbf85319063a60cc27a3047f922c4784394f39930ff4f239626ceb990dac77a717f94954655aca9dddf350db68"} build_url:https://queue.taskcluster.net/v1/task/FgytfQbwTkmLtPl8ayA3cA/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/FgytfQbwTkmLtPl8ayA3cA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/38b3a69db488956011d7abab5feae0375a83ced2d1431061151ad9e334949d05ef6c4cf115c3ee12ff9cbac76d665ed593acd7366d7cac2d1d808462d2c4b356", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/2e9fa38681c5d9f9831cf66b064bc51e135572afc4eb1057583f5bce026aece4a1985303ed0a84f0b6501d19069a674d31db48e46064fe07db9a55f795acc883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch-Non-PGO/sha512/862cef8a073710befe29ab1a35037cb640bfcadbf85319063a60cc27a3047f922c4784394f39930ff4f239626ceb990dac77a717f94954655aca9dddf350db68"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 13:23:21.585971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 13:23:21.586287) ========= '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-013 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-013 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-013 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-11-01 13:23:21.697610) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-01 13:23:21.697922) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-01 13:23:22.183999) ========= ========= Total master_lag: 0.16 =========