builder: mozilla-central_win7_ix_test-dromaeojs slave: t-w732-ix-144 starttime: 1480144040.51 results: success (0) buildid: 20161125212719 builduid: 4f59f4cef4704afbb5a94e40fcfc63e9 revision: f8f4eaac1701107f794b48891bcca2c95d39d503 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:20.510956) ========= master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:20.511855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:20.512141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:20.603658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:07:20.604021) ========= '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-144 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-144 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-144 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 --23:07:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 8.74 MB/s 23:07:21 (8.74 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.233000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:07:21.899712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2016-11-25 23:07:21.900029) ========= '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-144 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-144 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-144 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=7.001000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2016-11-25 23:07:28.910639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:07:28.911030) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev f8f4eaac1701107f794b48891bcca2c95d39d503 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f8f4eaac1701107f794b48891bcca2c95d39d503 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-144 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-144 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-144 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-25 23:07:29,118 truncating revision to first 12 chars 2016-11-25 23:07:29,118 Setting DEBUG logging. 2016-11-25 23:07:29,119 attempt 1/10 2016-11-25 23:07:29,119 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8f4eaac1701?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 23:07:29,641 unpacking tar archive at: mozilla-central-f8f4eaac1701/testing/mozharness/ 2016-11-25 23:07:29,769 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.068000 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:07:30.071211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:30.071547) ========= script_repo_revision: f8f4eaac1701107f794b48891bcca2c95d39d503 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:30.071935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:30.072203) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:07:30.081735) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 15 secs) (at 2016-11-25 23:07:30.082103) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-144 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-144 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-144 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 23:07:30 INFO - MultiFileLogger online at 20161125 23:07:30 in C:\slave\test 23:07:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 23:07:30 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 23:07:30 INFO - {'append_to_log': False, 23:07:30 INFO - 'base_work_dir': 'C:\\slave\\test', 23:07:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 23:07:30 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 23:07:30 INFO - 'branch': 'Firefox-Non-PGO', 23:07:30 INFO - 'buildbot_json_path': 'buildprops.json', 23:07:30 INFO - 'config_files': ('talos/windows_config.py',), 23:07:30 INFO - 'default_actions': ('clobber', 23:07:30 INFO - 'read-buildbot-config', 23:07:30 INFO - 'download-and-extract', 23:07:30 INFO - 'populate-webroot', 23:07:30 INFO - 'create-virtualenv', 23:07:30 INFO - 'install', 23:07:30 INFO - 'run-tests'), 23:07:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:07:30 INFO - 'download_minidump_stackwalk': True, 23:07:30 INFO - 'download_symbols': 'ondemand', 23:07:30 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 23:07:30 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 23:07:30 INFO - 'hg': 'c:/mozilla-build/hg/hg', 23:07:30 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 23:07:30 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 23:07:30 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 23:07:30 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 23:07:30 INFO - 'C:/mozilla-build/tooltool.py'), 23:07:30 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 23:07:30 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 23:07:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:07:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:07:30 INFO - 'installer_path': 'installer.exe', 23:07:30 INFO - 'log_level': 'info', 23:07:30 INFO - 'log_name': 'talos', 23:07:30 INFO - 'log_to_console': True, 23:07:30 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 23:07:30 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 23:07:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 23:07:30 INFO - 'opt_config_files': (), 23:07:30 INFO - 'pip_index': False, 23:07:30 INFO - 'sps_profile': False, 23:07:30 INFO - 'sps_profile_interval': 0, 23:07:30 INFO - 'suite': 'dromaeojs', 23:07:30 INFO - 'system_bits': '32', 23:07:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:07:30 INFO - 'title': 'T-W732-IX-144', 23:07:30 INFO - 'use_talos_json': True, 23:07:30 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 23:07:30 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 23:07:30 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 23:07:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:07:30 INFO - 'work_dir': 'build'} 23:07:30 INFO - [mozharness: 2016-11-26 07:07:30.589000Z] Running clobber step. 23:07:30 INFO - Running pre-action listener: _resource_record_pre_action 23:07:30 INFO - Running main action method: clobber 23:07:30 INFO - rmtree: C:\slave\test\build 23:07:30 INFO - Using _rmtree_windows ... 23:07:30 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 23:09:06 INFO - Running post-action listener: _resource_record_post_action 23:09:06 INFO - [mozharness: 2016-11-26 07:09:06.556000Z] Finished clobber step (success) 23:09:06 INFO - [mozharness: 2016-11-26 07:09:06.556000Z] Running read-buildbot-config step. 23:09:06 INFO - Running pre-action listener: _resource_record_pre_action 23:09:06 INFO - Running main action method: read_buildbot_config 23:09:06 INFO - Using buildbot properties: 23:09:06 INFO - { 23:09:06 INFO - "project": "", 23:09:06 INFO - "product": "firefox", 23:09:06 INFO - "script_repo_revision": "production", 23:09:06 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 23:09:06 INFO - "repository": "", 23:09:06 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 23:09:06 INFO - "buildid": "20161125212719", 23:09:06 INFO - "pgo_build": "False", 23:09:06 INFO - "basedir": "C:\\slave\\test", 23:09:06 INFO - "buildnumber": 39, 23:09:06 INFO - "slavename": "t-w732-ix-144", 23:09:06 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/", 23:09:06 INFO - "platform": "win7_ix", 23:09:06 INFO - "scheduler": "tests-mozilla-central-win32-talos", 23:09:06 INFO - "branch": "mozilla-central", 23:09:06 INFO - "repo_path": "mozilla-central", 23:09:06 INFO - "revision": "f8f4eaac1701107f794b48891bcca2c95d39d503", 23:09:06 INFO - "stage_platform": "win32", 23:09:06 INFO - "builduid": "4f59f4cef4704afbb5a94e40fcfc63e9", 23:09:06 INFO - "slavebuilddir": "test" 23:09:06 INFO - } 23:09:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 23:09:06 INFO - Running post-action listener: _resource_record_post_action 23:09:06 INFO - [mozharness: 2016-11-26 07:09:06.560000Z] Finished read-buildbot-config step (success) 23:09:06 INFO - [mozharness: 2016-11-26 07:09:06.560000Z] Running download-and-extract step. 23:09:06 INFO - Running pre-action listener: _resource_record_pre_action 23:09:06 INFO - Running main action method: download_and_extract 23:09:06 INFO - mkdir: C:\slave\test\build\tests 23:09: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')]} 23:09:06 INFO - trying https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 23:09:06 INFO - Downloading https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 23:09:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 23:09:07 INFO - Downloaded 1274 bytes. 23:09:07 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 23:09:07 INFO - Using the following test package requirements: 23:09:07 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 23:09:07 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 23:09:07 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'], 23:09:07 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'jsshell-win32.zip'], 23:09:07 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 23:09:07 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 23:09:07 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 23:09:07 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 23:09:07 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 23:09:07 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:09:07 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 23:09:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 23:09:07 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 23:09:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 23:09:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 23:09:08 INFO - Http code: 200 23:09:08 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 23:09:08 INFO - x-amz-cf-id: P7SDQzOxRxN_bR6kHBDy9RVHiUZ8KuzKaKcdopBxt-wnKgsd9eoutg== 23:09:08 INFO - x-amz-version-id: 0XdZzxLGdkIfWJgTdznt_2lT7Fp.5Rj_ 23:09:08 INFO - Expected file size: 16888045 23:09:08 INFO - Obtained file size: 16888045 23:09:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 23:09:30 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 23:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 23:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 23:09:30 INFO - Http code: 200 23:09:30 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 23:09:30 INFO - x-amz-cf-id: rxtp49TK1QGT1olBnerrDi0Uw_VUfxAxhPihLdWZrVHPbs0BLmApEg== 23:09:30 INFO - x-amz-version-id: 0XdZzxLGdkIfWJgTdznt_2lT7Fp.5Rj_ 23:09:31 INFO - Expected file size: 16888045 23:09:31 INFO - Obtained file size: 16888045 23:09:40 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 23:09:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 23:09:40 INFO - Fetch https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 23:09:40 INFO - Http code: 200 23:09:40 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 23:09:40 INFO - x-amz-cf-id: OBM1CS33TJYWpRW-mWzWq_73v5e3DiRUdduhDcDmI-EQrIYAIBMjVA== 23:09:40 INFO - x-amz-version-id: auVi.LpeyKA4Dy7eQ81RXS8b.JKhaf_W 23:09:41 INFO - Expected file size: 14409702 23:09:41 INFO - Obtained file size: 14409702 23:09: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')]} 23:09:41 INFO - trying https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:09:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 23:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 23:09:43 INFO - Downloaded 53408270 bytes. 23:09:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:09:43 INFO - mkdir: C:\slave\test\properties 23:09:43 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 23:09:43 INFO - Writing to file C:\slave\test\properties\build_url 23:09:43 INFO - Contents: 23:09:43 INFO - build_url:https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:09:43 INFO - Running post-action listener: _resource_record_post_action 23:09:43 INFO - Running post-action listener: set_extra_try_arguments 23:09:43 INFO - [mozharness: 2016-11-26 07:09:43.833000Z] Finished download-and-extract step (success) 23:09:43 INFO - [mozharness: 2016-11-26 07:09:43.834000Z] Running populate-webroot step. 23:09:43 INFO - Running pre-action listener: _resource_record_pre_action 23:09:43 INFO - Running main action method: populate_webroot 23:09:43 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 23:09:43 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 23:09:43 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 23:09:43 INFO - u'--tppagecycles', 23:09:43 INFO - u'7'], 23:09:43 INFO - u'tests': [u'tsvgm']}}, 23:09:43 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 23:09:43 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 23:09:43 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 23:09:43 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 23:09:43 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 23:09:43 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 23:09:43 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'damp', u'tps']}, 23:09:43 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'tests': [u'damp', u'tps']}, 23:09:43 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'dromaeo_dom']}, 23:09:43 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 23:09:43 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'basic_compositor_video']}, 23:09:43 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 23:09:43 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'other-e10s': {u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'a11yr', 23:09:43 INFO - u'ts_paint', 23:09:43 INFO - u'tpaint', 23:09:43 INFO - u'sessionrestore', 23:09:43 INFO - u'sessionrestore_no_auto_restore', 23:09:43 INFO - u'tabpaint']}, 23:09:43 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'tsvgx', 23:09:43 INFO - u'tsvgr_opacity', 23:09:43 INFO - u'tart', 23:09:43 INFO - u'tscrollx', 23:09:43 INFO - u'cart', 23:09:43 INFO - u'tsvg_static']}, 23:09:43 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 23:09:43 INFO - u'tsvgr_opacity', 23:09:43 INFO - u'tart', 23:09:43 INFO - u'tscrollx', 23:09:43 INFO - u'cart', 23:09:43 INFO - u'tsvg_static']}, 23:09:43 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'talos_options': [u'--disable-e10s'], 23:09:43 INFO - u'tests': [u'tp5o']}, 23:09:43 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'tests': [u'tp5o']}, 23:09:43 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'talos_options': [u'--disable-e10s', 23:09:43 INFO - u'--xperf_path', 23:09:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:43 INFO - u'tests': [u'tp5n']}, 23:09:43 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 23:09:43 INFO - u'talos_options': [u'--xperf_path', 23:09:43 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:43 INFO - u'tests': [u'tp5n']}}, 23:09:43 INFO - u'talos.zip': {u'path': u'', 23:09:43 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:09:43 INFO - Running post-action listener: _resource_record_post_action 23:09:43 INFO - [mozharness: 2016-11-26 07:09:43.848000Z] Finished populate-webroot step (success) 23:09:43 INFO - [mozharness: 2016-11-26 07:09:43.848000Z] Running create-virtualenv step. 23:09:43 INFO - Running pre-action listener: _resource_record_pre_action 23:09:43 INFO - Running main action method: create_virtualenv 23:09:43 INFO - Creating virtualenv C:\slave\test\build/venv 23:09:43 INFO - mkdir: C:\slave\test\build\venv\Scripts 23:09:43 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 23:09:43 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'! 23:09:43 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 23:09:43 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 23:09:48 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 23:09:55 INFO - Installing distribute......................................................................................................................................................................................done. 23:09:56 INFO - Return code: 0 23:09:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 23:09:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 23:09:57 INFO - Reading from file tmpfile_stdout 23:09:57 INFO - Output received: 23:09:57 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 23:09:57 INFO - Using _rmtree_windows ... 23:09:57 INFO - Using _rmtree_windows ... 23:09:57 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 23:09: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')]} 23:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09: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')]} 23:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:57 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:09:57 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 23:09:57 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 23:09:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:57 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:09:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:57 INFO - 'DCLOCATION': 'SCL3', 23:09:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:57 INFO - 'HOMEDRIVE': 'C:', 23:09:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:57 INFO - 'KTS_VERSION': '1.19c', 23:09:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:09:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:57 INFO - 'MOZ_NO_REMOTE': '1', 23:09:57 INFO - 'NO_EM_RESTART': '1', 23:09:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:57 INFO - 'OS': 'Windows_NT', 23:09:57 INFO - 'OURDRIVE': 'C:', 23:09: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;', 23:09:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:57 INFO - 'PROCESSOR_LEVEL': '6', 23:09:57 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:57 INFO - 'PROMPT': '$P$G', 23:09:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:57 INFO - 'PWD': 'C:\\slave\\test', 23:09:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:57 INFO - 'SYSTEMDRIVE': 'C:', 23:09:57 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:57 INFO - 'TEST1': 'testie', 23:09:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:57 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:09:57 INFO - 'USERNAME': 'cltbld', 23:09:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:57 INFO - 'WINDIR': 'C:\\windows', 23:09:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 23:09:58 INFO - Downloading/unpacking pip>=1.5 23:10:02 INFO - Running setup.py egg_info for package pip 23:10:02 INFO - warning: no files found matching 'pip\cacert.pem' 23:10:02 INFO - warning: no files found matching '*.html' under directory 'docs' 23:10:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 23:10:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 23:10:02 INFO - Installing collected packages: pip 23:10:02 INFO - Found existing installation: pip 0.8.2 23:10:02 INFO - Uninstalling pip: 23:10:02 INFO - Successfully uninstalled pip 23:10:02 INFO - Running setup.py install for pip 23:10:02 INFO - warning: no files found matching 'pip\cacert.pem' 23:10:02 INFO - warning: no files found matching '*.html' under directory 'docs' 23:10:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 23:10:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 23:10:02 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 23:10:02 INFO - Successfully installed pip 23:10:02 INFO - Cleaning up... 23:10:02 INFO - Return code: 0 23:10:02 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 23:10:02 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')]} 23:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:02 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')]} 23:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:02 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:02 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 23:10:02 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 23:10:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:02 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:02 INFO - 'DCLOCATION': 'SCL3', 23:10:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:02 INFO - 'HOMEDRIVE': 'C:', 23:10:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:02 INFO - 'KTS_VERSION': '1.19c', 23:10:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:02 INFO - 'MOZ_NO_REMOTE': '1', 23:10:02 INFO - 'NO_EM_RESTART': '1', 23:10:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:02 INFO - 'OS': 'Windows_NT', 23:10:02 INFO - 'OURDRIVE': 'C:', 23:10:02 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;', 23:10:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:02 INFO - 'PROCESSOR_LEVEL': '6', 23:10:02 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:02 INFO - 'PROMPT': '$P$G', 23:10:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:02 INFO - 'PWD': 'C:\\slave\\test', 23:10:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:02 INFO - 'SYSTEMDRIVE': 'C:', 23:10:02 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:02 INFO - 'TEST1': 'testie', 23:10:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:02 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:02 INFO - 'USERNAME': 'cltbld', 23:10:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:02 INFO - 'WINDIR': 'C:\\windows', 23:10:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:04 INFO - Downloading/unpacking psutil>=3.1.1 23:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:05 INFO - Installing collected packages: psutil 23:10:05 INFO - Successfully installed psutil 23:10:05 INFO - Cleaning up... 23:10:05 INFO - Return code: 0 23:10:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 23:10: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')]} 23:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10: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')]} 23:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:05 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:05 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 23:10:05 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 23:10:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:05 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:05 INFO - 'DCLOCATION': 'SCL3', 23:10:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:05 INFO - 'HOMEDRIVE': 'C:', 23:10:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:05 INFO - 'KTS_VERSION': '1.19c', 23:10:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:05 INFO - 'MOZ_NO_REMOTE': '1', 23:10:05 INFO - 'NO_EM_RESTART': '1', 23:10:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:05 INFO - 'OS': 'Windows_NT', 23:10:05 INFO - 'OURDRIVE': 'C:', 23:10: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;', 23:10:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:05 INFO - 'PROCESSOR_LEVEL': '6', 23:10:05 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:05 INFO - 'PROMPT': '$P$G', 23:10:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:05 INFO - 'PWD': 'C:\\slave\\test', 23:10:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:05 INFO - 'SYSTEMDRIVE': 'C:', 23:10:05 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:05 INFO - 'TEST1': 'testie', 23:10:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:05 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:05 INFO - 'USERNAME': 'cltbld', 23:10:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:05 INFO - 'WINDIR': 'C:\\windows', 23:10:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:10:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 23:10:06 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) 23:10:06 INFO - Installing collected packages: mozsystemmonitor 23:10:06 INFO - Running setup.py install for mozsystemmonitor 23:10:07 INFO - Successfully installed mozsystemmonitor 23:10:07 INFO - Cleaning up... 23:10:07 INFO - Return code: 0 23:10:07 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 23:10: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')]} 23:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10: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')]} 23:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:07 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:07 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 23:10:07 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 23:10:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:07 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:07 INFO - 'DCLOCATION': 'SCL3', 23:10:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:07 INFO - 'HOMEDRIVE': 'C:', 23:10:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:07 INFO - 'KTS_VERSION': '1.19c', 23:10:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:07 INFO - 'MOZ_NO_REMOTE': '1', 23:10:07 INFO - 'NO_EM_RESTART': '1', 23:10:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:07 INFO - 'OS': 'Windows_NT', 23:10:07 INFO - 'OURDRIVE': 'C:', 23:10:07 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;', 23:10:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:07 INFO - 'PROCESSOR_LEVEL': '6', 23:10:07 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:07 INFO - 'PROMPT': '$P$G', 23:10:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:07 INFO - 'PWD': 'C:\\slave\\test', 23:10:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:07 INFO - 'SYSTEMDRIVE': 'C:', 23:10:07 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:07 INFO - 'TEST1': 'testie', 23:10:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:07 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:07 INFO - 'USERNAME': 'cltbld', 23:10:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:07 INFO - 'WINDIR': 'C:\\windows', 23:10:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:07 INFO - Downloading/unpacking jsonschema==2.5.1 23:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:10:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:10:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 23:10:08 INFO - warning: no files found matching '*.txt' 23:10:08 INFO - no previously-included directories found matching 'build' 23:10:08 INFO - no previously-included directories found matching 'dist' 23:10:08 INFO - no previously-included directories found matching '.git*' 23:10:08 INFO - Installing collected packages: jsonschema, functools32 23:10:09 INFO - Running setup.py install for functools32 23:10:09 INFO - warning: no files found matching '*.txt' 23:10:09 INFO - no previously-included directories found matching 'build' 23:10:09 INFO - no previously-included directories found matching 'dist' 23:10:09 INFO - no previously-included directories found matching '.git*' 23:10:09 INFO - Successfully installed jsonschema functools32 23:10:09 INFO - Cleaning up... 23:10:09 INFO - Return code: 0 23:10:09 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 23:10: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')]} 23:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10: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')]} 23:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:09 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:09 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 23:10:09 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 23:10:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:09 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:09 INFO - 'DCLOCATION': 'SCL3', 23:10:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:09 INFO - 'HOMEDRIVE': 'C:', 23:10:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:09 INFO - 'KTS_VERSION': '1.19c', 23:10:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:09 INFO - 'MOZ_NO_REMOTE': '1', 23:10:09 INFO - 'NO_EM_RESTART': '1', 23:10:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:09 INFO - 'OS': 'Windows_NT', 23:10:09 INFO - 'OURDRIVE': 'C:', 23:10: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;', 23:10:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:09 INFO - 'PROCESSOR_LEVEL': '6', 23:10:09 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:09 INFO - 'PROMPT': '$P$G', 23:10:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:09 INFO - 'PWD': 'C:\\slave\\test', 23:10:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:09 INFO - 'SYSTEMDRIVE': 'C:', 23:10:09 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:09 INFO - 'TEST1': 'testie', 23:10:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:09 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:09 INFO - 'USERNAME': 'cltbld', 23:10:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:09 INFO - 'WINDIR': 'C:\\windows', 23:10:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 23:10:10 INFO - Cleaning up... 23:10:10 INFO - Return code: 0 23:10:10 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 23:10:10 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')]} 23:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:10 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')]} 23:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:10 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:10 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 23:10:10 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 23:10:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:10 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:10 INFO - 'DCLOCATION': 'SCL3', 23:10:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:10 INFO - 'HOMEDRIVE': 'C:', 23:10:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:10 INFO - 'KTS_VERSION': '1.19c', 23:10:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:10 INFO - 'MOZ_NO_REMOTE': '1', 23:10:10 INFO - 'NO_EM_RESTART': '1', 23:10:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:10 INFO - 'OS': 'Windows_NT', 23:10:10 INFO - 'OURDRIVE': 'C:', 23:10:10 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;', 23:10:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:10 INFO - 'PROCESSOR_LEVEL': '6', 23:10:10 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:10 INFO - 'PROMPT': '$P$G', 23:10:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:10 INFO - 'PWD': 'C:\\slave\\test', 23:10:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:10 INFO - 'SYSTEMDRIVE': 'C:', 23:10:10 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:10 INFO - 'TEST1': 'testie', 23:10:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:10 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:10 INFO - 'USERNAME': 'cltbld', 23:10:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:10 INFO - 'WINDIR': 'C:\\windows', 23:10:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:10 INFO - Downloading/unpacking blobuploader==1.2.4 23:10:10 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 23:10:10 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 23:10:11 INFO - Downloading blobuploader-1.2.4.tar.gz 23:10:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 23:10:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:10:11 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 23:10:11 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 23:10:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 23:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:10:12 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 23:10:12 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 23:10:12 INFO - Downloading docopt-0.6.1.tar.gz 23:10:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 23:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 23:10:12 INFO - Running setup.py install for blobuploader 23:10:12 INFO - Running setup.py install for requests 23:10:13 INFO - Running setup.py install for docopt 23:10:13 INFO - Successfully installed blobuploader requests docopt 23:10:13 INFO - Cleaning up... 23:10:14 INFO - Return code: 0 23:10:14 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:10:14 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')]} 23:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:14 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')]} 23:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:14 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:14 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 23:10:14 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 23:10:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:14 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:14 INFO - 'DCLOCATION': 'SCL3', 23:10:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:14 INFO - 'HOMEDRIVE': 'C:', 23:10:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:14 INFO - 'KTS_VERSION': '1.19c', 23:10:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:14 INFO - 'MOZ_NO_REMOTE': '1', 23:10:14 INFO - 'NO_EM_RESTART': '1', 23:10:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:14 INFO - 'OS': 'Windows_NT', 23:10:14 INFO - 'OURDRIVE': 'C:', 23:10:14 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;', 23:10:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:14 INFO - 'PROCESSOR_LEVEL': '6', 23:10:14 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:14 INFO - 'PROMPT': '$P$G', 23:10:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:14 INFO - 'PWD': 'C:\\slave\\test', 23:10:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:14 INFO - 'SYSTEMDRIVE': 'C:', 23:10:14 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:14 INFO - 'TEST1': 'testie', 23:10:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:14 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:14 INFO - 'USERNAME': 'cltbld', 23:10:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:14 INFO - 'WINDIR': 'C:\\windows', 23:10:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fbygur-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u6imvq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qj3lsn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yu2_n0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f79qhg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 23:10:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 23:10:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvwv1y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7wicai-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8uniuz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xuxxum-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eewtqj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-icmo3z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-03oden-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 23:10:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqzc8g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 23:10:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 23:10:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qi4eoo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 23:10:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 23:10:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a7rt55-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 23:10:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 23:10:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gr6xcj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 23:10:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 23:10:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t8tijl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 23:10:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:10:17 INFO - Running setup.py install for manifestparser 23:10:17 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 23:10:17 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 23:10:18 INFO - Running setup.py install for mozcrash 23:10:18 INFO - Running setup.py install for mozdebug 23:10:19 INFO - Running setup.py install for mozdevice 23:10:19 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 23:10:19 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 23:10:19 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 23:10:19 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 23:10:19 INFO - Running setup.py install for mozfile 23:10:20 INFO - Running setup.py install for mozhttpd 23:10:20 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 23:10:20 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 23:10:20 INFO - Running setup.py install for mozinfo 23:10:20 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 23:10:20 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 23:10:20 INFO - Running setup.py install for mozInstall 23:10:21 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Running setup.py install for mozleak 23:10:21 INFO - Running setup.py install for mozlog 23:10:21 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 23:10:21 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 23:10:22 INFO - Running setup.py install for moznetwork 23:10:22 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 23:10:22 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 23:10:22 INFO - Running setup.py install for mozprocess 23:10:23 INFO - Running setup.py install for mozprofile 23:10:23 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 23:10:23 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 23:10:23 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 23:10:23 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 23:10:23 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 23:10:23 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 23:10:24 INFO - Running setup.py install for mozrunner 23:10:24 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 23:10:24 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 23:10:24 INFO - Running setup.py install for mozscreenshot 23:10:25 INFO - Running setup.py install for moztest 23:10:25 INFO - Running setup.py install for mozversion 23:10:25 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 23:10:25 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 23:10:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:10:25 INFO - Cleaning up... 23:10:25 INFO - Return code: 0 23:10:25 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:25 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:25 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 23:10:25 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 23:10:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:25 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:25 INFO - 'DCLOCATION': 'SCL3', 23:10:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:25 INFO - 'HOMEDRIVE': 'C:', 23:10:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:25 INFO - 'KTS_VERSION': '1.19c', 23:10:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:25 INFO - 'MOZ_NO_REMOTE': '1', 23:10:25 INFO - 'NO_EM_RESTART': '1', 23:10:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:25 INFO - 'OS': 'Windows_NT', 23:10:25 INFO - 'OURDRIVE': 'C:', 23:10:25 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 23:10:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:25 INFO - 'PROCESSOR_LEVEL': '6', 23:10:25 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:25 INFO - 'PROMPT': '$P$G', 23:10:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:25 INFO - 'PWD': 'C:\\slave\\test', 23:10:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:25 INFO - 'SYSTEMDRIVE': 'C:', 23:10:25 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:25 INFO - 'TEST1': 'testie', 23:10:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:25 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:25 INFO - 'USERNAME': 'cltbld', 23:10:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:25 INFO - 'WINDIR': 'C:\\windows', 23:10:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wctycb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 23:10:26 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)) 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3qubv8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 23:10:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7fygzt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 23:10:26 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)) 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0jaipf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 23:10:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z9izd7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 23:10:26 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)) 23:10:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 23:10:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ugthua-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-how0xj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jhfy9t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g_bfu1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7c2mtm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8wqvmc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-spvbwd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 23:10:27 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)) 23:10:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 23:10:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_wffuj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 23:10:28 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)) 23:10:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 23:10:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2pu6si-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 23:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 23:10:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 23:10:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8t2xxx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 23:10:28 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)) 23:10:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 23:10:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ern3sn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 23:10:28 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)) 23:10:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 23:10:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0iy4b0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 23:10:28 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)) 23:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 23:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 23:10:28 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)) 23:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 23:10:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 23:10:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 23:10:28 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 23:10:28 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 23:10:29 INFO - Downloading blessings-1.6.tar.gz 23:10:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 23:10:30 INFO - Installing collected packages: blessings 23:10:30 INFO - Running setup.py install for blessings 23:10:30 INFO - Successfully installed blessings 23:10:30 INFO - Cleaning up... 23:10:33 INFO - Return code: 0 23:10:33 INFO - Done creating virtualenv C:\slave\test\build/venv. 23:10:33 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 23:10:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 23:10:33 INFO - Reading from file tmpfile_stdout 23:10:33 INFO - Using _rmtree_windows ... 23:10:33 INFO - Using _rmtree_windows ... 23:10:33 INFO - Current package versions: 23:10:33 INFO - blessings == 1.6 23:10:33 INFO - blobuploader == 1.2.4 23:10:33 INFO - docopt == 0.6.1 23:10:33 INFO - functools32 == 3.2.3-2 23:10:33 INFO - jsonschema == 2.5.1 23:10:33 INFO - manifestparser == 1.1 23:10:33 INFO - mozInstall == 1.12 23:10:33 INFO - mozcrash == 1.0 23:10:33 INFO - mozdebug == 0.1 23:10:33 INFO - mozdevice == 0.49 23:10:33 INFO - mozfile == 1.2 23:10:33 INFO - mozhttpd == 0.7 23:10:33 INFO - mozinfo == 0.9 23:10:33 INFO - mozleak == 0.1 23:10:33 INFO - mozlog == 3.4 23:10:33 INFO - moznetwork == 0.27 23:10:33 INFO - mozprocess == 0.23 23:10:33 INFO - mozprofile == 0.28 23:10:33 INFO - mozrunner == 6.13 23:10:33 INFO - mozscreenshot == 0.1 23:10:33 INFO - mozsystemmonitor == 0.3 23:10:33 INFO - moztest == 0.7 23:10:33 INFO - mozversion == 1.4 23:10:33 INFO - psutil == 3.1.1 23:10:33 INFO - requests == 1.2.3 23:10:33 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:10: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')]} 23:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10: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')]} 23:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:33 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 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10:33 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 23:10:33 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 23:10:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:33 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:33 INFO - 'DCLOCATION': 'SCL3', 23:10:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:33 INFO - 'HOMEDRIVE': 'C:', 23:10:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:33 INFO - 'KTS_VERSION': '1.19c', 23:10:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:33 INFO - 'MOZ_NO_REMOTE': '1', 23:10:33 INFO - 'NO_EM_RESTART': '1', 23:10:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:33 INFO - 'OS': 'Windows_NT', 23:10:33 INFO - 'OURDRIVE': 'C:', 23:10: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;', 23:10:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:33 INFO - 'PROCESSOR_LEVEL': '6', 23:10:33 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:33 INFO - 'PROMPT': '$P$G', 23:10:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:33 INFO - 'PWD': 'C:\\slave\\test', 23:10:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:33 INFO - 'SYSTEMDRIVE': 'C:', 23:10:33 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:33 INFO - 'TEST1': 'testie', 23:10:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:33 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:33 INFO - 'USERNAME': 'cltbld', 23:10:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:33 INFO - 'WINDIR': 'C:\\windows', 23:10:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 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)) 23:10:33 INFO - Cleaning up... 23:10:33 INFO - Return code: 0 23:10:33 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 23:10: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')]} 23:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10: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')]} 23:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017F0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9A020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0179B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-144', 'USERDOMAIN': 'T-W732-IX-144', '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-144', '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 23:10: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', 'jsonschema'] in C:\slave\test\build 23:10: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 jsonschema 23:10:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:33 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:33 INFO - 'DCLOCATION': 'SCL3', 23:10:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:33 INFO - 'HOMEDRIVE': 'C:', 23:10:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:33 INFO - 'KTS_VERSION': '1.19c', 23:10:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:33 INFO - 'MOZ_NO_REMOTE': '1', 23:10:33 INFO - 'NO_EM_RESTART': '1', 23:10:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:34 INFO - 'OS': 'Windows_NT', 23:10:34 INFO - 'OURDRIVE': 'C:', 23:10:34 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;', 23:10:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:34 INFO - 'PROCESSOR_LEVEL': '6', 23:10:34 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:34 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:34 INFO - 'PROMPT': '$P$G', 23:10:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:34 INFO - 'PWD': 'C:\\slave\\test', 23:10:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:34 INFO - 'SYSTEMDRIVE': 'C:', 23:10:34 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:34 INFO - 'TEST1': 'testie', 23:10:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:34 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:34 INFO - 'USERNAME': 'cltbld', 23:10:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:34 INFO - 'WINDIR': 'C:\\windows', 23:10:34 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 23:10:34 INFO - Cleaning up... 23:10:34 INFO - Return code: 0 23:10:34 INFO - Running post-action listener: _resource_record_post_action 23:10:34 INFO - Running post-action listener: _start_resource_monitoring 23:10:34 INFO - Starting resource monitoring. 23:10:34 INFO - [mozharness: 2016-11-26 07:10:34.448000Z] Finished create-virtualenv step (success) 23:10:34 INFO - [mozharness: 2016-11-26 07:10:34.449000Z] Running install step. 23:10:34 INFO - Running pre-action listener: _resource_record_pre_action 23:10:34 INFO - Running main action method: install 23:10:34 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 23:10:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 23:10:34 INFO - Reading from file tmpfile_stdout 23:10:34 INFO - Using _rmtree_windows ... 23:10:34 INFO - Using _rmtree_windows ... 23:10:34 INFO - Detecting whether we're running mozinstall >=1.0... 23:10:34 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 23:10:34 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 23:10:34 INFO - Reading from file tmpfile_stdout 23:10:34 INFO - Output received: 23:10:34 INFO - Usage: mozinstall-script.py [options] installer 23:10:34 INFO - Options: 23:10:34 INFO - -h, --help show this help message and exit 23:10:34 INFO - -d DEST, --destination=DEST 23:10:34 INFO - Directory to install application into. [default: 23:10:34 INFO - "C:\slave\test"] 23:10:34 INFO - --app=APP Application being installed. [default: firefox] 23:10:34 INFO - Using _rmtree_windows ... 23:10:34 INFO - Using _rmtree_windows ... 23:10:34 INFO - mkdir: C:\slave\test\build\application 23:10:34 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-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 23:10:34 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 23:10:37 INFO - Reading from file tmpfile_stdout 23:10:37 INFO - Output received: 23:10:37 INFO - C:\slave\test\build\application\firefox\firefox.exe 23:10:37 INFO - Using _rmtree_windows ... 23:10:37 INFO - Using _rmtree_windows ... 23:10:37 INFO - Running post-action listener: _resource_record_post_action 23:10:37 INFO - [mozharness: 2016-11-26 07:10:37.173000Z] Finished install step (success) 23:10:37 INFO - [mozharness: 2016-11-26 07:10:37.174000Z] Running run-tests step. 23:10:37 INFO - Running pre-action listener: _resource_record_pre_action 23:10:37 INFO - Running main action method: run_tests 23:10:37 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 23:10:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 23:10:37 INFO - Python 2.7.3 23:10:37 INFO - Return code: 0 23:10:37 INFO - grabbing minidump binary from tooltool 23:10: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')]} 23:10:37 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 0x018B7E90>, '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 0x0123A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F7B5C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 23:10:37 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 23:10:37 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 23:10:37 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 23:10:37 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 23:10:39 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp1_nw5l 23:10:39 INFO - INFO - File integrity verified, renaming tmp1_nw5l to win32-minidump_stackwalk.exe 23:10:39 INFO - Return code: 0 23:10:39 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 23:10:39 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 23:10:39 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 23:10:39 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 23:10:39 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 23:10:39 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-144', '--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 23:10:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-144 --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 23:10:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:10:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:10:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:10:39 INFO - 'COMPUTERNAME': 'T-W732-IX-144', 23:10:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:10:39 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:10:39 INFO - 'DCLOCATION': 'SCL3', 23:10:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:10:39 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:10:39 INFO - 'HOMEDRIVE': 'C:', 23:10:39 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:10:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:10:39 INFO - 'KTS_VERSION': '1.19c', 23:10:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:10:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-144', 23:10:39 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 23:10:39 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 23:10:39 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:10:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:39 INFO - 'MOZ_NO_REMOTE': '1', 23:10:39 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 23:10:39 INFO - 'NO_EM_RESTART': '1', 23:10:39 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:10:39 INFO - 'OS': 'Windows_NT', 23:10:39 INFO - 'OURDRIVE': 'C:', 23:10: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;', 23:10:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:10:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:10:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:10:39 INFO - 'PROCESSOR_LEVEL': '6', 23:10:39 INFO - 'PROCESSOR_REVISION': '1e05', 23:10:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:10:39 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:10:39 INFO - 'PROMPT': '$P$G', 23:10:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:10:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:10:39 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:10:39 INFO - 'PWD': 'C:\\slave\\test', 23:10:39 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 23:10:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:10:39 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:10:39 INFO - 'SYSTEMDRIVE': 'C:', 23:10:39 INFO - 'SYSTEMROOT': 'C:\\windows', 23:10:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:39 INFO - 'TEST1': 'testie', 23:10:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:10:39 INFO - 'USERDOMAIN': 'T-W732-IX-144', 23:10:39 INFO - 'USERNAME': 'cltbld', 23:10:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:10:39 INFO - 'WINDIR': 'C:\\windows', 23:10:39 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:10:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:10:39 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-144', '--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 23:10:40 INFO - mozversion application_buildid: 20161125212719 23:10:40 INFO - mozversion application_changeset: f8f4eaac1701107f794b48891bcca2c95d39d503 23:10:40 INFO - mozversion application_display_name: Nightly 23:10:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:10:40 INFO - mozversion application_name: Firefox 23:10:40 INFO - mozversion application_remotingname: firefox 23:10:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:10:40 INFO - mozversion application_vendor: Mozilla 23:10:40 INFO - mozversion application_version: 53.0a1 23:10:40 INFO - mozversion platform_buildid: 20161125212719 23:10:40 INFO - mozversion platform_changeset: f8f4eaac1701107f794b48891bcca2c95d39d503 23:10:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:10:40 INFO - mozversion platform_version: 53.0a1 23:10:40 INFO - using testdate: 1480144240 23:10:40 INFO - actual date: 1480144240 23:10:40 INFO - starting webserver on 'localhost:50000' 23:10:40 INFO - SUITE-START | Running 2 tests 23:10:40 INFO - TEST-START | dromaeo_css 23:10:40 INFO - operating with platform_type : w7_ 23:10:40 INFO - Initialising browser for dromaeo_css test... 23:10:40 INFO - TEST-INFO | started process 3292 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp5729p3\profile http://localhost:50000/getInfo.html) 23:10:47 INFO - PROCESS | 3292 | __metrics Screen width/height:1600/1200 23:10:47 INFO - PROCESS | 3292 | colorDepth:24 23:10:47 INFO - PROCESS | 3292 | Browser inner width/height: 1010/674 23:10:47 INFO - PROCESS | 3292 | __metrics 23:10:48 INFO - PROCESS | 3292 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:10:48 INFO - PROCESS | 3292 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:10:49 INFO - TEST-INFO | 3292: exit 0 23:10:49 INFO - Browser initialized. 23:10:49 INFO - Running cycle 1/1 for dromaeo_css test... 23:10:49 INFO - TEST-INFO | started process 2400 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp5729p3\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:10:50 INFO - PROCESS | 2400 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 23:11:01 INFO - PROCESS | 2400 | RSS: Main: 138317824 23:11:01 INFO - PROCESS | 2400 | 23:11:52 INFO - PROCESS | 2400 | 1480144312865 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] 23:14:07 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50000/tests/dromaeo/cssquery-ext.html) 23:14:09 INFO - PROCESS | 2400 | RSS: Main: 205062144 23:14:09 INFO - PROCESS | 2400 | 23:17:11 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-ext.html (next: http://localhost:50000/tests/dromaeo/cssquery-jquery.html) 23:17:12 INFO - PROCESS | 2400 | RSS: Main: 206946304 23:17:12 INFO - PROCESS | 2400 | 23:20:15 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50000/tests/dromaeo/cssquery-mootools.html) 23:20:15 INFO - PROCESS | 2400 | RSS: Main: 140636160 23:20:15 INFO - PROCESS | 2400 | 23:23:18 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50000/tests/dromaeo/cssquery-prototype.html) 23:23:18 INFO - PROCESS | 2400 | RSS: Main: 141037568 23:23:18 INFO - PROCESS | 2400 | 23:26:21 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50000/tests/dromaeo/cssquery-yui.html) 23:26:22 INFO - PROCESS | 2400 | RSS: Main: 142987264 23:26:22 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | Cycle 1(1): loaded http://localhost:50000/tests/dromaeo/cssquery-yui.html (next: http://localhost:50000/tests/dromaeo/cssquery-dojo.html) 23:29:25 INFO - PROCESS | 2400 | RSS: Main: 202350592 23:29:25 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | __start_tp_report 23:29:25 INFO - PROCESS | 2400 | _x_x_mozilla_page_load 23:29:25 INFO - PROCESS | 2400 | _x_x_mozilla_page_load_details 23:29:25 INFO - PROCESS | 2400 | |i|pagename|runs| 23:29:25 INFO - PROCESS | 2400 | |0;dojo.html;2328.3015095471364;2342.1568235435248;2355.211004313556;2375.334906226256;2390.880455977203;4021.537523184831;4050.2102090092444;4123.60825721556;4125.83496660134;4135.04893874409;3926.587708290624;3980.8009599519983;4058.3506638937774;4124.092699606082;4162.916741665165;3465.358908601904;3558.6797188253054;3591.102224443889;3607.963920360789;3636.7454278200535;3369.9326013479717;3596.982015089921;3597.0647631615707;3624.1664417184065;3646.325429795483;3544.5392099027217;3566.7503274770775;3584.31897939392;3595.352836489431;3652.324320000795;3173.1908363367424;3549.7870127792157;3588.9641103588892;3592.748507604456;3659.304732100892;3391.2030672791875;3468.6357932416986;3597.0827439003147;3603.6036036036016;3607.7654952428006;3969.8809035728973;3977;4054.8580799671868;4063.7155399122075;4072.857449989236;3950.723449358546;4029.234445455354;4139.275626765304;4142.1922725068625;4290.892727681801;3914.667253283474;4108.239975604492;4128.256913755523;4129.566395528456;4153.6677065834665;3846.4614953906475;3964.5242570891387;4020.1356708307853;4051.9797401013398;4069.674426045909;4339.067100573392;4350;4410.845620403335;4435.534268901783;4437.933430998538;4022;4188.748675079505;4267.231898258343;4269.124829409963;4270.508891477458;4114.650254728322;4120.690948178899;4159.064210552662;4236.5128010278595;4250.149970006011;4173.207090652827;4236.766977816188;4252.149570085995;4253.383259427365;4260.084081922401;4187.099773548702;4212.7472351659;4245.87262382129;4255.4255175551525;4263.744175349427;2898.811577247472;3009.7893147479467;3043.7260646541918;3045.0255918106436;3121.68783121686;2728.4270303236544;2776.8889244429997;2788.3726161613477;2803.032953630994;2812.831230126199;3943.2310699413338;3999.5000624921886;4057.79711014454;4083.285425050664;4086.4891888513935;4211.43145675336;4226.936595951063;4300.978495107504;4374.934375984363;4384.758838263926;3862.4013277941963;3902.726809123334;3920.9803950980063;3951.4863067801;3979.2837289288204;3484.8257587120543;3553.076200187918;3585.9779962710504;3663.8534458621357;3673.577538583097;4162.5421203668175;4185.953511622094;4301.096769678403;4376.912461750683;4406.603405693503;4136.751794892316;4149.25313443583;4182.853600123981;4210.179015092028;4300.03249268912;4217.683673724544;4296.462942132233;4324.24325743;4331.133773245427;4387.692861499664;4085.5914408560097;4088.8773336799945;4193.811278492414;4207.789610519523;4207.831686732497;4104.138130992525;4123.773192474443;4261.147770445985;4262.595053469915;4271.188474189894;4048.5748996356137;4078.4290199372704;4120.21715873983;4163.812628431668;4183.874483765492;3961.2275606256503;4083.632473077437;4182.289010868099;4201.34879093741;4210.263203939237;3904.316744569746;3956.7823769692945;3991.341428664354;4010.7393019453643;4109.8972525687095;3873.941890871583;4035.4753881995152;4038.555758866581;4141.295979683401;4210.831566737419;4140.358244472111;4150.398192262045;4184.058586817941;4204.747715137101;4212.241796476664 23:29:25 INFO - PROCESS | 2400 | |1;ext.html;557.9135234038725;561.1638658398987;573.922520459738;574.3940143148977;588.2676068294974;24956;26623.467530649374;26702;26712.19863404096;26714.06500772471;26657.600135997975;36880.44679329812;37373.81313093437;38023.23953520928;38036.23927521448;6995;7103.325184107502;7116.822079448029;7117.928820711779;7121.145462544476;7016.754413595525;7087;7088.681009354591;7094.184168820574;7097.609631470267;6303.117563541108;6426.646534440604;9007.684731034415;9216;9220.723378298662;9137.269018478506;9186.770330741718;9190.356675032717;9210.263178945668;9228.307876909192;9012.729618111467;9062.864057039149;9078.092190780935;9084;9087.545622718904;26136.477270454674;26543.867280663668;32024.839875800706;32083;32567.67432325694;2505.799536037113;2512.371907023244;2522.1676846640567;2543.6566063581363;2582.3544113971507;23920.40198995039;24298.635520467207;24549.631755523504;24827.379315517075;25039.12363067284;2887.8556072196516;3001.3397052648384;3006.8423656892037;3037.407705497429;3091.2580980564726;200417;200486;201218;201616;202705;45268;45699;45714.77142614266;45773;45855;45756.77121614371;47455;47507.28739068848;47507.52492475031;47544.7622761884;10232.33489823174;10358.53386597605;10518.263721539572;10529.999650033235;10566.101881340172;7662.885056724153;7668.846623067508;7691.346235569928;7713;7717.459777815499;12572.182808117443;14507.347169377403;14571.344289506998;14622.415103395744;14660.193689346976;4894.700646845994;5636.2954630671165;5865.765369385262;5878.559108066912;5997.460228579362;696.4080531548245;700.0479348086598;811.1077814404226;826.7147833997261;840.0843081041694;23199.652005219934;23567;23886.283411497683;23937.7606223939;24088.51822963531;863.0506442912871;871.8517851965181;908.9363744537819;909.231699214163;953.0707560128819;12287.877121228852;12957.481700732054;12973.805392919114;13063.477460901648;13130.934345328213;122657;279499;281408.3366248524;281508;281719;41662.791686041375;42035.36946945798;42088.158236835094;42148.789256053526;42256.366154507705;918.4764684130115;935.8502639577634;1048.046277887119;1062.5962134388883;1076.3004047369116;22718.545629087657;24718.382040449134;25220.495590088463;25239;25259.62110568306;36237;36265.637343627284;36646.816765917065;36716.63283367132;36756.81621591875;218344;235716;237638;238576;239726;19367;20505.79494205039;20549.38351849447;31442.371152577278;31830.68169318277;36154.63845361512;36562.08594785152;36668.26663466769;36712.26575468529;36911.07722306964;29454.558181626864;29876.402471950005;31113.222169445944;31127;31930.36139277248;30136.84931575328;30229.395412092075;30267.584441811898;30679.53980690336;31516.527252091695;28643.28391790222;31160.53259201159;32401.51397729083;32686;32738;27763.722362776112;28816.279593009505;28910.710892891642;28926.42147157003;29307.560386594643;20587.4853128667;21795.455113622287;22033;22412.77587224107;22791.430214244778 23:29:25 INFO - PROCESS | 2400 | |2;jquery.html;286.43572162839223;311.5731447916354;313.6048578790723;316.9778115531914;320.91656169395964;11201.271917325368;11965.461554230058;14849.406023759064;14953.850461495382;15319.77020344693;13480.730385392286;14229;14824.629384265372;15135.394584216645;15147.54557363281;4480.260756975095;4597.172508948388;4811.30236115763;4824.758762061901;4843.878903027417;3467.9133021674406;3628.87298944537;4731.810727570893;4805.0389922015565;5254;5404.351477822667;5458.1266997280445;5583;5585.357683866367;5593.776248950037;5143.665661731976;5310.0441920454305;5490.80782172624;5563.916541251884;5569.832905012856;4660.091282199973;4804.711717296938;4822.987172693738;4825.855224343275;4846.248831431134;18516.722249166138;18749.625007499773;19218.711719324223;19267.421977340702;19306.32427865032;1711.2556038123457;1738.6001219719535;1750.3786155914722;1973.3290681168328;2218.489747358117;7349.30181632744;7397.889031664529;7779;7819.335356494705;8020.3984701147065;1487.7917091607183;1501.69215310861;1502.5342143935416;1555.3467543631702;1575.6769862178226;59699;61882.69058654678;62421;63461.68269158625;64858.351416485704;8142.470739402039;8196.877046844302;8413.055430599363;8432.283255923192;8571.699990500303;9239.676611318571;9598.66404675833;9640.662576809778;9644.373115747454;10686.89313106859;3767.1147280389087;3833.9808300958316;3836.2519308735086;3855.3060449118866;5070.898582028412;5142.820001300011;5183.766730497136;5282.894342113136;5447;5462.3445186577865;3319.5684561007397;3395.422778127724;3398.9830050849587;3450.3271861987173;3455.481677748358;1392.9651758706113;1404.3399602186955;1463.985360146385;1488.9627759306104;1508.5625168701174;705.9576425414491;780.4653812138703;830.3108420011376;862.6161358195543;862.8058686795421;14871.925640371945;15144.394224231128;15287.770683439758;15383.92308038475;15590.688186236212;784.7096574267443;785.1598789295399;792.595776154154;808.4138999225514;832.9292010179081;8357.791055223668;8429.578521073921;8582.141785821368;8624.137586241326;8627.61175747093;261663;265177;269024;271126;271975;52622.47377526176;66343.66828165925;68920.21595680884;70378.64810675914;71405.64297178584;733.7431898835365;754.4115589839934;761.0600907878766;769.0502229746371;771.4407054885163;14443.78334325007;14647.780283295971;14699.706005880036;14766.33551490207;14844;35786.284274313846;36208.45687314745;37022.8148859254;37131.814340928126;38352.42471362987;49743.802520483936;50499.49500504948;50849.49150508447;50958.49041509686;51107;6297.653629050263;6308;6420.390062944013;6481.222253329631;6540.444062254762;9107.499087550248;10371.94814025925;10603.363798172135;10810.29733067348;10890.727731806768;6963.777167124656;7265.963670181615;8954.417962832395;8956.328275379243;9174.770630734285;8712.172343627346;8961.372703910663;9102.362834601514;9221.123993220634;9402.341836071682;4386.758728269976;8404.495730256203;8652.740417787476;8802.207801297915;9511.66709165176;5358.089124848707;5436.429174936575;5733.168690539932;5804.709764511673;5814.04068328721;8004.679812807422;8042.437029407885;8844.911550884408;9002.50486223264;9219.26245900313 23:29:25 INFO - PROCESS | 2400 | |3;mootools.html;1113.409892756839;1154.2282596214811;1211.0977321895186;1383.6610030542518;1445.0751519027817;5759.136129580555;5780.971095144524;6201.193844800176;6218.937810621893;6223.782167624134;5207.375114986207;5382.923415316933;5951.256092988377;6110.572259941795;6121.663308518041;1742.1811748478226;1769.3453422233727;1770.884892481991;1772.7606773085597;1775.351996521267;1699.1419333236697;1724.370604729271;1745.2320978769365;1771.3977247735768;1789.4899953513252;1474.3291802230012;1511.8790496760287;1685.1826863970964;1722.448816378753;1733.6272701369203;1723.4312676816621;1726.8273172682757;1727.0242313093124;1728.3777839977597;1736.2360561353028;1676.5389517882559;1689.1216567385013;1690.433704708924;1717.9226934787964;1719.4583706132528;5773;5852.092925596539;5855.531557475391;5868.4718375346;5976.701164941735;3484.5470088888537;3490.3891818931584;3515.982420087909;3569.964300356989;3603.4414665726854;2280.09936075251;2348.1429278313462;2388.773066558674;2396.628522579003;2417.951640967171;741.6143605325254;745.437194917839;779.3414564692825;782.7416952405692;789.4000559574764;7797.922020779776;7818.45270831042;7844.4901081429525;7848.843023139505;7866.056073271244;3077.8307193104592;3214.100051985448;3263.592050993626;3338.7329013678846;3346.849391777376;6840.384365407137;6840.863182736317;6846.246912839583;6855.108835851307;6862.622555759382;3616.222512159898;3628.727845411605;3631.9092022699115;3635.345637785171;3637.5998640149564;3065;3166.2875852932907;3260.7554433417113;3281.2125089978235;3285.3593549258144;2777.972220277771;2781.582762585628;2823.5482322828248;2869.239651492356;2892.2191008428026;978.7553111722069;985.2462865907586;991.4971905922104;995.2734503812113;996.7807082441851;672.2067959807474;677.6916502991127;679.8028571714148;694.0838093716347;708.4014008163094;3807.485989391452;3867.091233560111;3870.767753934773;3940.251352243122;3946.861859834892;317.3510171698881;323.5098825279703;327.3796156283876;330.6561176376541;330.7436736529209;2572.691277046729;2591.676040494938;2707.255504736174;2716.9592456113173;2726.3184203949013;6415.518836087312;6801;7143.7142514299885;7195.820104497325;7197.820054498575;6713.832154196184;6954.7218111276;7118.359347658632;7138.571685698875;7153.4992550520965;5589;5637.68992705397;5661.490465858093;5677.432256774289;5699.572532060112;3830.540335159799;3875.844966201433;3913.7064720146673;3923.6664883485223;3938.5470670873215;6551.148350714377;6601.207855057424;6607.867842643024;6642.036904648702;6644.368784965421;6551;6685.598864067977;6730.125083739083;6760.729570817112;6776.491763117688;4955.72743499111;4959.876003099951;5141.28022076917;5166.199239117943;5243.528082472596;5483.150111732688;5573.136163894603;5627.5497960162265;5633;5635.605507614428;4858.149823780895;4882.365292512093;5069.467705890976;5096.949030509648;5135.820246291362;4959.256111583291;4973.850784476472;5101.744912754421;5127.615428842777;5131.358580177478;4840.080384726891;4841.540053694894;4860.246661767432;4969.850904472872;4974.054929563371;3183.633882103588;3196.968030319667;3272.7709060365546;3282.4748040313434;3358.580177477815;3402.285520040819;3441.4149594569485;3442.345954268681;3460.740444466625;3487.2851065532013 23:29:25 INFO - PROCESS | 2400 | |4;prototype.html;227.16064142991632;234.87434222690862;245.16154750752426;253.60311113107997;254.1562014113145;4195.559466256045;4362.912741745163;4479.334795796496;4489.528599497046;4507.5943165115095;4334.84828031019;4416.2713152329825;4418;4420.403245561856;4433.71180873243;3123.3128711683394;3217.935641287173;3224.564683767685;3267.54254404383;3308.3217940322174;3181.1569933967517;3203.5515027896113;3278.1149089745754;3301.735861131104;3304.1904733340243;3261.423475454388;3266.1181481000235;3274.2469232076637;3277.5411442398085;3280.721138703201;3234.692704193098;3259.783347323819;3273.492608645664;3276.393867134576;3280.721138703213;2711.4712631036955;2780.9860950695115;2803.803733738639;2846.7864910131825;2854.8858045678357;4258.212230737324;4265.936010959838;4268.829246830123;4269.850555230552;4279.593438623357;3835;3841.5582208046153;3841.692664586826;3842.9039274018373;3853.7880416577077;4514.751688657123;4521.751303678297;4524.8190072397065;4538.909221815578;4552.954470455286;3126.6873312668777;3228.8547015384365;3232.078857525594;3234.628017777962;3242.157039169809;4667.789949452283;4697.295405689174;4697.999999999965;4721.197396442613;4727.456342520585;3202.550049212354;3225.419424503565;3270.4603740383045;3313.7514686398135;3318.1538707629884;3286.9153179450723;3327.351166522505;3354.832258387071;3356.4965255211423;3360.83195840207;3756.154865155318;3821.3694740368082;3826.846926122924;3831.1571454280015;3843.3466310727244;4310.719803212781;4514.300283456069;4563.95436045642;4567.451905771329;4569.405977222939;1066.184368957748;1135.898178766595;1138.3966497756203;1144.3019757947645;1146.7534480086654;769.8152443413541;770.5376773935594;779.0845756236399;784.9442499837709;789.889415481833;4196.706230563831;4221.6200541951375;4224.767637779952;4226.978865105655;4238.279492486285;4570.862874113782;4577.024933793061;4589.724616522953;4606.239970404913;4616.8614941551805;4202.3315194850875;4220.345846393814;4234.322508398642;4236.766977816188;4239.639630631369;2395.7604239575903;2413.7706917842775;2432.452698142939;2439.2804122783823;2448.6694296269775;4406.294992801137;4516.774161291923;4569.405977222939;4616.215243408628;4661.720296782204;3145;4405.801738921692;4430.556944305544;4438.866833994985;4602.861914142581;4293.635041021548;4344.891377715582;4373.562643735601;4387.670924680661;4447.866564003085;4143.937840932449;4309.784510774512;4320.351947207818;4433.268510695699;4445.088756804783;4281;4447.2217361962175;4479.865604031885;4489.169503641837;4513.819447222074;4670.8365207217585;4673.462551806586;4688.859334219979;4688.859334219979;4696.107739529479;3234.773565850368;3247.1395080303264;3248.2204270974325;3265.9673403265665;3273.7872038318387;4512.165249428866;4516.412866327357;4522.88692782683;4547.3179023146795;4555.33947577606;4356.6950313477755;4399.406080179263;4412.426384569985;4451.577100175478;4467.463904331501;4446.821592277985;4486.94887476506;4511.458624965026;4520.638348932143;4533.297338912474;4402.119576084732;4412.426384569985;4412.82348706061;4425.579569940851;4425.645948324062;4516.570925762047;4535.886602834956;4549.886252843706;4564.954350456453;4572.177008138567;4289.463817022872;4472.127935052634;4479.193745125908;4487.406970525417;4489.685721999559 23:29:25 INFO - PROCESS | 2400 | |5;yui.html;549.2091388400703;587.9029960056591;592.6148003797531;596.1356033751063;645.7223393940607;10286.382817030983;10774.892251077506;10888.29226100303;11192.38441885696;11334.43327833608;10198.75627361981;10343.844842327353;10449.320794148394;10493.317934334282;10516.68449946501;488.3578094805337;496.3473032961644;581.3169525807181;604.5284677951183;618.1932577985726;615.2063837649449;615.7260019291417;621.4810633121349;624.6096189881324;625.5058503782006;660.8347913021745;660.9438197753196;662.4170729757807;663.1743479368193;663.4095654867172;501.05300981145666;514.5600511562635;630.9683667204108;650.8242774450872;653.9771108011197;470.160763037979;470.26873212155425;478.88746144655977;479.2499737910159;479.74333731453504;10745.516451759611;10767.83848242277;10771.407572583505;10775.4612269387;10798.514066866932;10168.542415591317;10603.734906627318;10673.83989240162;10799.136069114453;10934.507947142318;8369.455985360932;8627.61175747093;9153.679621213225;10195.133413659847;10845.295055821347;2793.315637668764;2812.5921741347593;2850.087971849009;2939.397423528193;2982.0308399769947;48803.51196488025;50192.498075019146;51168.488315116745;51382.22926656103;52133;7983.20167983197;8495.957520212483;8761.38670293086;8912.821743565093;8999.145081217273;15268.389264429521;15332.233388330762;15365.846341536442;15397.615059623375;15487.225638718019;5621.550275977912;5702.116171993348;5709.400512946164;5768.192453056576;5835.5915085944425;3108.6580476147606;3120.0795765249304;3174.619045714529;3215.035489353185;3261.1357990132633;2023.3727544461265;2246.0229800037037;2248.2805502239453;2252.6283163278204;2417.419819243369;407.96532294754684;411.3500668943063;417.9707420480537;421.54052083229425;422.7379025004517;430.58233513491865;507.07711964224757;529.9947000530022;545.1250742558216;546.3962321634616;6162.414570615784;6561.245456772533;6691.330866913269;6725;6736.831579210559;492.2861850317055;493.9678920870132;517.7618295584074;526.0741095671569;530.4748299183781;3118.236032172087;3279.8032118072992;3349.296647703961;3411.8976430707116;3429.4684323929832;66719.33280667229;68855;69384;69775;70427.64786176036;61014.69492652508;61662.69168654128;62967.68516157482;63485;63771.36228637654;46125.30812037822;46291.537084629395;50510.49489505058;51252.231216531785;52434.475655243725;6477.740890364333;6553.115329430466;6644.534882558174;6756.966215168893;6976.651167441709;37914.8104259477;38798.80600596979;39006.4149037759;39744.60255397409;40328.1934361317;50752.746236268584;51942;52335.214971774694;52344;52470.73764631152;9903.851442228228;10018.1985441166;10056.14522765544;10120.291579589359;10441.112505437122;10894.07654438633;10979.39613321243;11063.668089957315;11290;11713.941430292794;9689.41863488193;9810.411375317504;9947.552360143665;10145.64490242838;10367;9982.151517120836;10162;10295.897041029793;10494.265401421828;10647;10274.794504110025;10570.365778053341;10617.044465998097;10633.361998280128;10779.137668986305;8508.574571271536;8721.956390218009;8836.337274704552;8855.645774168961;9032.63869445215;8565.657373705231;9046.547672616474;9088.272938165062;9226.630934762534;9239.39943903644 23:29:25 INFO - PROCESS | 2400 | __end_tp_report 23:29:25 INFO - PROCESS | 2400 | __start_cc_report 23:29:25 INFO - PROCESS | 2400 | _x_x_mozilla_cycle_collect,1342 23:29:25 INFO - PROCESS | 2400 | __end_cc_report 23:29:25 INFO - PROCESS | 2400 | __startTimestamp1480145365895__endTimestamp 23:29:25 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | ------- Summary: start ------- 23:29:25 INFO - PROCESS | 2400 | Number of tests: 6 23:29:25 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | [#0] dojo.html Cycles:165 Average:3909.77 Median:4083.29 stddev:462.77 (11.3%) stddev-sans-first:447.25 23:29:25 INFO - PROCESS | 2400 | Values: 2328.3 2342.2 2355.2 2375.3 2390.9 4021.5 4050.2 4123.6 4125.8 4135.0 3926.6 3980.8 4058.4 4124.1 4162.9 3465.4 3558.7 3591.1 3608.0 3636.7 3369.9 3597.0 3597.1 3624.2 3646.3 3544.5 3566.8 3584.3 3595.4 3652.3 3173.2 3549.8 3589.0 3592.7 3659.3 3391.2 3468.6 3597.1 3603.6 3607.8 3969.9 3977.0 4054.9 4063.7 4072.9 3950.7 4029.2 4139.3 4142.2 4290.9 3914.7 4108.2 4128.3 4129.6 4153.7 3846.5 3964.5 4020.1 4052.0 4069.7 4339.1 4350.0 4410.8 4435.5 4437.9 4022.0 4188.7 4267.2 4269.1 4270.5 4114.7 4120.7 4159.1 4236.5 4250.1 4173.2 4236.8 4252.1 4253.4 4260.1 4187.1 4212.7 4245.9 4255.4 4263.7 2898.8 3009.8 3043.7 3045.0 3121.7 2728.4 2776.9 2788.4 2803.0 2812.8 3943.2 3999.5 4057.8 4083.3 4086.5 4211.4 4226.9 4301.0 4374.9 4384.8 3862.4 3902.7 3921.0 3951.5 3979.3 3484.8 3553.1 3586.0 3663.9 3673.6 4162.5 4186.0 4301.1 4376.9 4406.6 4136.8 4149.3 4182.9 4210.2 4300.0 4217.7 4296.5 4324.2 4331.1 4387.7 4085.6 4088.9 4193.8 4207.8 4207.8 4104.1 4123.8 4261.1 4262.6 4271.2 4048.6 4078.4 4120.2 4163.8 4183.9 3961.2 4083.6 4182.3 4201.3 4210.3 3904.3 3956.8 3991.3 4010.7 4109.9 3873.9 4035.5 4038.6 4141.3 4210.8 4140.4 4150.4 4184.1 4204.7 4212.2 23:29:25 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | [#1] ext.html Cycles:180 Average:36817.67 Median:31673.60 stddev:60691.22 (191.6%) stddev-sans-first:60800.41 23:29:25 INFO - PROCESS | 2400 | Values: 557.9 561.2 573.9 574.4 588.3 24956.0 26623.5 26702.0 26712.2 26714.1 26657.6 36880.4 37373.8 38023.2 38036.2 6995.0 7103.3 7116.8 7117.9 7121.1 7016.8 7087.0 7088.7 7094.2 7097.6 6303.1 6426.6 9007.7 9216.0 9220.7 9137.3 9186.8 9190.4 9210.3 9228.3 9012.7 9062.9 9078.1 9084.0 9087.5 26136.5 26543.9 32024.8 32083.0 32567.7 2505.8 2512.4 2522.2 2543.7 2582.4 23920.4 24298.6 24549.6 24827.4 25039.1 2887.9 3001.3 3006.8 3037.4 3091.3 200417.0 200486.0 201218.0 201616.0 202705.0 45268.0 45699.0 45714.8 45773.0 45855.0 45756.8 47455.0 47507.3 47507.5 47544.8 10232.3 10358.5 10518.3 10530.0 10566.1 7662.9 7668.8 7691.3 7713.0 7717.5 12572.2 14507.3 14571.3 14622.4 14660.2 4894.7 5636.3 5865.8 5878.6 5997.5 696.4 700.0 811.1 826.7 840.1 23199.7 23567.0 23886.3 23937.8 24088.5 863.1 871.9 908.9 909.2 953.1 12287.9 12957.5 12973.8 13063.5 13130.9 122657.0 279499.0 281408.3 281508.0 281719.0 41662.8 42035.4 42088.2 42148.8 42256.4 918.5 935.9 1048.0 1062.6 1076.3 22718.5 24718.4 25220.5 25239.0 25259.6 36237.0 36265.6 36646.8 36716.6 36756.8 218344.0 235716.0 237638.0 238576.0 239726.0 19367.0 20505.8 20549.4 31442.4 31830.7 36154.6 36562.1 36668.3 36712.3 36911.1 29454.6 29876.4 31113.2 31127.0 31930.4 30136.8 30229.4 30267.6 30679.5 31516.5 28643.3 31160.5 32401.5 32686.0 32738.0 27763.7 28816.3 28910.7 28926.4 29307.6 20587.5 21795.5 22033.0 22412.8 22791.4 23:29:25 INFO - PROCESS | 2400 | 23:29:25 INFO - PROCESS | 2400 | [#2] jquery.html Cycles:180 Average:19351.40 Median:5143.24 stddev:45046.75 (875.8%) stddev-sans-first:45150.37 23:29:25 INFO - PROCESS | 2400 | Values: 286.4 311.6 313.6 317.0 320.9 11201.3 11965.5 14849.4 14953.9 15319.8 13480.7 14229.0 14824.6 15135.4 15147.5 4480.3 4597.2 4811.3 4824.8 4843.9 3467.9 3628.9 4731.8 4805.0 5254.0 5404.4 5458.1 5583.0 5585.4 5593.8 5143.7 5310.0 5490.8 5563.9 5569.8 4660.1 4804.7 4823.0 4825.9 4846.2 18516.7 18749.6 19218.7 19267.4 19306.3 1711.3 1738.6 1750.4 1973.3 2218.5 7349.3 7397.9 7779.0 7819.3 8020.4 1487.8 1501.7 1502.5 1555.3 1575.7 59699.0 61882.7 62421.0 63461.7 64858.4 8142.5 8196.9 8413.1 8432.3 8571.7 9239.7 9598.7 9640.7 9644.4 10686.9 3767.1 3834.0 3836.3 3855.3 5070.9 5142.8 5183.8 5282.9 5447.0 5462.3 3319.6 3395.4 3399.0 3450.3 3455.5 1393.0 1404.3 1464.0 1489.0 1508.6 706.0 780.5 830.3 862.6 862.8 14871.9 15144.4 15287.8 15383.9 15590.7 784.7 785.2 792.6 808.4 832.9 8357.8 8429.6 8582.1 8624.1 8627.6 261663.0 265177.0 269024.0 271126.0 271975.0 52622.5 66343.7 68920.2 70378.6 71405.6 733.7 754.4 761.1 769.1 771.4 14443.8 14647.8 14699.7 14766.3 14844.0 35786.3 36208.5 37022.8 37131.8 38352.4 49743.8 50499.5 50849.5 50958.5 51107.0 6297.7 6308.0 6420.4 6481.2 6540.4 9107.5 10371.9 10603.4 10810.3 10890.7 6963.8 7266.0 8954.4 8956.3 9174.8 8712.2 8961.4 9102.4 9221.1 9402.3 4386.8 8404.5 8652.7 8802.2 9511.7 5358.1 5436.4 5733.2 5804.7 5814.0 8004.7 8042.4 8844.9 9002.5 9219.3 23:29:26 INFO - PROCESS | 2400 | 23:29:26 INFO - PROCESS | 2400 | [#3] mootools.html Cycles:180 Average:3874.56 Median:4840.81 stddev:2118.11 (43.8%) stddev-sans-first:2113.89 23:29:26 INFO - PROCESS | 2400 | Values: 1113.4 1154.2 1211.1 1383.7 1445.1 5759.1 5781.0 6201.2 6218.9 6223.8 5207.4 5382.9 5951.3 6110.6 6121.7 1742.2 1769.3 1770.9 1772.8 1775.4 1699.1 1724.4 1745.2 1771.4 1789.5 1474.3 1511.9 1685.2 1722.4 1733.6 1723.4 1726.8 1727.0 1728.4 1736.2 1676.5 1689.1 1690.4 1717.9 1719.5 5773.0 5852.1 5855.5 5868.5 5976.7 3484.5 3490.4 3516.0 3570.0 3603.4 2280.1 2348.1 2388.8 2396.6 2418.0 741.6 745.4 779.3 782.7 789.4 7797.9 7818.5 7844.5 7848.8 7866.1 3077.8 3214.1 3263.6 3338.7 3346.8 6840.4 6840.9 6846.2 6855.1 6862.6 3616.2 3628.7 3631.9 3635.3 3637.6 3065.0 3166.3 3260.8 3281.2 3285.4 2778.0 2781.6 2823.5 2869.2 2892.2 978.8 985.2 991.5 995.3 996.8 672.2 677.7 679.8 694.1 708.4 3807.5 3867.1 3870.8 3940.3 3946.9 317.4 323.5 327.4 330.7 330.7 2572.7 2591.7 2707.3 2717.0 2726.3 6415.5 6801.0 7143.7 7195.8 7197.8 6713.8 6954.7 7118.4 7138.6 7153.5 5589.0 5637.7 5661.5 5677.4 5699.6 3830.5 3875.8 3913.7 3923.7 3938.5 6551.1 6601.2 6607.9 6642.0 6644.4 6551.0 6685.6 6730.1 6760.7 6776.5 4955.7 4959.9 5141.3 5166.2 5243.5 5483.2 5573.1 5627.5 5633.0 5635.6 4858.1 4882.4 5069.5 5096.9 5135.8 4959.3 4973.9 5101.7 5127.6 5131.4 4840.1 4841.5 4860.2 4969.9 4974.1 3183.6 3197.0 3272.8 3282.5 3358.6 3402.3 3441.4 3442.3 3460.7 3487.3 23:29:26 INFO - PROCESS | 2400 | 23:29:26 INFO - PROCESS | 2400 | [#4] prototype.html Cycles:180 Average:3733.90 Median:4301.71 stddev:1101.50 (25.6%) stddev-sans-first:1072.69 23:29:26 INFO - PROCESS | 2400 | Values: 227.2 234.9 245.2 253.6 254.2 4195.6 4362.9 4479.3 4489.5 4507.6 4334.8 4416.3 4418.0 4420.4 4433.7 3123.3 3217.9 3224.6 3267.5 3308.3 3181.2 3203.6 3278.1 3301.7 3304.2 3261.4 3266.1 3274.2 3277.5 3280.7 3234.7 3259.8 3273.5 3276.4 3280.7 2711.5 2781.0 2803.8 2846.8 2854.9 4258.2 4265.9 4268.8 4269.9 4279.6 3835.0 3841.6 3841.7 3842.9 3853.8 4514.8 4521.8 4524.8 4538.9 4553.0 3126.7 3228.9 3232.1 3234.6 3242.2 4667.8 4697.3 4698.0 4721.2 4727.5 3202.6 3225.4 3270.5 3313.8 3318.2 3286.9 3327.4 3354.8 3356.5 3360.8 3756.2 3821.4 3826.8 3831.2 3843.3 4310.7 4514.3 4564.0 4567.5 4569.4 1066.2 1135.9 1138.4 1144.3 1146.8 769.8 770.5 779.1 784.9 789.9 4196.7 4221.6 4224.8 4227.0 4238.3 4570.9 4577.0 4589.7 4606.2 4616.9 4202.3 4220.3 4234.3 4236.8 4239.6 2395.8 2413.8 2432.5 2439.3 2448.7 4406.3 4516.8 4569.4 4616.2 4661.7 3145.0 4405.8 4430.6 4438.9 4602.9 4293.6 4344.9 4373.6 4387.7 4447.9 4143.9 4309.8 4320.4 4433.3 4445.1 4281.0 4447.2 4479.9 4489.2 4513.8 4670.8 4673.5 4688.9 4688.9 4696.1 3234.8 3247.1 3248.2 3266.0 3273.8 4512.2 4516.4 4522.9 4547.3 4555.3 4356.7 4399.4 4412.4 4451.6 4467.5 4446.8 4486.9 4511.5 4520.6 4533.3 4402.1 4412.4 4412.8 4425.6 4425.6 4516.6 4535.9 4549.9 4565.0 4572.2 4289.5 4472.1 4479.2 4487.4 4489.7 23:29:26 INFO - PROCESS | 2400 | 23:29:26 INFO - PROCESS | 2400 | [#5] yui.html Cycles:180 Average:14013.25 Median:25346.78 stddev:18749.37 (74.0%) stddev-sans-first:18774.71 23:29:26 INFO - PROCESS | 2400 | Values: 549.2 587.9 592.6 596.1 645.7 10286.4 10774.9 10888.3 11192.4 11334.4 10198.8 10343.8 10449.3 10493.3 10516.7 488.4 496.3 581.3 604.5 618.2 615.2 615.7 621.5 624.6 625.5 660.8 660.9 662.4 663.2 663.4 501.1 514.6 631.0 650.8 654.0 470.2 470.3 478.9 479.2 479.7 10745.5 10767.8 10771.4 10775.5 10798.5 10168.5 10603.7 10673.8 10799.1 10934.5 8369.5 8627.6 9153.7 10195.1 10845.3 2793.3 2812.6 2850.1 2939.4 2982.0 48803.5 50192.5 51168.5 51382.2 52133.0 7983.2 8496.0 8761.4 8912.8 8999.1 15268.4 15332.2 15365.8 15397.6 15487.2 5621.6 5702.1 5709.4 5768.2 5835.6 3108.7 3120.1 3174.6 3215.0 3261.1 2023.4 2246.0 2248.3 2252.6 2417.4 408.0 411.4 418.0 421.5 422.7 430.6 507.1 530.0 545.1 546.4 6162.4 6561.2 6691.3 6725.0 6736.8 492.3 494.0 517.8 526.1 530.5 3118.2 3279.8 3349.3 3411.9 3429.5 66719.3 68855.0 69384.0 69775.0 70427.6 61014.7 61662.7 62967.7 63485.0 63771.4 46125.3 46291.5 50510.5 51252.2 52434.5 6477.7 6553.1 6644.5 6757.0 6976.7 37914.8 38798.8 39006.4 39744.6 40328.2 50752.7 51942.0 52335.2 52344.0 52470.7 9903.9 10018.2 10056.1 10120.3 10441.1 10894.1 10979.4 11063.7 11290.0 11713.9 9689.4 9810.4 9947.6 10145.6 10367.0 9982.2 10162.0 10295.9 10494.3 10647.0 10274.8 10570.4 10617.0 10633.4 10779.1 8508.6 8722.0 8836.3 8855.6 9032.6 8565.7 9046.5 9088.3 9226.6 9239.4 23:29:26 INFO - PROCESS | 2400 | -------- Summary: end -------- 23:29:26 INFO - PROCESS | 2400 | 23:29:26 INFO - TEST-INFO | 2400: exit 0 23:29:27 INFO - TEST-OK | dromaeo_css | took 1127242ms 23:29:27 INFO - TEST-START | kraken 23:29:27 INFO - operating with platform_type : w7_ 23:29:27 INFO - Initialising browser for kraken test... 23:29:27 INFO - TEST-INFO | started process 1748 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpuwostz\profile http://localhost:50000/getInfo.html) 23:29:32 INFO - PROCESS | 1748 | __metrics Screen width/height:1600/1200 23:29:32 INFO - PROCESS | 1748 | colorDepth:24 23:29:32 INFO - PROCESS | 1748 | Browser inner width/height: 1010/674 23:29:32 INFO - PROCESS | 1748 | __metrics 23:29:32 INFO - PROCESS | 1748 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:29:32 INFO - PROCESS | 1748 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:29:33 INFO - PROCESS | 1748 | console.error: 23:29:33 INFO - PROCESS | 1748 | Message: Error: Connection closed before committing the transaction. 23:29:33 INFO - PROCESS | 1748 | Stack: 23:29:33 INFO - PROCESS | 1748 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 23:29:33 INFO - PROCESS | 1748 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 23:29:33 INFO - PROCESS | 1748 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 23:29:33 INFO - PROCESS | 1748 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 23:29:33 INFO - PROCESS | 1748 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 23:29:33 INFO - PROCESS | 1748 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 23:29:33 INFO - PROCESS | 1748 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 23:29:33 INFO - PROCESS | 1748 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 23:29:33 INFO - PROCESS | 1748 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 23:29:33 INFO - PROCESS | 1748 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 23:29:33 INFO - PROCESS | 1748 | Barrier/this.client.addBlocker/promise13.4% 23:30:34 INFO - TinderboxPrint: I/O read bytes / time
443,046,912 / 3,699,110 23:30:34 INFO - TinderboxPrint: I/O write bytes / time
519,454,208 / 3,858,720 23:30:34 INFO - TinderboxPrint: CPU idle
8,311.8 (86.6%) 23:30:34 INFO - TinderboxPrint: CPU user
1,237.2 (12.9%) 23:30:34 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 32768; Write bytes: 35853824; Read time: 2920; Write time: 220420 23:30:34 INFO - run-tests - Wall time: 1197s; CPU: 13.0%; Read bytes: 443014144; Write bytes: 446412800; Read time: 3696190; Write time: 3602330 23:30:35 INFO - Running post-run listener: _upload_blobber_files 23:30:35 INFO - Blob upload gear active. 23:30:35 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 23:30:35 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 23:30:35 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', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 23:30:35 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 Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 23:30:35 INFO - (blobuploader) - INFO - Open directory for files ... 23:30:35 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 23:30:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:30:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:30:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:30:36 INFO - (blobuploader) - INFO - Done attempting. 23:30:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 23:30:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:30:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:30:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:30:37 INFO - (blobuploader) - INFO - Done attempting. 23:30:37 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 23:30:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:30:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:30:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:30:45 INFO - (blobuploader) - INFO - Done attempting. 23:30:45 INFO - (blobuploader) - INFO - Iteration through files over. 23:30:45 INFO - Return code: 0 23:30:45 INFO - rmtree: C:\slave\test\build\uploaded_files.json 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 23:30:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54aed5c0c6373842381b0f280eaee682076ee24b484ca0f55700d61deb8bdd125e8fb4cdb098c62583df046f4d66b5c757a187833df2c08d813ffddbe4256d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b57ca21a2a5d7c4d5f999dae6698b4ceb1908d3ad312512a3808720339bed5f256ab386fe208e43efb4b5521f55b2057bbc3a41d51e1a8de2511f387cd466d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fedd88a0da10e29b9a8b42f0e5f35e6dff39b817b7c7e65b7a8b5918692c05608f6f715fa1995a62c26b3416d5e53bf9e316386ed66820699e5d96c2587ca9d0"} 23:30:45 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 23:30:45 INFO - Writing to file C:\slave\test\properties\blobber_files 23:30:45 INFO - Contents: 23:30:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54aed5c0c6373842381b0f280eaee682076ee24b484ca0f55700d61deb8bdd125e8fb4cdb098c62583df046f4d66b5c757a187833df2c08d813ffddbe4256d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b57ca21a2a5d7c4d5f999dae6698b4ceb1908d3ad312512a3808720339bed5f256ab386fe208e43efb4b5521f55b2057bbc3a41d51e1a8de2511f387cd466d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fedd88a0da10e29b9a8b42f0e5f35e6dff39b817b7c7e65b7a8b5918692c05608f6f715fa1995a62c26b3416d5e53bf9e316386ed66820699e5d96c2587ca9d0"} 23:30:45 INFO - Running post-run listener: copy_logs_to_upload_dir 23:30:45 INFO - Copying logs to upload dir... 23:30:45 INFO - mkdir: C:\slave\test\build\upload\logs 23:30:45 INFO - Copying logs to upload dir... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... 23:30:45 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1395.093000 ========= master_lag: 0.65 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 15 secs) (at 2016-11-25 23:30:45.825224) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 23:30:45.826049) ========= '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-144 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-144 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-144 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/Firefox-Non-PGO/sha512/54aed5c0c6373842381b0f280eaee682076ee24b484ca0f55700d61deb8bdd125e8fb4cdb098c62583df046f4d66b5c757a187833df2c08d813ffddbe4256d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b57ca21a2a5d7c4d5f999dae6698b4ceb1908d3ad312512a3808720339bed5f256ab386fe208e43efb4b5521f55b2057bbc3a41d51e1a8de2511f387cd466d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fedd88a0da10e29b9a8b42f0e5f35e6dff39b817b7c7e65b7a8b5918692c05608f6f715fa1995a62c26b3416d5e53bf9e316386ed66820699e5d96c2587ca9d0"} build_url:https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/HEVwpJTBSZqytZgSOIq-Fw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/54aed5c0c6373842381b0f280eaee682076ee24b484ca0f55700d61deb8bdd125e8fb4cdb098c62583df046f4d66b5c757a187833df2c08d813ffddbe4256d8b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3b57ca21a2a5d7c4d5f999dae6698b4ceb1908d3ad312512a3808720339bed5f256ab386fe208e43efb4b5521f55b2057bbc3a41d51e1a8de2511f387cd466d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/fedd88a0da10e29b9a8b42f0e5f35e6dff39b817b7c7e65b7a8b5918692c05608f6f715fa1995a62c26b3416d5e53bf9e316386ed66820699e5d96c2587ca9d0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 23:30:45.941142) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:30:45.941495) ========= '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-144 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-144 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-144 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-25 23:30:46.052063) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 23:30:46.052456) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 23:30:46.594343) ========= ========= Total master_lag: 0.84 =========