builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-068 starttime: 1476798855.69 results: success (0) buildid: 20161018065414 builduid: eb2f61481b8846a9aa3e875ae6855142 revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.688458) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.688907) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.689192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:15.835147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 06:54:15.835558) ========= '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-068 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-068 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-068 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --06:54:16-- 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% 2.46 GB/s 06:54:16 (2.46 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.165000 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 06:54:17.135231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 18 secs) (at 2016-10-18 06:54:17.135571) ========= '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-068 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-068 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-068 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=16.304000 ========= master_lag: 1.97 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 18 secs) (at 2016-10-18 06:54:35.405068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-18 06:54:35.405396) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-068 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-068 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-068 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-10-18 06:54:35,562 truncating revision to first 12 chars 2016-10-18 06:54:35,562 Setting DEBUG logging. 2016-10-18 06:54:35,562 attempt 1/10 2016-10-18 06:54:35,562 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 06:54:36,098 unpacking tar archive at: fx-team-dc89484d4b45/testing/mozharness/ 2016-10-18 06:54:36,221 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.330000 ========= master_lag: 4.41 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-18 06:54:41.148021) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.154337) ========= script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.154765) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.155037) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:54:41.183884) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 12 secs) (at 2016-10-18 06:54:41.184155) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-068 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-068 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-068 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 06:54:41 INFO - MultiFileLogger online at 20161018 06:54:41 in C:\slave\test 06:54:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:54:41 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:54:41 INFO - {'append_to_log': False, 06:54:41 INFO - 'base_work_dir': 'C:\\slave\\test', 06:54:41 INFO - 'blob_upload_branch': 'Fx-Team', 06:54:41 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:54:41 INFO - 'branch': 'Fx-Team', 06:54:41 INFO - 'buildbot_json_path': 'buildprops.json', 06:54:41 INFO - 'config_files': ('talos/windows_config.py',), 06:54:41 INFO - 'default_actions': ('clobber', 06:54:41 INFO - 'read-buildbot-config', 06:54:41 INFO - 'download-and-extract', 06:54:41 INFO - 'populate-webroot', 06:54:41 INFO - 'create-virtualenv', 06:54:41 INFO - 'install', 06:54:41 INFO - 'run-tests'), 06:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:54:41 INFO - 'download_minidump_stackwalk': True, 06:54:41 INFO - 'download_symbols': 'ondemand', 06:54:41 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:54:41 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:54:41 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:54:41 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:54:41 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:54:41 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:54:41 INFO - 'C:/mozilla-build/tooltool.py'), 06:54:41 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:54:41 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:54:41 INFO - 'installer_path': 'installer.exe', 06:54:41 INFO - 'log_level': 'info', 06:54:41 INFO - 'log_name': 'talos', 06:54:41 INFO - 'log_to_console': True, 06:54:41 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:54:41 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:54:41 INFO - 'opt_config_files': (), 06:54:41 INFO - 'pip_index': False, 06:54:41 INFO - 'sps_profile': False, 06:54:41 INFO - 'sps_profile_interval': 0, 06:54:41 INFO - 'suite': 'dromaeojs', 06:54:41 INFO - 'system_bits': '32', 06:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:54:41 INFO - 'title': 'T-W732-IX-068', 06:54:41 INFO - 'use_talos_json': True, 06:54:41 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:54:41 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:54:41 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:54:41 INFO - 'work_dir': 'build'} 06:54:41 INFO - [mozharness: 2016-10-18 13:54:41.525000Z] Running clobber step. 06:54:41 INFO - Running pre-action listener: _resource_record_pre_action 06:54:41 INFO - Running main action method: clobber 06:54:41 INFO - rmtree: C:\slave\test\build 06:54:41 INFO - Using _rmtree_windows ... 06:54:41 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 06:56:02 INFO - Running post-action listener: _resource_record_post_action 06:56:02 INFO - [mozharness: 2016-10-18 13:56:02.813000Z] Finished clobber step (success) 06:56:02 INFO - [mozharness: 2016-10-18 13:56:02.813000Z] Running read-buildbot-config step. 06:56:02 INFO - Running pre-action listener: _resource_record_pre_action 06:56:02 INFO - Running main action method: read_buildbot_config 06:56:02 INFO - Using buildbot properties: 06:56:02 INFO - { 06:56:02 INFO - "project": "", 06:56:02 INFO - "product": "firefox", 06:56:02 INFO - "script_repo_revision": "production", 06:56:02 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 06:56:02 INFO - "repository": "", 06:56:02 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 06:56:02 INFO - "buildid": "20161018065414", 06:56:02 INFO - "stage_platform": "win32-pgo", 06:56:02 INFO - "basedir": "C:\\slave\\test", 06:56:02 INFO - "buildnumber": 232, 06:56:02 INFO - "platform": "win7_ix", 06:56:02 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 06:56:02 INFO - "slavebuilddir": "test", 06:56:02 INFO - "branch": "fx-team", 06:56:02 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d", 06:56:02 INFO - "slavename": "t-w732-ix-068", 06:56:02 INFO - "builduid": "eb2f61481b8846a9aa3e875ae6855142", 06:56:02 INFO - "repo_path": "integration/fx-team" 06:56:02 INFO - } 06:56:02 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json. 06:56:02 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip. 06:56:02 INFO - Running post-action listener: _resource_record_post_action 06:56:02 INFO - [mozharness: 2016-10-18 13:56:02.816000Z] Finished read-buildbot-config step (success) 06:56:02 INFO - [mozharness: 2016-10-18 13:56:02.817000Z] Running download-and-extract step. 06:56:02 INFO - Running pre-action listener: _resource_record_pre_action 06:56:02 INFO - Running main action method: download_and_extract 06:56:02 INFO - mkdir: C:\slave\test\build\tests 06:56: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')]} 06:56:02 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json 06:56:02 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 06:56:03 INFO - Downloaded 1218 bytes. 06:56:03 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:56:03 INFO - Using the following test package requirements: 06:56:03 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:56:03 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 06:56:03 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:56:03 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'jsshell-win32.zip'], 06:56:03 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 06:56:03 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:56:03 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 06:56:03 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 06:56:03 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 06:56:03 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:56:03 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 06:56:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:56:03 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip 06:56:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:56:03 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:56:03 INFO - Http code: 200 06:56:03 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 06:56:03 INFO - x-amz-cf-id: eqSEuFiybIL1CHRuB0el-qi64_5CF0PsiPQTTkmc1xF7AEpGHgvP0w== 06:56:03 INFO - x-amz-replication-status: COMPLETED 06:56:03 INFO - x-amz-version-id: EhbTUiW.GlyjH7b1xGpDynzanB2KARhs 06:56:04 INFO - Expected file size: 17241912 06:56:04 INFO - Obtained file size: 17241912 06:56:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 06:56:37 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip 06:56:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:56:37 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:56:37 INFO - Http code: 200 06:56:37 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 06:56:37 INFO - x-amz-cf-id: j3stAui95ZU0AC2rq2mrS9r825kbKsPXppVt_EAKFBfQk_Q9azgwXA== 06:56:37 INFO - x-amz-replication-status: COMPLETED 06:56:37 INFO - x-amz-version-id: EhbTUiW.GlyjH7b1xGpDynzanB2KARhs 06:56:38 INFO - Expected file size: 17241912 06:56:38 INFO - Obtained file size: 17241912 06:56:51 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip 06:56:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 06:56:51 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 06:56:51 INFO - Http code: 200 06:56:51 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 06:56:51 INFO - x-amz-cf-id: T5KyTF8u71osYho2bGct6TSHIE3jEJ9Q6kklskxSwEVs-z_BqxWBvQ== 06:56:51 INFO - x-amz-replication-status: COMPLETED 06:56:51 INFO - x-amz-version-id: KpFnLVRf_jW7u4T8KYmqOrTxChuMYPm0 06:56:52 INFO - Expected file size: 14409028 06:56:52 INFO - Obtained file size: 14409028 06:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:56:53 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip 06:56:53 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 06:56:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 06:56:57 INFO - Downloaded 54248074 bytes. 06:56:57 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip 06:56:57 INFO - mkdir: C:\slave\test\properties 06:56:57 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 06:56:57 INFO - Writing to file C:\slave\test\properties\build_url 06:56:57 INFO - Contents: 06:56:57 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip 06:56:58 INFO - Running post-action listener: _resource_record_post_action 06:56:58 INFO - Running post-action listener: set_extra_try_arguments 06:56:58 INFO - [mozharness: 2016-10-18 13:56:58.195000Z] Finished download-and-extract step (success) 06:56:58 INFO - [mozharness: 2016-10-18 13:56:58.195000Z] Running populate-webroot step. 06:56:58 INFO - Running pre-action listener: _resource_record_pre_action 06:56:58 INFO - Running main action method: populate_webroot 06:56:58 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:56:58 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:56:58 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:56:58 INFO - u'--tppagecycles', 06:56:58 INFO - u'7'], 06:56:58 INFO - u'tests': [u'tsvgm']}}, 06:56:58 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:56:58 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 06:56:58 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 06:56:58 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 06:56:58 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:56:58 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:56:58 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'damp', u'tps']}, 06:56:58 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'tests': [u'damp', u'tps']}, 06:56:58 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'dromaeo_dom']}, 06:56:58 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:56:58 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'basic_compositor_video']}, 06:56:58 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 06:56:58 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'other-e10s': {u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'a11yr', 06:56:58 INFO - u'ts_paint', 06:56:58 INFO - u'tpaint', 06:56:58 INFO - u'sessionrestore', 06:56:58 INFO - u'sessionrestore_no_auto_restore', 06:56:58 INFO - u'tabpaint']}, 06:56:58 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'tsvgx', 06:56:58 INFO - u'tsvgr_opacity', 06:56:58 INFO - u'tart', 06:56:58 INFO - u'tscrollx', 06:56:58 INFO - u'cart']}, 06:56:58 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 06:56:58 INFO - u'tsvgr_opacity', 06:56:58 INFO - u'tart', 06:56:58 INFO - u'tscrollx', 06:56:58 INFO - u'cart']}, 06:56:58 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'talos_options': [u'--disable-e10s'], 06:56:58 INFO - u'tests': [u'tp5o']}, 06:56:58 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'tests': [u'tp5o']}, 06:56:58 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'talos_options': [u'--disable-e10s', 06:56:58 INFO - u'--xperf_path', 06:56:58 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:58 INFO - u'tests': [u'tp5n']}, 06:56:58 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:56:58 INFO - u'talos_options': [u'--xperf_path', 06:56:58 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:56:58 INFO - u'tests': [u'tp5n']}}, 06:56:58 INFO - u'talos.zip': {u'path': u'', 06:56:58 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:56:58 INFO - Running post-action listener: _resource_record_post_action 06:56:58 INFO - [mozharness: 2016-10-18 13:56:58.258000Z] Finished populate-webroot step (success) 06:56:58 INFO - [mozharness: 2016-10-18 13:56:58.258000Z] Running create-virtualenv step. 06:56:58 INFO - Running pre-action listener: _resource_record_pre_action 06:56:58 INFO - Running main action method: create_virtualenv 06:56:58 INFO - Creating virtualenv C:\slave\test\build/venv 06:56:58 INFO - mkdir: C:\slave\test\build\venv\Scripts 06:56:58 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 06:56:58 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 06:56:58 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 06:56:58 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 06:57:09 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 06:57:14 INFO - Installing distribute......................................................................................................................................................................................done. 06:57:15 INFO - Return code: 0 06:57:15 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 06:57:15 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 06:57:16 INFO - Reading from file tmpfile_stdout 06:57:16 INFO - Output received: 06:57:16 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 06:57:16 INFO - Using _rmtree_windows ... 06:57:16 INFO - Using _rmtree_windows ... 06:57:16 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 06:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:16 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:16 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 06:57:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:57:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:16 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:16 INFO - 'DCLOCATION': 'SCL3', 06:57:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:16 INFO - 'HOMEDRIVE': 'C:', 06:57:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:16 INFO - 'KTS_VERSION': '1.19c', 06:57:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:16 INFO - 'MOZ_NO_REMOTE': '1', 06:57:16 INFO - 'NO_EM_RESTART': '1', 06:57:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:16 INFO - 'OS': 'Windows_NT', 06:57:16 INFO - 'OURDRIVE': 'C:', 06:57:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:16 INFO - 'PROCESSOR_LEVEL': '6', 06:57:16 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:16 INFO - 'PROMPT': '$P$G', 06:57:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:16 INFO - 'PWD': 'C:\\slave\\test', 06:57:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:16 INFO - 'SYSTEMDRIVE': 'C:', 06:57:16 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:16 INFO - 'TEST1': 'testie', 06:57:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:16 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:16 INFO - 'USERNAME': 'cltbld', 06:57:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:16 INFO - 'WINDIR': 'C:\\windows', 06:57:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:57:17 INFO - Downloading/unpacking pip>=1.5 06:57:21 INFO - Running setup.py egg_info for package pip 06:57:21 INFO - warning: no files found matching 'pip\cacert.pem' 06:57:21 INFO - warning: no files found matching '*.html' under directory 'docs' 06:57:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:57:21 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:57:21 INFO - Installing collected packages: pip 06:57:21 INFO - Found existing installation: pip 0.8.2 06:57:21 INFO - Uninstalling pip: 06:57:21 INFO - Successfully uninstalled pip 06:57:21 INFO - Running setup.py install for pip 06:57:21 INFO - warning: no files found matching 'pip\cacert.pem' 06:57:21 INFO - warning: no files found matching '*.html' under directory 'docs' 06:57:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:57:21 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:57:21 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 06:57:21 INFO - Successfully installed pip 06:57:21 INFO - Cleaning up... 06:57:21 INFO - Return code: 0 06:57:21 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 06:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:21 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 06:57:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:57:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:21 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:21 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:21 INFO - 'DCLOCATION': 'SCL3', 06:57:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:21 INFO - 'HOMEDRIVE': 'C:', 06:57:21 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:21 INFO - 'KTS_VERSION': '1.19c', 06:57:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:21 INFO - 'MOZ_NO_REMOTE': '1', 06:57:21 INFO - 'NO_EM_RESTART': '1', 06:57:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:21 INFO - 'OS': 'Windows_NT', 06:57:21 INFO - 'OURDRIVE': 'C:', 06:57:21 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:21 INFO - 'PROCESSOR_LEVEL': '6', 06:57:21 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:21 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:21 INFO - 'PROMPT': '$P$G', 06:57:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:21 INFO - 'PWD': 'C:\\slave\\test', 06:57:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:21 INFO - 'SYSTEMDRIVE': 'C:', 06:57:21 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:21 INFO - 'TEST1': 'testie', 06:57:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:21 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:21 INFO - 'USERNAME': 'cltbld', 06:57:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:21 INFO - 'WINDIR': 'C:\\windows', 06:57:21 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:23 INFO - Downloading/unpacking psutil>=3.1.1 06:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:24 INFO - Installing collected packages: psutil 06:57:24 INFO - Successfully installed psutil 06:57:24 INFO - Cleaning up... 06:57:24 INFO - Return code: 0 06:57:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 06:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:24 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 06:57:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:57:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:24 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:24 INFO - 'DCLOCATION': 'SCL3', 06:57:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:24 INFO - 'HOMEDRIVE': 'C:', 06:57:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:24 INFO - 'KTS_VERSION': '1.19c', 06:57:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:24 INFO - 'MOZ_NO_REMOTE': '1', 06:57:24 INFO - 'NO_EM_RESTART': '1', 06:57:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:24 INFO - 'OS': 'Windows_NT', 06:57:24 INFO - 'OURDRIVE': 'C:', 06:57:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:24 INFO - 'PROCESSOR_LEVEL': '6', 06:57:24 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:24 INFO - 'PROMPT': '$P$G', 06:57:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:24 INFO - 'PWD': 'C:\\slave\\test', 06:57:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:24 INFO - 'SYSTEMDRIVE': 'C:', 06:57:24 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:24 INFO - 'TEST1': 'testie', 06:57:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:24 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:24 INFO - 'USERNAME': 'cltbld', 06:57:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:24 INFO - 'WINDIR': 'C:\\windows', 06:57:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:57:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 06:57:25 INFO - Installing collected packages: mozsystemmonitor 06:57:25 INFO - Running setup.py install for mozsystemmonitor 06:57:26 INFO - Successfully installed mozsystemmonitor 06:57:26 INFO - Cleaning up... 06:57:26 INFO - Return code: 0 06:57:26 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:26 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:26 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 06:57:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:57:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:26 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:26 INFO - 'DCLOCATION': 'SCL3', 06:57:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:26 INFO - 'HOMEDRIVE': 'C:', 06:57:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:26 INFO - 'KTS_VERSION': '1.19c', 06:57:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:26 INFO - 'MOZ_NO_REMOTE': '1', 06:57:26 INFO - 'NO_EM_RESTART': '1', 06:57:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:26 INFO - 'OS': 'Windows_NT', 06:57:26 INFO - 'OURDRIVE': 'C:', 06:57:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:26 INFO - 'PROCESSOR_LEVEL': '6', 06:57:26 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:26 INFO - 'PROMPT': '$P$G', 06:57:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:26 INFO - 'PWD': 'C:\\slave\\test', 06:57:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:26 INFO - 'SYSTEMDRIVE': 'C:', 06:57:26 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:26 INFO - 'TEST1': 'testie', 06:57:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:26 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:26 INFO - 'USERNAME': 'cltbld', 06:57:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:26 INFO - 'WINDIR': 'C:\\windows', 06:57:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:26 INFO - Downloading/unpacking jsonschema==2.5.1 06:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:57:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:27 INFO - Downloading functools32-3.2.3-2.tar.gz 06:57:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 06:57:27 INFO - warning: no files found matching '*.txt' 06:57:27 INFO - no previously-included directories found matching 'build' 06:57:27 INFO - no previously-included directories found matching 'dist' 06:57:27 INFO - no previously-included directories found matching '.git*' 06:57:27 INFO - Installing collected packages: jsonschema, functools32 06:57:28 INFO - Running setup.py install for functools32 06:57:28 INFO - warning: no files found matching '*.txt' 06:57:28 INFO - no previously-included directories found matching 'build' 06:57:28 INFO - no previously-included directories found matching 'dist' 06:57:28 INFO - no previously-included directories found matching '.git*' 06:57:28 INFO - Successfully installed jsonschema functools32 06:57:28 INFO - Cleaning up... 06:57:28 INFO - Return code: 0 06:57:28 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 06:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:28 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 06:57:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:57:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:28 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:28 INFO - 'DCLOCATION': 'SCL3', 06:57:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:28 INFO - 'HOMEDRIVE': 'C:', 06:57:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:28 INFO - 'KTS_VERSION': '1.19c', 06:57:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:28 INFO - 'MOZ_NO_REMOTE': '1', 06:57:28 INFO - 'NO_EM_RESTART': '1', 06:57:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:28 INFO - 'OS': 'Windows_NT', 06:57:28 INFO - 'OURDRIVE': 'C:', 06:57:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:28 INFO - 'PROCESSOR_LEVEL': '6', 06:57:28 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:28 INFO - 'PROMPT': '$P$G', 06:57:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:28 INFO - 'PWD': 'C:\\slave\\test', 06:57:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:28 INFO - 'SYSTEMDRIVE': 'C:', 06:57:28 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:28 INFO - 'TEST1': 'testie', 06:57:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:28 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:28 INFO - 'USERNAME': 'cltbld', 06:57:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:28 INFO - 'WINDIR': 'C:\\windows', 06:57:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 06:57:29 INFO - Cleaning up... 06:57:29 INFO - Return code: 0 06:57:29 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 06:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:29 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 06:57:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:57:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:29 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:29 INFO - 'DCLOCATION': 'SCL3', 06:57:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:29 INFO - 'HOMEDRIVE': 'C:', 06:57:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:29 INFO - 'KTS_VERSION': '1.19c', 06:57:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:29 INFO - 'MOZ_NO_REMOTE': '1', 06:57:29 INFO - 'NO_EM_RESTART': '1', 06:57:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:29 INFO - 'OS': 'Windows_NT', 06:57:29 INFO - 'OURDRIVE': 'C:', 06:57:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:29 INFO - 'PROCESSOR_LEVEL': '6', 06:57:29 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:29 INFO - 'PROMPT': '$P$G', 06:57:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:29 INFO - 'PWD': 'C:\\slave\\test', 06:57:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:29 INFO - 'SYSTEMDRIVE': 'C:', 06:57:29 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:29 INFO - 'TEST1': 'testie', 06:57:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:29 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:29 INFO - 'USERNAME': 'cltbld', 06:57:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:29 INFO - 'WINDIR': 'C:\\windows', 06:57:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:29 INFO - Downloading/unpacking blobuploader==1.2.4 06:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:30 INFO - Downloading blobuploader-1.2.4.tar.gz 06:57:30 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:57:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:30 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 06:57:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:31 INFO - Downloading docopt-0.6.1.tar.gz 06:57:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 06:57:31 INFO - Installing collected packages: blobuploader, requests, docopt 06:57:31 INFO - Running setup.py install for blobuploader 06:57:31 INFO - Running setup.py install for requests 06:57:32 INFO - Running setup.py install for docopt 06:57:32 INFO - Successfully installed blobuploader requests docopt 06:57:32 INFO - Cleaning up... 06:57:33 INFO - Return code: 0 06:57:33 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:57: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')]} 06:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57: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')]} 06:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:33 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 06:57:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:57:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:33 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:33 INFO - 'DCLOCATION': 'SCL3', 06:57:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:33 INFO - 'HOMEDRIVE': 'C:', 06:57:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:33 INFO - 'KTS_VERSION': '1.19c', 06:57:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:33 INFO - 'MOZ_NO_REMOTE': '1', 06:57:33 INFO - 'NO_EM_RESTART': '1', 06:57:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:33 INFO - 'OS': 'Windows_NT', 06:57:33 INFO - 'OURDRIVE': 'C:', 06:57: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;', 06:57:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:33 INFO - 'PROCESSOR_LEVEL': '6', 06:57:33 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:33 INFO - 'PROMPT': '$P$G', 06:57:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:33 INFO - 'PWD': 'C:\\slave\\test', 06:57:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:33 INFO - 'SYSTEMDRIVE': 'C:', 06:57:33 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:33 INFO - 'TEST1': 'testie', 06:57:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:33 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:33 INFO - 'USERNAME': 'cltbld', 06:57:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:33 INFO - 'WINDIR': 'C:\\windows', 06:57:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yt9z8h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-grqqw1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4rjxqe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvryxa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7qxi8i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qnrly6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:57:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:57:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bruzyn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wj2kem-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ltqp_o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2viwue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yg7zwj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y_62pr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-skt05c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:57:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:57:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-us0jwe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:57:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:57:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owl_wh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:57:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:57:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l9yezp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:57:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:57:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y23sge-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:57:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:57:36 INFO - Running setup.py install for manifestparser 06:57:36 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 06:57:36 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 06:57:37 INFO - Running setup.py install for mozcrash 06:57:37 INFO - Running setup.py install for mozdebug 06:57:37 INFO - Running setup.py install for mozdevice 06:57:37 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 06:57:37 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 06:57:37 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 06:57:37 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 06:57:38 INFO - Running setup.py install for mozfile 06:57:38 INFO - Running setup.py install for mozhttpd 06:57:38 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 06:57:38 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Running setup.py install for mozinfo 06:57:39 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Running setup.py install for mozInstall 06:57:39 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 06:57:39 INFO - Running setup.py install for mozleak 06:57:40 INFO - Running setup.py install for mozlog 06:57:40 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 06:57:40 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 06:57:41 INFO - Running setup.py install for moznetwork 06:57:41 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 06:57:41 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 06:57:41 INFO - Running setup.py install for mozprocess 06:57:41 INFO - Running setup.py install for mozprofile 06:57:42 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Running setup.py install for mozrunner 06:57:42 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 06:57:42 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 06:57:43 INFO - Running setup.py install for mozscreenshot 06:57:43 INFO - Running setup.py install for moztest 06:57:43 INFO - Running setup.py install for mozversion 06:57:44 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 06:57:44 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 06:57:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:57:44 INFO - Cleaning up... 06:57:44 INFO - Return code: 0 06:57:44 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:44 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:44 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 06:57:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:57:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:44 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:44 INFO - 'DCLOCATION': 'SCL3', 06:57:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:44 INFO - 'HOMEDRIVE': 'C:', 06:57:44 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:44 INFO - 'KTS_VERSION': '1.19c', 06:57:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:44 INFO - 'MOZ_NO_REMOTE': '1', 06:57:44 INFO - 'NO_EM_RESTART': '1', 06:57:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:44 INFO - 'OS': 'Windows_NT', 06:57:44 INFO - 'OURDRIVE': 'C:', 06:57:44 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:44 INFO - 'PROCESSOR_LEVEL': '6', 06:57:44 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:44 INFO - 'PROMPT': '$P$G', 06:57:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:44 INFO - 'PWD': 'C:\\slave\\test', 06:57:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:44 INFO - 'SYSTEMDRIVE': 'C:', 06:57:44 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:44 INFO - 'TEST1': 'testie', 06:57:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:44 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:44 INFO - 'USERNAME': 'cltbld', 06:57:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:44 INFO - 'WINDIR': 'C:\\windows', 06:57:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:57:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vkprz4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 06:57:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:57:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aams9w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5bvhyr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zdief6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zgl_5q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jstuzs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hohzs0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 06:57:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:57:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kpkszo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dv6cgu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c66u64-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lfas6d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-am6lob-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frb_1a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fa3tdc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:57:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 06:57:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:57:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-71kivb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 06:57:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:57:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zhmavl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 06:57:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:57:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eols5e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:57:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:57:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:57:48 INFO - Downloading blessings-1.6.tar.gz 06:57:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 06:57:49 INFO - Installing collected packages: blessings 06:57:49 INFO - Running setup.py install for blessings 06:57:49 INFO - Successfully installed blessings 06:57:49 INFO - Cleaning up... 06:57:52 INFO - Return code: 0 06:57:52 INFO - Done creating virtualenv C:\slave\test\build/venv. 06:57:52 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:57:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:57:52 INFO - Reading from file tmpfile_stdout 06:57:52 INFO - Using _rmtree_windows ... 06:57:52 INFO - Using _rmtree_windows ... 06:57:52 INFO - Current package versions: 06:57:52 INFO - blessings == 1.6 06:57:52 INFO - blobuploader == 1.2.4 06:57:52 INFO - docopt == 0.6.1 06:57:52 INFO - functools32 == 3.2.3-2 06:57:52 INFO - jsonschema == 2.5.1 06:57:52 INFO - manifestparser == 1.1 06:57:52 INFO - mozInstall == 1.12 06:57:52 INFO - mozcrash == 0.17 06:57:52 INFO - mozdebug == 0.1 06:57:52 INFO - mozdevice == 0.48 06:57:52 INFO - mozfile == 1.2 06:57:52 INFO - mozhttpd == 0.7 06:57:52 INFO - mozinfo == 0.9 06:57:52 INFO - mozleak == 0.1 06:57:52 INFO - mozlog == 3.4 06:57:52 INFO - moznetwork == 0.27 06:57:52 INFO - mozprocess == 0.23 06:57:52 INFO - mozprofile == 0.28 06:57:52 INFO - mozrunner == 6.12 06:57:52 INFO - mozscreenshot == 0.1 06:57:52 INFO - mozsystemmonitor == 0.3 06:57:52 INFO - moztest == 0.7 06:57:52 INFO - mozversion == 1.4 06:57:52 INFO - psutil == 3.1.1 06:57:52 INFO - requests == 1.2.3 06:57:52 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:52 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 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 06:57:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:57:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:52 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:52 INFO - 'DCLOCATION': 'SCL3', 06:57:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:52 INFO - 'HOMEDRIVE': 'C:', 06:57:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:52 INFO - 'KTS_VERSION': '1.19c', 06:57:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:52 INFO - 'MOZ_NO_REMOTE': '1', 06:57:52 INFO - 'NO_EM_RESTART': '1', 06:57:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:52 INFO - 'OS': 'Windows_NT', 06:57:52 INFO - 'OURDRIVE': 'C:', 06:57:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:52 INFO - 'PROCESSOR_LEVEL': '6', 06:57:52 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:52 INFO - 'PROMPT': '$P$G', 06:57:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:52 INFO - 'PWD': 'C:\\slave\\test', 06:57:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:52 INFO - 'SYSTEMDRIVE': 'C:', 06:57:52 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:52 INFO - 'TEST1': 'testie', 06:57:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:52 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:52 INFO - 'USERNAME': 'cltbld', 06:57:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:52 INFO - 'WINDIR': 'C:\\windows', 06:57:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 06:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 06:57:52 INFO - Cleaning up... 06:57:52 INFO - Return code: 0 06:57:52 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 06:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:57:52 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01921640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-068', 'USERDOMAIN': 'T-W732-IX-068', '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-068', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:57:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 06:57:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:57:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:52 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:52 INFO - 'DCLOCATION': 'SCL3', 06:57:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:52 INFO - 'HOMEDRIVE': 'C:', 06:57:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:52 INFO - 'KTS_VERSION': '1.19c', 06:57:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:52 INFO - 'MOZ_NO_REMOTE': '1', 06:57:52 INFO - 'NO_EM_RESTART': '1', 06:57:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:52 INFO - 'OS': 'Windows_NT', 06:57:52 INFO - 'OURDRIVE': 'C:', 06:57:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:52 INFO - 'PROCESSOR_LEVEL': '6', 06:57:52 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:52 INFO - 'PROMPT': '$P$G', 06:57:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:52 INFO - 'PWD': 'C:\\slave\\test', 06:57:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:52 INFO - 'SYSTEMDRIVE': 'C:', 06:57:52 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:52 INFO - 'TEST1': 'testie', 06:57:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:52 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:52 INFO - 'USERNAME': 'cltbld', 06:57:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:52 INFO - 'WINDIR': 'C:\\windows', 06:57:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 06:57:53 INFO - Cleaning up... 06:57:53 INFO - Return code: 0 06:57:53 INFO - Running post-action listener: _resource_record_post_action 06:57:53 INFO - Running post-action listener: _start_resource_monitoring 06:57:53 INFO - Starting resource monitoring. 06:57:53 INFO - [mozharness: 2016-10-18 13:57:53.457000Z] Finished create-virtualenv step (success) 06:57:53 INFO - [mozharness: 2016-10-18 13:57:53.457000Z] Running install step. 06:57:53 INFO - Running pre-action listener: _resource_record_pre_action 06:57:53 INFO - Running main action method: install 06:57:53 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:57:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:57:53 INFO - Reading from file tmpfile_stdout 06:57:53 INFO - Using _rmtree_windows ... 06:57:53 INFO - Using _rmtree_windows ... 06:57:53 INFO - Detecting whether we're running mozinstall >=1.0... 06:57:53 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 06:57:53 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 06:57:53 INFO - Reading from file tmpfile_stdout 06:57:53 INFO - Output received: 06:57:53 INFO - Usage: mozinstall-script.py [options] installer 06:57:53 INFO - Options: 06:57:53 INFO - -h, --help show this help message and exit 06:57:53 INFO - -d DEST, --destination=DEST 06:57:53 INFO - Directory to install application into. [default: 06:57:53 INFO - "C:\slave\test"] 06:57:53 INFO - --app=APP Application being installed. [default: firefox] 06:57:53 INFO - Using _rmtree_windows ... 06:57:53 INFO - Using _rmtree_windows ... 06:57:53 INFO - mkdir: C:\slave\test\build\application 06:57:53 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 06:57:53 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 06:57:56 INFO - Reading from file tmpfile_stdout 06:57:56 INFO - Output received: 06:57:56 INFO - C:\slave\test\build\application\firefox\firefox.exe 06:57:56 INFO - Using _rmtree_windows ... 06:57:56 INFO - Using _rmtree_windows ... 06:57:56 INFO - Running post-action listener: _resource_record_post_action 06:57:56 INFO - [mozharness: 2016-10-18 13:57:56.248000Z] Finished install step (success) 06:57:56 INFO - [mozharness: 2016-10-18 13:57:56.248000Z] Running run-tests step. 06:57:56 INFO - Running pre-action listener: _resource_record_pre_action 06:57:56 INFO - Running main action method: run_tests 06:57:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 06:57:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 06:57:56 INFO - Python 2.7.3 06:57:56 INFO - Return code: 0 06:57:56 INFO - grabbing minidump binary from tooltool 06:57:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:57:56 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 0x019E8E90>, '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 0x013EA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A9E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 06:57:56 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 06:57:56 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 06:57:56 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 06:57:56 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:57:56 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpv2qp8m 06:57:56 INFO - INFO - File integrity verified, renaming tmpv2qp8m to win32-minidump_stackwalk.exe 06:57:56 INFO - Return code: 0 06:57:56 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 06:57:56 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 06:57:56 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 06:57:56 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 06:57:56 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 06:57:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 06:57:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-068 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 06:57:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:57:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:57:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:57:56 INFO - 'COMPUTERNAME': 'T-W732-IX-068', 06:57:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:57:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:57:56 INFO - 'DCLOCATION': 'SCL3', 06:57:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:57:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:57:56 INFO - 'HOMEDRIVE': 'C:', 06:57:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:57:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:57:56 INFO - 'KTS_VERSION': '1.19c', 06:57:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:57:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-068', 06:57:56 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:57:56 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 06:57:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:57:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:57:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:57:56 INFO - 'MOZ_NO_REMOTE': '1', 06:57:56 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:57:56 INFO - 'NO_EM_RESTART': '1', 06:57:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:57:56 INFO - 'OS': 'Windows_NT', 06:57:56 INFO - 'OURDRIVE': 'C:', 06:57:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:57:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:57:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:57:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:57:56 INFO - 'PROCESSOR_LEVEL': '6', 06:57:56 INFO - 'PROCESSOR_REVISION': '1e05', 06:57:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:57:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:57:56 INFO - 'PROMPT': '$P$G', 06:57:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:57:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:57:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:57:56 INFO - 'PWD': 'C:\\slave\\test', 06:57:56 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 06:57:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:57:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:57:56 INFO - 'SYSTEMDRIVE': 'C:', 06:57:56 INFO - 'SYSTEMROOT': 'C:\\windows', 06:57:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:56 INFO - 'TEST1': 'testie', 06:57:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:57:56 INFO - 'USERDOMAIN': 'T-W732-IX-068', 06:57:56 INFO - 'USERNAME': 'cltbld', 06:57:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:57:56 INFO - 'WINDIR': 'C:\\windows', 06:57:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:57:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:57:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:57:56 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-068', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 06:57:58 INFO - mozversion application_buildid: 20161018033016 06:57:58 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 06:57:58 INFO - mozversion application_display_name: Nightly 06:57:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:57:58 INFO - mozversion application_name: Firefox 06:57:58 INFO - mozversion application_remotingname: firefox 06:57:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:57:58 INFO - mozversion application_vendor: Mozilla 06:57:58 INFO - mozversion application_version: 52.0a1 06:57:58 INFO - mozversion platform_buildid: 20161018033016 06:57:58 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 06:57:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:57:58 INFO - mozversion platform_version: 52.0a1 06:57:58 INFO - using testdate: 1476799078 06:57:58 INFO - actual date: 1476799078 06:57:58 INFO - starting webserver on 'localhost:49721' 06:57:58 INFO - SUITE-START | Running 2 tests 06:57:58 INFO - TEST-START | dromaeo_css 06:57:58 INFO - operating with platform_type : w7_ 06:57:58 INFO - Initialising browser for dromaeo_css test... 06:57:58 INFO - TEST-INFO | started process 1828 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp7gmag0\profile http://localhost:49721/getInfo.html) 06:58:05 INFO - PROCESS | 1828 | __metrics Screen width/height:1600/1200 06:58:05 INFO - PROCESS | 1828 | colorDepth:24 06:58:05 INFO - PROCESS | 1828 | Browser inner width/height: 1010/674 06:58:05 INFO - PROCESS | 1828 | __metrics 06:58:06 INFO - PROCESS | 1828 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:58:06 INFO - PROCESS | 1828 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:58:07 INFO - TEST-INFO | 1828: exit 0 06:58:07 INFO - Browser initialized. 06:58:07 INFO - Running cycle 1/1 for dromaeo_css test... 06:58:07 INFO - TEST-INFO | started process 3344 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp7gmag0\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:58:07 INFO - PROCESS | 3344 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 06:58:18 INFO - PROCESS | 3344 | RSS: Main: 123043840 06:58:18 INFO - PROCESS | 3344 | 06:59:10 INFO - PROCESS | 3344 | 1476799150125 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] 07:01:18 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49721/tests/dromaeo/cssquery-ext.html) 07:01:22 INFO - PROCESS | 3344 | RSS: Main: 259506176 07:01:22 INFO - PROCESS | 3344 | 07:04:24 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-ext.html (next: http://localhost:49721/tests/dromaeo/cssquery-jquery.html) 07:04:25 INFO - PROCESS | 3344 | RSS: Main: 200265728 07:04:25 INFO - PROCESS | 3344 | 07:07:27 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49721/tests/dromaeo/cssquery-mootools.html) 07:07:27 INFO - PROCESS | 3344 | RSS: Main: 152879104 07:07:27 INFO - PROCESS | 3344 | 07:10:30 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49721/tests/dromaeo/cssquery-prototype.html) 07:10:30 INFO - PROCESS | 3344 | RSS: Main: 149553152 07:10:30 INFO - PROCESS | 3344 | 07:13:33 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49721/tests/dromaeo/cssquery-yui.html) 07:13:34 INFO - PROCESS | 3344 | RSS: Main: 134172672 07:13:34 INFO - PROCESS | 3344 | 07:16:36 INFO - PROCESS | 3344 | Cycle 1(1): loaded http://localhost:49721/tests/dromaeo/cssquery-yui.html (next: http://localhost:49721/tests/dromaeo/cssquery-dojo.html) 07:16:37 INFO - PROCESS | 3344 | RSS: Main: 185131008 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | __start_tp_report 07:16:37 INFO - PROCESS | 3344 | _x_x_mozilla_page_load 07:16:37 INFO - PROCESS | 3344 | _x_x_mozilla_page_load_details 07:16:37 INFO - PROCESS | 3344 | |i|pagename|runs| 07:16:37 INFO - PROCESS | 3344 | |0;dojo.html;2716.2394529531725;2732.4808286425578;2742.7531522163004;2755.6417665703457;2765.6404667393235;4733.810647574093;4765.309030190625;4807.855764327067;4808.37491126154;4838.879028024301;4689.742064186468;4706.74112923789;4727.66906316558;4759.952400475994;4813.205821039524;4139.8965025874295;4163.208990291835;4177;4207.936880946788;4221.662267018646;4136.5449800522065;4153.522344930327;4159.064210552617;4164.625183733463;4244.087521182945;3928.01259632412;4044.858429954952;4236.766977816234;4249.787510624456;4253.978730106361;4106.733062350938;4107.54816970133;4116.58834116589;4135.8966025849295;4188.225178342016;3601.9954135534317;4087.1621317629815;4134.689898257643;4159.625633692952;4171.290880550314;4561.657875659339;4714.080754252922;4714.575688188045;4751.976240118777;4761.47623761386;4870.586000189988;4951;4952.232403977389;4953.257011448276;5055.140626093573;4951.579115775164;4972.701637901738;4999.3000979862845;5000.149974504343;5006.349174607314;4378.2119218540965;4535.501094879561;4607.792649330788;4639.559241871983;4671.065786842645;4988.850334489971;5075.974620126875;5079.847604571869;5081.085404627129;5083.440821509631;4772.188727916263;4873.147199240189;4879.194932836113;4896.681715688469;4915.48387419323;4965.280034395065;4972.825951091694;5004.749762511859;5007.749612519359;5024.899502009939;4832.855014349575;4979.800807967641;4985.576226020757;4991.226359914219;4995.325631039836;4870.683405578626;4984.476629953875;4987.351644286219;5009.724465154379;5033.2701758245585;3317.452620317669;3326.0021993401883;3387.796732196076;3393.8812141574926;3397.4394224952607;3038.9088327350214;3066.938661226763;3073.3545955349628;3101.953470697941;3106.176863131272;4472.1502914446155;4586.334981427674;4633.884152896205;4635.559621835921;4637.095766325534;4867.878303042381;4932.605391568666;4950.579200767903;4961.553460188528;5005.124103281911;4505.954940450619;4510.7068040577915;4529.116822219701;4599.896024953987;4653.464851542116;3960.940585891214;4083.6528895044253;4106.281400754856;4131.731437456556;4169.228692691861;5055;5127.692338459704;5199.142141546602;5226.790928362823;5231.686098834082;4946.4558898521855;4986.850394488171;5028.748562571856;5046.596272298134;5090.898182036412;5170.689758614495;5186.325777648882;5204.73976301191;5291.285676433786;5305.389880163831;4865.683730557503;4868.2210846264425;4915.066137433876;4920;5010.624203184703;4993.650444468853;5005.424376196784;5007.524285192901;5025.120603894377;5025.84922452327;4732;4865.659403841697;4892.31507588945;4901.068796928572;4905.730184839868;4856.125897338513;4860.392450943632;4868.756562171807;4875.975620121877;4881.755912204305;4760.761961901821;4790.976045119752;4800.567948884617;4806.975965120152;4812.542808433193;4551.8634440966825;4805.111054454937;4831.251156070814;4913.680610760289;4942.8764280893265;4697.154512187839;4744.312074749211;4810;4812.687175333592;4834.154023045882 07:16:37 INFO - PROCESS | 3344 | |1;ext.html;483.7387810582286;512.40305044623;610.6183635227983;637.9298277189508;638.1321402892069;35322.823385883064;36236.818815905914;36478.45282320767;36886.99999999996;37198;37390.8130459348;39488;39633.40549891754;39715.40426893592;39746.60253397465;7958.840823183547;8245.670173193064;8246.134155913633;8246.83506329876;8252.917470825305;8182.18178182183;8298.087210406851;8299.95850020752;8307.335413166933;8320.875186872201;7949.880751788728;8252.092269850311;8280.337572994145;8355.791105222388;8361.205685459872;8219.342452603807;8270.172982701743;8309.169083091703;8319.875201871917;8320.167983201693;7588.962055189689;8133.674653013873;8187.79530511736;8200;8200.343972482187;33647.831760841036;36638.63361366405;36667.63332366669;36742.26515469702;36853;2823.9294017649518;2847.8433686147255;3295.752818538619;3541.1394142693885;3600.4239321708665;27410.5888411674;29165.592591858105;29219.853900730574;29826.70173298261;29928;3196.856141473639;3229.2088438332526;3253.430649636328;3262.5001241816544;3343.0652570323955;287227;313154;315899;316118;316733;47595;47719.28421073686;47954.76022619865;48016.75991620089;48022;42061;42526.25895504129;44053.11893762107;44476.332855007204;46139.76930115395;11991.280523168638;12193.268403895618;12268.693282668004;12486.001119910385;13009;8980.371374003887;9185.862212066824;9384.8592271116;9414.717558473258;9516.524938634384;12392.380380980914;13343.466261349631;13845.238511881944;14172.787408188886;14222.857771422361;4997.232072055859;5953.702314884238;6016.465801220661;6145.139680444741;6172.351903050205;737.9963100184465;806.5160903457995;820.8686610142348;827.9774478519024;923.3131587081695;26234.344141396617;26617.467650647264;26965;27295.590566141524;27487.17538473849;880.0759202836996;912.5847739278612;922.5387306346827;926.7358802741186;944.8960614332418;13594.252316122709;13900.16599004063;14215.85784142166;14394.712105757826;14458.566243012727;464630;465238;468543;469137;471682;44544.43194600675;47155.29267060997;47418.51629674079;47741;47765.761171194616;1066.4081434803604;1088.8584484017185;1100.9559617615366;1111.2665640677114;1112.2436743014666;25856.482870342865;26899;26918.461630767666;26989;27305.45389092247;41527.96180095522;41680.58319416767;41726.58273417227;41843;41907.7904610475;362536;373219.47292899154;388907;390223;391918;32844.1788955278;34921.82539087288;35950;36208.275834482636;36209.27581448304;31899.36201276008;31913;32873.17817054497;39204.803975979936;41530.169396611294;33120.50319245261;35759.82120089487;36057.639423605426;36096.639033609325;36177;34953.47569786506;35385.469217962265;35868.28263434769;36803.26393472169;36836.81581592075;32953.67046329506;33548.329033419686;33565.66434335626;33579.16052098717;33869.83065084659;30819.229519262197;31491;31533.369332613678;32046.519302210952;32511.187220318734;24097.75902240955;24576.6313505294;24617.507649847263;24677.136300229406;24817.50364992726 07:16:37 INFO - PROCESS | 3344 | |2;jquery.html;277.09114105733204;301.28745516852644;303.6720342030607;304.11654144709627;310.2569346415336;12169.148159628818;17360.91319543402;17640.117994100277;17834.821651783495;18064;16108.919455402736;16165.19174041299;16338.856280060401;16375.508734737978;16783.32866685334;5190.636655434121;5236.895262094766;5261.2371206175085;5414.729263536808;5537.473939975695;5109.616778741609;5288.471152884719;5295.391030031538;5484.725763711818;5616.606837521375;5579.525740312079;5581.6930068846195;5584.4136365681625;5587;5592.4407559244155;5071.2139618359215;5101.387833460009;5162.819301324474;5507.0637991541535;5546.91679624806;5040.445550989388;5150.253213284091;5332.413434522199;5360.839174824761;5360.892782144335;11160.497777600027;11493.597724079616;20344.491387715276;20503.2823851166;20619.07214175366;1770.9822901770947;1816.8364847163818;1983.1571582077559;1995.8902260375673;2018.1019446346381;8722.040575536686;9046.77383065422;9597.424154550748;13969.022168448213;14432.783508247385;1653.1238443624898;1732.3503686117706;1736.7758061476593;1779.1549014218272;1801.3605170164533;78682.60658696727;81607.18392816055;85970;86353;91934;7882.763517094496;8053.27520523144;8259.463134896212;8378;8390.832183356299;10110;10487.161027117812;10861.728456788644;12336.383180840921;12516.937415313047;6206.627602343874;6809.182898052265;7143.857122857514;7220;7234.421246300283;5899.17411562373;6621.635810030398;6689.832754181184;6932.792016239521;7078.25678303781;3694.390425579803;3806.906201589115;3818.904527386782;3838.1747924196434;3846.3461211594094;1603.174365201922;1632.3389027443905;1643.186622621786;1647.7610746441699;1682.125294846673;776.8756998880152;788.3771820261928;792.7859477940924;851.3274513134678;894.4230971023653;17054.67433850387;17480.82519174792;18249.90875045616;18503.53741156482;18511.259549617866;787.4055088408329;810.8459392715365;813.3696385301439;818.1981657975217;834.8121672623611;9460.85130825766;9519.286053546011;9562.904370956201;9745.512724363753;10013.649522266685;415161;426853;438665;439389;443503;94053.5297323509;96095.51952240194;96695.51652241693;101616;105611;761.1969372312263;784.6665167303873;788.5071830106184;793.412874472886;815.8001289684065;15643.921780391025;15841.920790395974;16202.027878327339;16505.83494165091;16886.24011919442;42144.78927605342;43077.3538396918;44264.336034958855;44807.327890081026;45365;61145.38854611397;61293.69353153206;62505;62649.37350626618;63118.684406577675;6545.770898018546;6592.604443733391;6858.725650974105;7013.368796808311;7489.21363256872;11988.220765650205;12983.221006739626;13009.869901300866;13057.738845222852;13124.606261812161;10333.638322658671;10458.63394781179;10568.365898046142;11055.668329949793;11075.72310692239;10005.249606279413;10224;10301.433421161908;10338.276320657482;10571.415730785904;10188.592456301665;10214.846777298495;10637.095846853104;10746.086582640562;11229.831552526555;6685.498587606046;6850.314968503109;6974.895376569254;7054.576725396492;7093.32613401726;8138.593070346577;10862.293950893483;10960.123190144928;11022.779544409228;11288.717782055513 07:16:37 INFO - PROCESS | 3344 | |3;mootools.html;1047.334942311632;1123.3203911633461;1131.6774719205027;1164.196704274051;1267.4993377615847;6363.268224154218;6806.659667016642;6812.114425124733;6812.318768123185;7061.399781018613;6826.146731658543;6864.107666003425;6874.690638921248;6907.274736152707;7325.084364454443;1788.68697977854;1886.349209522716;1889.527618095476;1907.01788578882;1907.6661584222775;1757.1038770227212;1781.8396344329008;1784.3843873863561;1787.7497150398954;1888.150332350441;1782.7147656374982;1877.953051173718;1890.4801179675562;1906.1708156951702;1906.3994841624844;1745.3716662001732;1748.720204767231;1749.0729913146054;1751.2644689230553;1781.5724226185687;1741.930322787087;1743.2852530462576;1792.8386445219862;1807.204829874864;1815.4190658989128;6780.6948687308695;7072.929270707278;7180.030695856097;7182.245864184238;7204.7118115275325;3949.447077409165;4097.282975479279;4097.364908439167;4170.874873753793;4383.167198232326;2902.34697193132;2977.151511819121;3022.8488575571355;3040.331127152045;3046.466868298039;852.9061803201643;870.4646642314995;888.4536010353651;935.6023689931752;944.6929747831982;8679.088695686925;8681.82636347276;8685.609147588373;8707.303415726727;8862.512561809099;3370.511275865035;3420.1449637590604;3470.8091054991714;3671.3208056509097;3681.6686498214754;7277.490575659653;7315;7317.012203352479;7321.670524826395;7332.266773322732;3977.840886364571;4098.323776576832;4256.382824490432;4257.595528424795;4274.038341373228;3636.9818150909077;3744.0826997385816;3903;4023.979880100581;4024.5975402460103;2971.821690698565;2978.6872378399958;3060.1890499017777;3068.2942923127357;3090.9381812364077;855.8545047341967;863.4646519158163;865.1219012702112;869.443556124081;919.9402038867452;637.2671427858;713.6360456167392;718.9245129261457;742.9219931907072;803.7227156630953;4417.9838637113;4442.355858400514;4498.685092043591;4541.364209010741;4577.107464044546;751.0949306086153;779.2752739951899;782.3545574900729;798.0463346301115;815.6737305077897;3080.0605815226572;3080.7073328033803;3158.8104713717253;3210.887618933326;3217.243947672342;7864.41016923733;8378.329733621296;8623.525706086226;8720.345974051972;8733.126687331216;8616.698415555426;8700.825983480296;8716;8728.650853965897;8748.912510874938;6549.312322206195;6677.432418244406;6720.462363010947;6834.1457317835275;6841.315868413219;4452.888677783081;4456.331550267356;4799.856004319876;4805.159097158054;4879.585235255043;7515.2109028553405;7668.348190403878;7713;8192.139825318256;8194.098649148707;7805.726799562215;7961.8407631848195;8252.504849709037;8263.132371100948;8278.379121565786;6434.453071488789;6465.579737317247;6549.312322206099;6551.050097735897;6566.310537393696;5996.910046349221;6242;6459.5478316519275;7002;7053.153621565445;6239.313675495601;6357;6377.96811015942;6399.360063993563;6453.1610890583925;6084.482818960438;6094.573379863367;6117.908231376622;6129;6129.570930034854;5307.814226502208;5471.425500322409;5573.052581061148;5657.8019769307875;5685.061964775931;3801.6388443097862;4011.5988401159652;4112.065967910117;4120.75275483472;4137.668986481134;3950.1704642025497;4017.879463616096;4085.611866872642;4154.169166166839;4180.247555439928 07:16:37 INFO - PROCESS | 3344 | |4;prototype.html;247.69657169966783;260.0728403242441;261.2202575312692;261.97773189278917;263.9920802375928;5060.215666571683;5105.361829771278;5123.308353372294;5127.615428842833;5130.410002849673;4638.559336863001;4689.742064186468;4819.710817350961;4985.376827896513;5047.520485553876;3525.2244506208594;3525.4711793230963;3543.3799085159994;3578.3916734155127;3728.3102626014274;3552.8756493522733;3734.3278209922205;3738.7009039276927;3742.4760533525327;3747.6627103560672;3527.753057285991;3570.410882204433;3617.6924961378313;3620.2759448110482;3638.9272214555695;3511.5434993450817;3516.947245791328;3518.7712798668003;3519.278547897675;3520.9119772005647;3053.40458610571;3060.6021217241614;3075.5852307938376;3177.5710279112254;3244.643089260203;4820.975895120502;4854.713500922258;4893.168161412533;4925.458199598042;4957.454679985199;4010.2981978153707;4132.409022526319;4144.316187829005;4152.584741525853;4278.336857787048;4990.850274491735;4993.550580447741;5005.47442518534;5057.620678449094;5062.620303477217;3421.367047096295;3426.880059197916;3498.3003399320237;3567.4113771227976;3628.383174860254;5483.601681571174;5491.258680078218;5497.587680923907;5505.623594101475;5526.949879522918;3714;3722.1253005543676;3722.4788529605876;3726.4596633488536;3728.664420202195;3519.771214871025;3691.8154092295276;3692.2430901665093;3709.4250391189407;3723.5717892442176;4083.9183216335505;4100.466939297932;4291.871243862689;4320.805563749639;4329.718568293051;4999.925001124986;5016.799328026838;5019.573336266459;5026.271190677331;5231.973840130775;1178.3106882473678;1215.8176273559036;1249.6750844780422;1267.8732126787431;1275.1520239040933;755.1089714137371;850.1965642467932;864.2092485375937;869.8695195720566;881.299367003234;4778.1638213312535;4870.634702397334;4976.178930476503;4984.925226121611;4999.975000124976;5242.895142097213;5245.26566280721;5401.405845357008;5403.216533602685;5416.729163541807;4720.244760838249;4756.405449318835;4950.21792154824;4975.850724478271;5000.67495612784;2685.9597106043425;2861.427714457117;2867.813592116506;2877.568364745263;2878.280429892527;5624.268845050117;5626.493615574618;5626.690532020778;5627.184058311522;5631.662100273997;5243.816466423733;5249.50129737682;5300.310959575308;5492.780288788484;5498.257735205776;5214.165733482625;5332;5342.252084708066;5385.919211211835;5613.522850557748;5359.919601205907;5370.650907690988;5386.919196212139;5388.73056347189;5401.513863752281;5036.546710795899;5067.113255180255;5096.872578185426;5306.469353064663;5311.362636483646;5326.840194794162;5328.387235467816;5332.33345831771;5432.782688692408;5602.187682785891;3660.2862441824655;3691.3171063352293;3695.3533131702384;3702.315071711742;3707.5550933887025;5314.22943673173;5333.43998880112;5339.626226164131;5343.4122246553625;5354.7858085676135;5123.308353372396;5211.76547055391;5228.921566176435;5237.135872580981;5238.7257963772645;5000.099982003274;5091.796328146833;5205.869853253699;5236.188390799432;5244.9737751310995;4849.878753031202;4858.975705121593;4987.975060124676;5110.64225504226;5121.769520371667;4840.491748366511;4965.677230979974;5170.017696637627;5171.534561889298;5244.187150991603;5116.590672746097;5224.6342756008235;5229.450907654642;5235.356145146677;5249.947500524946 07:16:37 INFO - PROCESS | 3344 | |5;yui.html;606.6996836565901;611.0711718188354;647.7538535356565;670.9698063587138;682.839532709813;14187.858121418783;14939.477118300862;15050.924745376271;15082.170480623561;15202.087874727524;13753.724925501483;13939.233342166177;14547.418103275857;14825;14830;493.82962877807097;496.3324328777788;608.8782243551296;619.4517851701232;624.8500359913611;600.7867207141468;603.3604379357873;617.7745123030085;619.4332186049782;621.8911690454175;620.7889317632004;654.2280109470822;655.9606423614598;656.1601150527333;656.4026735670541;517.3894804131123;527.2407732864701;650.9772157974472;651.5894986158713;652.012201514708;449.3820996130321;466.5870704426573;468.33496435062295;468.80075967713924;471.0108771579655;13058.085933984625;13080.607581772467;13087.280199589019;13088.214707117508;13091.083624146313;12320.075994300463;12349.629511114681;12398.442070106867;12628.621141365773;13027.60917172477;12865.653459315703;13351;13450.93274533631;13460.730785384336;13872.722545549133;4198.874033778991;4208.053188032699;4340.587644173798;4370.497392799837;4382.517923028464;80768.59615701942;82716.58641706813;84169.57915210446;84419.57790211009;85048.57475712644;9993.813871773818;10048.397096174253;10101;10210.693679189637;10371.94814025925;16994.405195818086;17001.574960625836;17006.48980530586;17008.744868826976;17382.82617173812;6933.791986240421;7033.683484243222;7122;7187.7843664690145;7189;3961.7622942623534;3971.0667993021616;4079.8776036718946;4138.0689344897755;4218.325067989168;2654.9469010619678;2797.4544963732283;2800.215939536933;2928.941421171607;2932.401840996662;516.336507587748;519.462356461061;524.4938634217998;524.7192751877727;534.4708738349007;559.527199516408;645.1548471502254;670.2861452553016;691.8357435251881;727.3526561360394;9318.648795924553;9450;9458.668946586837;9645.469499177612;9793.90206097944;530.9973450132725;636.1888592045087;645.9402654317591;694.4548529404494;696.5681277607915;4650.67445278834;4680.789364478606;4782.115308667908;4807.663463557517;4854.63590230727;69105.30894690989;101313;115075;115148;116881;61175.69412152911;68125;91485.17029659502;97564.51217744008;98069.5096524527;85247.57376213079;85927.57036214779;87917.1208287909;88107.55946220228;89189;8772.29821614282;9001.32490063261;9155.679551215675;9159.77100572491;9391.01394353583;47244.7637761809;49654.503454964986;50981.23528147155;51650.74174629253;51687.48312516827;69754;74330.62834686009;76024.5788939488;76252;76381.2361876374;11361.602343917923;11364.090872729996;11422.657320280405;11448.42757862087;11550.537978480766;13387.732245355233;13641.658958526115;13669;13799.103058301178;14263.144211347353;11309.321440713584;12255.080868935043;12487.375631218221;12499.500019999097;13322.267275299952;13531.932340338235;13594.864051359755;13661.52678843317;13986.860131398556;14406.711865762416;13354.599362019155;13443.798343025059;13713.657158570717;14028.859711403164;14070.859291406954;11042.834357484484;11471.369074700971;11836.70408239801;12115.878841211475;12225.816612750994;10459.895401046197;10738.46307684597;10977.780444391226;11045.558177672803;11436.542538298374 07:16:37 INFO - PROCESS | 3344 | __end_tp_report 07:16:37 INFO - PROCESS | 3344 | __start_cc_report 07:16:37 INFO - PROCESS | 3344 | _x_x_mozilla_cycle_collect,3392 07:16:37 INFO - PROCESS | 3344 | __end_cc_report 07:16:37 INFO - PROCESS | 3344 | __startTimestamp1476800197060__endTimestamp 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | ------- Summary: start ------- 07:16:37 INFO - PROCESS | 3344 | Number of tests: 6 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#0] dojo.html Cycles:165 Average:4578.36 Median:4808.37 stddev:589.60 (12.3%) stddev-sans-first:573.02 07:16:37 INFO - PROCESS | 3344 | Values: 2716.2 2732.5 2742.8 2755.6 2765.6 4733.8 4765.3 4807.9 4808.4 4838.9 4689.7 4706.7 4727.7 4760.0 4813.2 4139.9 4163.2 4177.0 4207.9 4221.7 4136.5 4153.5 4159.1 4164.6 4244.1 3928.0 4044.9 4236.8 4249.8 4254.0 4106.7 4107.5 4116.6 4135.9 4188.2 3602.0 4087.2 4134.7 4159.6 4171.3 4561.7 4714.1 4714.6 4752.0 4761.5 4870.6 4951.0 4952.2 4953.3 5055.1 4951.6 4972.7 4999.3 5000.1 5006.3 4378.2 4535.5 4607.8 4639.6 4671.1 4988.9 5076.0 5079.8 5081.1 5083.4 4772.2 4873.1 4879.2 4896.7 4915.5 4965.3 4972.8 5004.7 5007.7 5024.9 4832.9 4979.8 4985.6 4991.2 4995.3 4870.7 4984.5 4987.4 5009.7 5033.3 3317.5 3326.0 3387.8 3393.9 3397.4 3038.9 3066.9 3073.4 3102.0 3106.2 4472.2 4586.3 4633.9 4635.6 4637.1 4867.9 4932.6 4950.6 4961.6 5005.1 4506.0 4510.7 4529.1 4599.9 4653.5 3960.9 4083.7 4106.3 4131.7 4169.2 5055.0 5127.7 5199.1 5226.8 5231.7 4946.5 4986.9 5028.7 5046.6 5090.9 5170.7 5186.3 5204.7 5291.3 5305.4 4865.7 4868.2 4915.1 4920.0 5010.6 4993.7 5005.4 5007.5 5025.1 5025.8 4732.0 4865.7 4892.3 4901.1 4905.7 4856.1 4860.4 4868.8 4876.0 4881.8 4760.8 4791.0 4800.6 4807.0 4812.5 4551.9 4805.1 4831.3 4913.7 4942.9 4697.2 4744.3 4810.0 4812.7 4834.2 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#1] ext.html Cycles:180 Average:52000.81 Median:36844.91 stddev:103927.51 (282.1%) stddev-sans-first:104147.08 07:16:37 INFO - PROCESS | 3344 | Values: 483.7 512.4 610.6 637.9 638.1 35322.8 36236.8 36478.5 36887.0 37198.0 37390.8 39488.0 39633.4 39715.4 39746.6 7958.8 8245.7 8246.1 8246.8 8252.9 8182.2 8298.1 8300.0 8307.3 8320.9 7949.9 8252.1 8280.3 8355.8 8361.2 8219.3 8270.2 8309.2 8319.9 8320.2 7589.0 8133.7 8187.8 8200.0 8200.3 33647.8 36638.6 36667.6 36742.3 36853.0 2823.9 2847.8 3295.8 3541.1 3600.4 27410.6 29165.6 29219.9 29826.7 29928.0 3196.9 3229.2 3253.4 3262.5 3343.1 287227.0 313154.0 315899.0 316118.0 316733.0 47595.0 47719.3 47954.8 48016.8 48022.0 42061.0 42526.3 44053.1 44476.3 46139.8 11991.3 12193.3 12268.7 12486.0 13009.0 8980.4 9185.9 9384.9 9414.7 9516.5 12392.4 13343.5 13845.2 14172.8 14222.9 4997.2 5953.7 6016.5 6145.1 6172.4 738.0 806.5 820.9 828.0 923.3 26234.3 26617.5 26965.0 27295.6 27487.2 880.1 912.6 922.5 926.7 944.9 13594.3 13900.2 14215.9 14394.7 14458.6 464630.0 465238.0 468543.0 469137.0 471682.0 44544.4 47155.3 47418.5 47741.0 47765.8 1066.4 1088.9 1101.0 1111.3 1112.2 25856.5 26899.0 26918.5 26989.0 27305.5 41528.0 41680.6 41726.6 41843.0 41907.8 362536.0 373219.5 388907.0 390223.0 391918.0 32844.2 34921.8 35950.0 36208.3 36209.3 31899.4 31913.0 32873.2 39204.8 41530.2 33120.5 35759.8 36057.6 36096.6 36177.0 34953.5 35385.5 35868.3 36803.3 36836.8 32953.7 33548.3 33565.7 33579.2 33869.8 30819.2 31491.0 31533.4 32046.5 32511.2 24097.8 24576.6 24617.5 24677.1 24817.5 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#2] jquery.html Cycles:180 Average:26879.07 Median:243883.67 stddev:72267.52 (29.6%) stddev-sans-first:72442.65 07:16:37 INFO - PROCESS | 3344 | Values: 277.1 301.3 303.7 304.1 310.3 12169.1 17360.9 17640.1 17834.8 18064.0 16108.9 16165.2 16338.9 16375.5 16783.3 5190.6 5236.9 5261.2 5414.7 5537.5 5109.6 5288.5 5295.4 5484.7 5616.6 5579.5 5581.7 5584.4 5587.0 5592.4 5071.2 5101.4 5162.8 5507.1 5546.9 5040.4 5150.3 5332.4 5360.8 5360.9 11160.5 11493.6 20344.5 20503.3 20619.1 1771.0 1816.8 1983.2 1995.9 2018.1 8722.0 9046.8 9597.4 13969.0 14432.8 1653.1 1732.4 1736.8 1779.2 1801.4 78682.6 81607.2 85970.0 86353.0 91934.0 7882.8 8053.3 8259.5 8378.0 8390.8 10110.0 10487.2 10861.7 12336.4 12516.9 6206.6 6809.2 7143.9 7220.0 7234.4 5899.2 6621.6 6689.8 6932.8 7078.3 3694.4 3806.9 3818.9 3838.2 3846.3 1603.2 1632.3 1643.2 1647.8 1682.1 776.9 788.4 792.8 851.3 894.4 17054.7 17480.8 18249.9 18503.5 18511.3 787.4 810.8 813.4 818.2 834.8 9460.9 9519.3 9562.9 9745.5 10013.6 415161.0 426853.0 438665.0 439389.0 443503.0 94053.5 96095.5 96695.5 101616.0 105611.0 761.2 784.7 788.5 793.4 815.8 15643.9 15841.9 16202.0 16505.8 16886.2 42144.8 43077.4 44264.3 44807.3 45365.0 61145.4 61293.7 62505.0 62649.4 63118.7 6545.8 6592.6 6858.7 7013.4 7489.2 11988.2 12983.2 13009.9 13057.7 13124.6 10333.6 10458.6 10568.4 11055.7 11075.7 10005.2 10224.0 10301.4 10338.3 10571.4 10188.6 10214.8 10637.1 10746.1 11229.8 6685.5 6850.3 6974.9 7054.6 7093.3 8138.6 10862.3 10960.1 11022.8 11288.7 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#3] mootools.html Cycles:180 Average:4540.95 Median:6040.70 stddev:2545.75 (42.1%) stddev-sans-first:2539.35 07:16:37 INFO - PROCESS | 3344 | Values: 1047.3 1123.3 1131.7 1164.2 1267.5 6363.3 6806.7 6812.1 6812.3 7061.4 6826.1 6864.1 6874.7 6907.3 7325.1 1788.7 1886.3 1889.5 1907.0 1907.7 1757.1 1781.8 1784.4 1787.7 1888.2 1782.7 1878.0 1890.5 1906.2 1906.4 1745.4 1748.7 1749.1 1751.3 1781.6 1741.9 1743.3 1792.8 1807.2 1815.4 6780.7 7072.9 7180.0 7182.2 7204.7 3949.4 4097.3 4097.4 4170.9 4383.2 2902.3 2977.2 3022.8 3040.3 3046.5 852.9 870.5 888.5 935.6 944.7 8679.1 8681.8 8685.6 8707.3 8862.5 3370.5 3420.1 3470.8 3671.3 3681.7 7277.5 7315.0 7317.0 7321.7 7332.3 3977.8 4098.3 4256.4 4257.6 4274.0 3637.0 3744.1 3903.0 4024.0 4024.6 2971.8 2978.7 3060.2 3068.3 3090.9 855.9 863.5 865.1 869.4 919.9 637.3 713.6 718.9 742.9 803.7 4418.0 4442.4 4498.7 4541.4 4577.1 751.1 779.3 782.4 798.0 815.7 3080.1 3080.7 3158.8 3210.9 3217.2 7864.4 8378.3 8623.5 8720.3 8733.1 8616.7 8700.8 8716.0 8728.7 8748.9 6549.3 6677.4 6720.5 6834.1 6841.3 4452.9 4456.3 4799.9 4805.2 4879.6 7515.2 7668.3 7713.0 8192.1 8194.1 7805.7 7961.8 8252.5 8263.1 8278.4 6434.5 6465.6 6549.3 6551.1 6566.3 5996.9 6242.0 6459.5 7002.0 7053.2 6239.3 6357.0 6378.0 6399.4 6453.2 6084.5 6094.6 6117.9 6129.0 6129.6 5307.8 5471.4 5573.1 5657.8 5685.1 3801.6 4011.6 4112.1 4120.8 4137.7 3950.2 4017.9 4085.6 4154.2 4180.2 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#4] prototype.html Cycles:180 Average:4291.72 Median:4986.68 stddev:1321.45 (26.5%) stddev-sans-first:1289.82 07:16:37 INFO - PROCESS | 3344 | Values: 247.7 260.1 261.2 262.0 264.0 5060.2 5105.4 5123.3 5127.6 5130.4 4638.6 4689.7 4819.7 4985.4 5047.5 3525.2 3525.5 3543.4 3578.4 3728.3 3552.9 3734.3 3738.7 3742.5 3747.7 3527.8 3570.4 3617.7 3620.3 3638.9 3511.5 3516.9 3518.8 3519.3 3520.9 3053.4 3060.6 3075.6 3177.6 3244.6 4821.0 4854.7 4893.2 4925.5 4957.5 4010.3 4132.4 4144.3 4152.6 4278.3 4990.9 4993.6 5005.5 5057.6 5062.6 3421.4 3426.9 3498.3 3567.4 3628.4 5483.6 5491.3 5497.6 5505.6 5526.9 3714.0 3722.1 3722.5 3726.5 3728.7 3519.8 3691.8 3692.2 3709.4 3723.6 4083.9 4100.5 4291.9 4320.8 4329.7 4999.9 5016.8 5019.6 5026.3 5232.0 1178.3 1215.8 1249.7 1267.9 1275.2 755.1 850.2 864.2 869.9 881.3 4778.2 4870.6 4976.2 4984.9 5000.0 5242.9 5245.3 5401.4 5403.2 5416.7 4720.2 4756.4 4950.2 4975.9 5000.7 2686.0 2861.4 2867.8 2877.6 2878.3 5624.3 5626.5 5626.7 5627.2 5631.7 5243.8 5249.5 5300.3 5492.8 5498.3 5214.2 5332.0 5342.3 5385.9 5613.5 5359.9 5370.7 5386.9 5388.7 5401.5 5036.5 5067.1 5096.9 5306.5 5311.4 5326.8 5328.4 5332.3 5432.8 5602.2 3660.3 3691.3 3695.4 3702.3 3707.6 5314.2 5333.4 5339.6 5343.4 5354.8 5123.3 5211.8 5228.9 5237.1 5238.7 5000.1 5091.8 5205.9 5236.2 5245.0 4849.9 4859.0 4988.0 5110.6 5121.8 4840.5 4965.7 5170.0 5171.5 5244.2 5116.6 5224.6 5229.5 5235.4 5249.9 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - PROCESS | 3344 | [#5] yui.html Cycles:180 Average:19795.18 Median:467.46 stddev:28715.51 (6142.9%) stddev-sans-first:28759.92 07:16:37 INFO - PROCESS | 3344 | Values: 606.7 611.1 647.8 671.0 682.8 14187.9 14939.5 15050.9 15082.2 15202.1 13753.7 13939.2 14547.4 14825.0 14830.0 493.8 496.3 608.9 619.5 624.9 600.8 603.4 617.8 619.4 621.9 620.8 654.2 656.0 656.2 656.4 517.4 527.2 651.0 651.6 652.0 449.4 466.6 468.3 468.8 471.0 13058.1 13080.6 13087.3 13088.2 13091.1 12320.1 12349.6 12398.4 12628.6 13027.6 12865.7 13351.0 13450.9 13460.7 13872.7 4198.9 4208.1 4340.6 4370.5 4382.5 80768.6 82716.6 84169.6 84419.6 85048.6 9993.8 10048.4 10101.0 10210.7 10371.9 16994.4 17001.6 17006.5 17008.7 17382.8 6933.8 7033.7 7122.0 7187.8 7189.0 3961.8 3971.1 4079.9 4138.1 4218.3 2654.9 2797.5 2800.2 2928.9 2932.4 516.3 519.5 524.5 524.7 534.5 559.5 645.2 670.3 691.8 727.4 9318.6 9450.0 9458.7 9645.5 9793.9 531.0 636.2 645.9 694.5 696.6 4650.7 4680.8 4782.1 4807.7 4854.6 69105.3 101313.0 115075.0 115148.0 116881.0 61175.7 68125.0 91485.2 97564.5 98069.5 85247.6 85927.6 87917.1 88107.6 89189.0 8772.3 9001.3 9155.7 9159.8 9391.0 47244.8 49654.5 50981.2 51650.7 51687.5 69754.0 74330.6 76024.6 76252.0 76381.2 11361.6 11364.1 11422.7 11448.4 11550.5 13387.7 13641.7 13669.0 13799.1 14263.1 11309.3 12255.1 12487.4 12499.5 13322.3 13531.9 13594.9 13661.5 13986.9 14406.7 13354.6 13443.8 13713.7 14028.9 14070.9 11042.8 11471.4 11836.7 12115.9 12225.8 10459.9 10738.5 10977.8 11045.6 11436.5 07:16:37 INFO - PROCESS | 3344 | -------- Summary: end -------- 07:16:37 INFO - PROCESS | 3344 | 07:16:37 INFO - TEST-INFO | 3344: exit 0 07:16:38 INFO - TEST-OK | dromaeo_css | took 1120388ms 07:16:38 INFO - TEST-START | kraken 07:16:38 INFO - operating with platform_type : w7_ 07:16:38 INFO - Initialising browser for kraken test... 07:16:38 INFO - TEST-INFO | started process 4020 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmc1xjm\profile http://localhost:49721/getInfo.html) 07:16:42 INFO - PROCESS | 4020 | __metrics Screen width/height:1600/1200 07:16:42 INFO - PROCESS | 4020 | colorDepth:24 07:16:42 INFO - PROCESS | 4020 | Browser inner width/height: 1010/674 07:16:42 INFO - PROCESS | 4020 | __metrics 07:16:43 INFO - PROCESS | 4020 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:16:43 INFO - PROCESS | 4020 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:43 INFO - PROCESS | 4020 | console.error: 07:16:43 INFO - PROCESS | 4020 | Message: Error: Connection closed before committing the transaction. 07:16:43 INFO - PROCESS | 4020 | Stack: 07:16:43 INFO - PROCESS | 4020 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:16:43 INFO - PROCESS | 4020 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:16:43 INFO - PROCESS | 4020 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:16:43 INFO - PROCESS | 4020 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:16:43 INFO - PROCESS | 4020 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:16:43 INFO - PROCESS | 4020 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 07:16:43 INFO - PROCESS | 4020 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:16:43 INFO - PROCESS | 4020 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:16:43 INFO - PROCESS | 4020 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:16:43 INFO - PROCESS | 4020 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:16:43 INFO - PROCESS | 4020 | Barrier/this.client.addBlocker/promise13.2% 07:17:41 INFO - TinderboxPrint: I/O read bytes / time
89,594,880 / 1,449,460 07:17:41 INFO - TinderboxPrint: I/O write bytes / time
475,103,232 / 3,818,440 07:17:41 INFO - TinderboxPrint: CPU idle
8,247.8 (86.8%) 07:17:41 INFO - TinderboxPrint: CPU user
1,210.7 (12.7%) 07:17:41 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 16384; Write bytes: 34746880; Read time: 25510; Write time: 238280 07:17:41 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 89545728; Write bytes: 403715072; Read time: 1417660; Write time: 3393960 07:17:41 INFO - Running post-run listener: _upload_blobber_files 07:17:41 INFO - Blob upload gear active. 07:17:41 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 07:17:41 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 07:17:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 07:17:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 07:17:42 INFO - (blobuploader) - INFO - Open directory for files ... 07:17:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 07:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:43 INFO - (blobuploader) - INFO - Done attempting. 07:17:43 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 07:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:17:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:45 INFO - (blobuploader) - INFO - Done attempting. 07:17:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 07:17:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:17:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:17:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:17:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:17:53 INFO - (blobuploader) - INFO - Done attempting. 07:17:53 INFO - (blobuploader) - INFO - Iteration through files over. 07:17:53 INFO - Return code: 0 07:17:53 INFO - rmtree: C:\slave\test\build\uploaded_files.json 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 07:17:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa64289f6f9f5fa98ed4f04cd7df2156d637231f649bdd92f08175fb62778b36add6317d13055a32b1e61c6953519c7f0b4fed985f0a09f82f09aa440910b46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c686a772647c3c6484bb6c30ebc7a3c87098073e967c315c54c0bf5b7931432c1eac86e04579e98a6b50708dd818f1651064c8811e3fc6c25f5a7f16f761737", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6565b67f34ea664a99ac82313d256dfa5ba3d1f5beb1123bac747a7c0d537b3da8be7106c157eafff344b5d2d6094e7138d2b6c87546a858db4eabdca56baa7"} 07:17:53 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 07:17:53 INFO - Writing to file C:\slave\test\properties\blobber_files 07:17:53 INFO - Contents: 07:17:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa64289f6f9f5fa98ed4f04cd7df2156d637231f649bdd92f08175fb62778b36add6317d13055a32b1e61c6953519c7f0b4fed985f0a09f82f09aa440910b46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c686a772647c3c6484bb6c30ebc7a3c87098073e967c315c54c0bf5b7931432c1eac86e04579e98a6b50708dd818f1651064c8811e3fc6c25f5a7f16f761737", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6565b67f34ea664a99ac82313d256dfa5ba3d1f5beb1123bac747a7c0d537b3da8be7106c157eafff344b5d2d6094e7138d2b6c87546a858db4eabdca56baa7"} 07:17:53 INFO - Running post-run listener: copy_logs_to_upload_dir 07:17:53 INFO - Copying logs to upload dir... 07:17:53 INFO - mkdir: C:\slave\test\build\upload\logs 07:17:53 INFO - Copying logs to upload dir... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... 07:17:53 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1392.606000 ========= master_lag: -0.29 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 12 secs) (at 2016-10-18 07:17:53.499156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:53.505556) ========= '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-068 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-068 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-068 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa64289f6f9f5fa98ed4f04cd7df2156d637231f649bdd92f08175fb62778b36add6317d13055a32b1e61c6953519c7f0b4fed985f0a09f82f09aa440910b46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c686a772647c3c6484bb6c30ebc7a3c87098073e967c315c54c0bf5b7931432c1eac86e04579e98a6b50708dd818f1651064c8811e3fc6c25f5a7f16f761737", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6565b67f34ea664a99ac82313d256dfa5ba3d1f5beb1123bac747a7c0d537b3da8be7106c157eafff344b5d2d6094e7138d2b6c87546a858db4eabdca56baa7"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476786616/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa64289f6f9f5fa98ed4f04cd7df2156d637231f649bdd92f08175fb62778b36add6317d13055a32b1e61c6953519c7f0b4fed985f0a09f82f09aa440910b46a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8c686a772647c3c6484bb6c30ebc7a3c87098073e967c315c54c0bf5b7931432c1eac86e04579e98a6b50708dd818f1651064c8811e3fc6c25f5a7f16f761737", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6565b67f34ea664a99ac82313d256dfa5ba3d1f5beb1123bac747a7c0d537b3da8be7106c157eafff344b5d2d6094e7138d2b6c87546a858db4eabdca56baa7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:53.619248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:53.619568) ========= '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-068 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-068 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-068 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:53.730766) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:53.731072) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-18 07:17:54.224552) ========= ========= Total master_lag: 6.24 =========