builder: fx-team_win7_ix_test-dromaeojs-e10s-pgo slave: t-w732-ix-015 starttime: 1477007896.44 results: success (0) buildid: 20161020162149 builduid: 8d248d2da27a4cbea9d865f9e6c72f69 revision: 4be40c5468626cd5065cbc79674c933b908a2450 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:16.437866) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:16.438929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:16.439598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:16.617748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:58:16.618204) ========= '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-015 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-015 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-015 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 --16:58:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 15.36 MB/s 16:58:18 (15.36 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.463000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:58:18.111858) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 33 secs) (at 2016-10-20 16:58:18.112609) ========= '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-015 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-015 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-015 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=32.201000 ========= master_lag: 1.55 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 33 secs) (at 2016-10-20 16:58:51.860349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:58:51.860651) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 4be40c5468626cd5065cbc79674c933b908a2450 --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 4be40c5468626cd5065cbc79674c933b908a2450 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-015 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-015 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-015 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-20 16:58:53,019 truncating revision to first 12 chars 2016-10-20 16:58:53,019 Setting DEBUG logging. 2016-10-20 16:58:53,019 attempt 1/10 2016-10-20 16:58:53,019 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4be40c546862?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 16:58:53,539 unpacking tar archive at: fx-team-4be40c546862/testing/mozharness/ 2016-10-20 16:58:53,664 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.429000 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 16:58:53.390188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:53.390582) ========= script_repo_revision: 4be40c5468626cd5065cbc79674c933b908a2450 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:53.391013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:53.391287) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 16:58:53.402380) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 52 secs) (at 2016-10-20 16:58:53.402708) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--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-e10s', '--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-015 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-015 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-015 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 16:58:54 INFO - MultiFileLogger online at 20161020 16:58:54 in C:\slave\test 16:58:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:58:54 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 16:58:54 INFO - {'append_to_log': False, 16:58:54 INFO - 'base_work_dir': 'C:\\slave\\test', 16:58:54 INFO - 'blob_upload_branch': 'Fx-Team', 16:58:54 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 16:58:54 INFO - 'branch': 'Fx-Team', 16:58:54 INFO - 'buildbot_json_path': 'buildprops.json', 16:58:54 INFO - 'config_files': ('talos/windows_config.py',), 16:58:54 INFO - 'default_actions': ('clobber', 16:58:54 INFO - 'read-buildbot-config', 16:58:54 INFO - 'download-and-extract', 16:58:54 INFO - 'populate-webroot', 16:58:54 INFO - 'create-virtualenv', 16:58:54 INFO - 'install', 16:58:54 INFO - 'run-tests'), 16:58:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:58:54 INFO - 'download_minidump_stackwalk': True, 16:58:54 INFO - 'download_symbols': 'ondemand', 16:58:54 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 16:58:54 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 16:58:54 INFO - 'hg': 'c:/mozilla-build/hg/hg', 16:58:54 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 16:58:54 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 16:58:54 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 16:58:54 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 16:58:54 INFO - 'C:/mozilla-build/tooltool.py'), 16:58:54 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 16:58:54 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 16:58:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:58:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:58:54 INFO - 'installer_path': 'installer.exe', 16:58:54 INFO - 'log_level': 'info', 16:58:54 INFO - 'log_name': 'talos', 16:58:54 INFO - 'log_to_console': True, 16:58:54 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 16:58:54 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 16:58:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 16:58:54 INFO - 'opt_config_files': (), 16:58:54 INFO - 'pip_index': False, 16:58:54 INFO - 'sps_profile': False, 16:58:54 INFO - 'sps_profile_interval': 0, 16:58:54 INFO - 'suite': 'dromaeojs-e10s', 16:58:54 INFO - 'system_bits': '32', 16:58:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:58:54 INFO - 'title': 'T-W732-IX-015', 16:58:54 INFO - 'use_talos_json': True, 16:58:54 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 16:58:54 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 16:58:54 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 16:58:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:58:54 INFO - 'work_dir': 'build'} 16:58:54 INFO - [mozharness: 2016-10-20 23:58:54.588000Z] Running clobber step. 16:58:54 INFO - Running pre-action listener: _resource_record_pre_action 16:58:54 INFO - Running main action method: clobber 16:58:54 INFO - rmtree: C:\slave\test\build 16:58:54 INFO - Using _rmtree_windows ... 16:58:54 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 17:01:00 INFO - Running post-action listener: _resource_record_post_action 17:01:00 INFO - [mozharness: 2016-10-21 00:01:00.182000Z] Finished clobber step (success) 17:01:00 INFO - [mozharness: 2016-10-21 00:01:00.182000Z] Running read-buildbot-config step. 17:01:00 INFO - Running pre-action listener: _resource_record_pre_action 17:01:00 INFO - Running main action method: read_buildbot_config 17:01:00 INFO - Using buildbot properties: 17:01:00 INFO - { 17:01:00 INFO - "project": "", 17:01:00 INFO - "product": "firefox", 17:01:00 INFO - "script_repo_revision": "production", 17:01:00 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs-e10s", 17:01:00 INFO - "repository": "", 17:01:00 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs-e10s", 17:01:00 INFO - "buildid": "20161020162149", 17:01:00 INFO - "stage_platform": "win32-pgo", 17:01:00 INFO - "basedir": "C:\\slave\\test", 17:01:00 INFO - "buildnumber": 206, 17:01:00 INFO - "platform": "win7_ix", 17:01:00 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 17:01:00 INFO - "slavebuilddir": "test", 17:01:00 INFO - "branch": "fx-team", 17:01:00 INFO - "revision": "4be40c5468626cd5065cbc79674c933b908a2450", 17:01:00 INFO - "slavename": "t-w732-ix-015", 17:01:00 INFO - "builduid": "8d248d2da27a4cbea9d865f9e6c72f69", 17:01:00 INFO - "repo_path": "integration/fx-team" 17:01:00 INFO - } 17:01:00 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json. 17:01:00 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip. 17:01:00 INFO - Running post-action listener: _resource_record_post_action 17:01:00 INFO - [mozharness: 2016-10-21 00:01:00.186000Z] Finished read-buildbot-config step (success) 17:01:00 INFO - [mozharness: 2016-10-21 00:01:00.186000Z] Running download-and-extract step. 17:01:00 INFO - Running pre-action listener: _resource_record_pre_action 17:01:00 INFO - Running main action method: download_and_extract 17:01:00 INFO - mkdir: C:\slave\test\build\tests 17:01:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:01:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json 17:01:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 17:01:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/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 17:01:00 INFO - Downloaded 1218 bytes. 17:01:00 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 17:01:00 INFO - Using the following test package requirements: 17:01:00 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 17:01:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 17:01:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 17:01:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'jsshell-win32.zip'], 17:01:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 17:01:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 17:01:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 17:01:00 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 17:01:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 17:01:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 17:01:00 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 17:01:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 17:01:00 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/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip 17:01:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 17:01:00 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip into memory 17:01:01 INFO - Http code: 200 17:01:01 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 17:01:01 INFO - x-amz-cf-id: F372c3LR1cDVQZwwOsqF8AQbiawYooToWeZ_KTfQxltmi3DoKqCueg== 17:01:01 INFO - x-amz-replication-status: COMPLETED 17:01:01 INFO - x-amz-version-id: cTZZFYOy9SwoEB4Y3J4Ci4tcbPSVhUSP 17:01:01 INFO - Expected file size: 17265184 17:01:01 INFO - Obtained file size: 17265184 17:02:05 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 17:02:05 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/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip 17:02:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 17:02:05 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.common.tests.zip into memory 17:02:05 INFO - Http code: 200 17:02:05 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 17:02:05 INFO - x-amz-cf-id: wUfZK2IW_79M75fQRZMeXcbjOv63Asj_dmO856DKf-cR7z7uYJqu2g== 17:02:05 INFO - x-amz-replication-status: COMPLETED 17:02:05 INFO - x-amz-version-id: cTZZFYOy9SwoEB4Y3J4Ci4tcbPSVhUSP 17:02:06 INFO - Expected file size: 17265184 17:02:06 INFO - Obtained file size: 17265184 17:02:30 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/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip 17:02:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 17:02:30 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 17:02:30 INFO - Http code: 200 17:02:30 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 17:02:30 INFO - x-amz-cf-id: CZvvgy2REyIHCdT30sSuhJ_Zu3aNdnrj0Y6dEHMubRTnGwj6wHd0HQ== 17:02:30 INFO - x-amz-replication-status: COMPLETED 17:02:30 INFO - x-amz-version-id: p77Yjq.KeWVzIFCPMnnvKR29uzVsu4AN 17:02:30 INFO - Expected file size: 14409028 17:02:30 INFO - Obtained file size: 14409028 17:02: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')]} 17:02:56 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip 17:02:56 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 17:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 17:02:59 INFO - Downloaded 54323664 bytes. 17:02:59 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip 17:02:59 INFO - mkdir: C:\slave\test\properties 17:02:59 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 17:02:59 INFO - Writing to file C:\slave\test\properties\build_url 17:02:59 INFO - Contents: 17:02:59 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip 17:02:59 INFO - Running post-action listener: _resource_record_post_action 17:02:59 INFO - Running post-action listener: set_extra_try_arguments 17:02:59 INFO - [mozharness: 2016-10-21 00:02:59.542000Z] Finished download-and-extract step (success) 17:02:59 INFO - [mozharness: 2016-10-21 00:02:59.542000Z] Running populate-webroot step. 17:02:59 INFO - Running pre-action listener: _resource_record_pre_action 17:02:59 INFO - Running main action method: populate_webroot 17:02:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 17:02:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 17:02:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 17:02:59 INFO - u'--tppagecycles', 17:02:59 INFO - u'7'], 17:02:59 INFO - u'tests': [u'tsvgm']}}, 17:02:59 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 17:02:59 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 17:02:59 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 17:02:59 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 17:02:59 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:02:59 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:02:59 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'damp', u'tps']}, 17:02:59 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'tests': [u'damp', u'tps']}, 17:02:59 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'dromaeo_dom']}, 17:02:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 17:02:59 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'basic_compositor_video']}, 17:02:59 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 17:02:59 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'other-e10s': {u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'a11yr', 17:02:59 INFO - u'ts_paint', 17:02:59 INFO - u'tpaint', 17:02:59 INFO - u'sessionrestore', 17:02:59 INFO - u'sessionrestore_no_auto_restore', 17:02:59 INFO - u'tabpaint']}, 17:02:59 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'tsvgx', 17:02:59 INFO - u'tsvgr_opacity', 17:02:59 INFO - u'tart', 17:02:59 INFO - u'tscrollx', 17:02:59 INFO - u'cart']}, 17:02:59 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 17:02:59 INFO - u'tsvgr_opacity', 17:02:59 INFO - u'tart', 17:02:59 INFO - u'tscrollx', 17:02:59 INFO - u'cart']}, 17:02:59 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'talos_options': [u'--disable-e10s'], 17:02:59 INFO - u'tests': [u'tp5o']}, 17:02:59 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'tests': [u'tp5o']}, 17:02:59 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'talos_options': [u'--disable-e10s', 17:02:59 INFO - u'--xperf_path', 17:02:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:59 INFO - u'tests': [u'tp5n']}, 17:02:59 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:02:59 INFO - u'talos_options': [u'--xperf_path', 17:02:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:02:59 INFO - u'tests': [u'tp5n']}}, 17:02:59 INFO - u'talos.zip': {u'path': u'', 17:02:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:02:59 INFO - Running post-action listener: _resource_record_post_action 17:02:59 INFO - [mozharness: 2016-10-21 00:02:59.612000Z] Finished populate-webroot step (success) 17:02:59 INFO - [mozharness: 2016-10-21 00:02:59.612000Z] Running create-virtualenv step. 17:02:59 INFO - Running pre-action listener: _resource_record_pre_action 17:02:59 INFO - Running main action method: create_virtualenv 17:02:59 INFO - Creating virtualenv C:\slave\test\build/venv 17:02:59 INFO - mkdir: C:\slave\test\build\venv\Scripts 17:03:00 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 17:03:00 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'! 17:03:00 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 17:03:00 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 17:03:46 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 17:03:57 INFO - Installing distribute......................................................................................................................................................................................done. 17:04:07 INFO - Return code: 0 17:04:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 17:04:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 17:04:08 INFO - Reading from file tmpfile_stdout 17:04:08 INFO - Output received: 17:04:08 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 17:04:08 INFO - Using _rmtree_windows ... 17:04:08 INFO - Using _rmtree_windows ... 17:04:08 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 17:04:08 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')]} 17:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:08 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')]} 17:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:08 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:08 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 17:04:08 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 17:04:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:08 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:08 INFO - 'DCLOCATION': 'SCL3', 17:04:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:08 INFO - 'HOMEDRIVE': 'C:', 17:04:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:08 INFO - 'KTS_VERSION': '1.19c', 17:04:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:08 INFO - 'MOZ_NO_REMOTE': '1', 17:04:08 INFO - 'NO_EM_RESTART': '1', 17:04:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:08 INFO - 'OS': 'Windows_NT', 17:04:08 INFO - 'OURDRIVE': 'C:', 17:04:08 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;', 17:04:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:08 INFO - 'PROCESSOR_LEVEL': '6', 17:04:08 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:08 INFO - 'PROMPT': '$P$G', 17:04:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:08 INFO - 'PWD': 'C:\\slave\\test', 17:04:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:08 INFO - 'SYSTEMDRIVE': 'C:', 17:04:08 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:08 INFO - 'TEST1': 'testie', 17:04:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:08 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:08 INFO - 'USERNAME': 'cltbld', 17:04:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:08 INFO - 'WINDIR': 'C:\\windows', 17:04:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:10 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:04:10 INFO - Downloading/unpacking pip>=1.5 17:04:18 INFO - Running setup.py egg_info for package pip 17:04:18 INFO - warning: no files found matching 'pip\cacert.pem' 17:04:18 INFO - warning: no files found matching '*.html' under directory 'docs' 17:04:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 17:04:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 17:04:18 INFO - Installing collected packages: pip 17:04:18 INFO - Found existing installation: pip 0.8.2 17:04:18 INFO - Uninstalling pip: 17:04:18 INFO - Successfully uninstalled pip 17:04:18 INFO - Running setup.py install for pip 17:04:18 INFO - warning: no files found matching 'pip\cacert.pem' 17:04:18 INFO - warning: no files found matching '*.html' under directory 'docs' 17:04:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 17:04:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 17:04:18 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 17:04:18 INFO - Successfully installed pip 17:04:18 INFO - Cleaning up... 17:04:18 INFO - Return code: 0 17:04:18 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 17:04:18 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')]} 17:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:18 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')]} 17:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:18 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:18 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 17:04:18 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 17:04:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:18 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:18 INFO - 'DCLOCATION': 'SCL3', 17:04:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:18 INFO - 'HOMEDRIVE': 'C:', 17:04:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:18 INFO - 'KTS_VERSION': '1.19c', 17:04:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:18 INFO - 'MOZ_NO_REMOTE': '1', 17:04:18 INFO - 'NO_EM_RESTART': '1', 17:04:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:18 INFO - 'OS': 'Windows_NT', 17:04:18 INFO - 'OURDRIVE': 'C:', 17:04:18 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;', 17:04:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:18 INFO - 'PROCESSOR_LEVEL': '6', 17:04:18 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:18 INFO - 'PROMPT': '$P$G', 17:04:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:18 INFO - 'PWD': 'C:\\slave\\test', 17:04:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:18 INFO - 'SYSTEMDRIVE': 'C:', 17:04:18 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:18 INFO - 'TEST1': 'testie', 17:04:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:18 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:18 INFO - 'USERNAME': 'cltbld', 17:04:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:18 INFO - 'WINDIR': 'C:\\windows', 17:04:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:31 INFO - Downloading/unpacking psutil>=3.1.1 17:04: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 17:04: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 17:04:32 INFO - Installing collected packages: psutil 17:04:33 INFO - Successfully installed psutil 17:04:33 INFO - Cleaning up... 17:04:33 INFO - Return code: 0 17:04:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 17:04: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')]} 17:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04: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')]} 17:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:33 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 17:04:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:04:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:33 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:33 INFO - 'DCLOCATION': 'SCL3', 17:04:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:33 INFO - 'HOMEDRIVE': 'C:', 17:04:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:33 INFO - 'KTS_VERSION': '1.19c', 17:04:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:33 INFO - 'MOZ_NO_REMOTE': '1', 17:04:33 INFO - 'NO_EM_RESTART': '1', 17:04:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:33 INFO - 'OS': 'Windows_NT', 17:04:33 INFO - 'OURDRIVE': 'C:', 17:04: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;', 17:04:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:33 INFO - 'PROCESSOR_LEVEL': '6', 17:04:33 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:33 INFO - 'PROMPT': '$P$G', 17:04:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:33 INFO - 'PWD': 'C:\\slave\\test', 17:04:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:33 INFO - 'SYSTEMDRIVE': 'C:', 17:04:33 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:33 INFO - 'TEST1': 'testie', 17:04:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:33 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:33 INFO - 'USERNAME': 'cltbld', 17:04:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:33 INFO - 'WINDIR': 'C:\\windows', 17:04:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:04:33 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 17:04:33 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 17:04:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:04:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 17:04:34 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) 17:04:34 INFO - Installing collected packages: mozsystemmonitor 17:04:34 INFO - Running setup.py install for mozsystemmonitor 17:04:34 INFO - Successfully installed mozsystemmonitor 17:04:34 INFO - Cleaning up... 17:04:35 INFO - Return code: 0 17:04:35 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 17:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:35 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:35 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 17:04:35 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 17:04:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:35 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:35 INFO - 'DCLOCATION': 'SCL3', 17:04:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:35 INFO - 'HOMEDRIVE': 'C:', 17:04:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:35 INFO - 'KTS_VERSION': '1.19c', 17:04:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:35 INFO - 'MOZ_NO_REMOTE': '1', 17:04:35 INFO - 'NO_EM_RESTART': '1', 17:04:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:35 INFO - 'OS': 'Windows_NT', 17:04:35 INFO - 'OURDRIVE': 'C:', 17:04:35 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;', 17:04:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:35 INFO - 'PROCESSOR_LEVEL': '6', 17:04:35 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:35 INFO - 'PROMPT': '$P$G', 17:04:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:35 INFO - 'PWD': 'C:\\slave\\test', 17:04:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:35 INFO - 'SYSTEMDRIVE': 'C:', 17:04:35 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:35 INFO - 'TEST1': 'testie', 17:04:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:35 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:35 INFO - 'USERNAME': 'cltbld', 17:04:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:35 INFO - 'WINDIR': 'C:\\windows', 17:04:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:35 INFO - Downloading/unpacking jsonschema==2.5.1 17:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:04:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:04:36 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 17:04:36 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 17:04:36 INFO - Downloading functools32-3.2.3-2.tar.gz 17:04:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 17:04:36 INFO - warning: no files found matching '*.txt' 17:04:36 INFO - no previously-included directories found matching 'build' 17:04:36 INFO - no previously-included directories found matching 'dist' 17:04:36 INFO - no previously-included directories found matching '.git*' 17:04:36 INFO - Installing collected packages: jsonschema, functools32 17:04:36 INFO - Running setup.py install for functools32 17:04:36 INFO - warning: no files found matching '*.txt' 17:04:36 INFO - no previously-included directories found matching 'build' 17:04:36 INFO - no previously-included directories found matching 'dist' 17:04:36 INFO - no previously-included directories found matching '.git*' 17:04:37 INFO - Successfully installed jsonschema functools32 17:04:37 INFO - Cleaning up... 17:04:37 INFO - Return code: 0 17:04:37 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:37 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:37 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 17:04:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:04:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:37 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:37 INFO - 'DCLOCATION': 'SCL3', 17:04:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:37 INFO - 'HOMEDRIVE': 'C:', 17:04:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:37 INFO - 'KTS_VERSION': '1.19c', 17:04:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:37 INFO - 'MOZ_NO_REMOTE': '1', 17:04:37 INFO - 'NO_EM_RESTART': '1', 17:04:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:37 INFO - 'OS': 'Windows_NT', 17:04:37 INFO - 'OURDRIVE': 'C:', 17:04:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:04:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:37 INFO - 'PROCESSOR_LEVEL': '6', 17:04:37 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:37 INFO - 'PROMPT': '$P$G', 17:04:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:37 INFO - 'PWD': 'C:\\slave\\test', 17:04:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:37 INFO - 'SYSTEMDRIVE': 'C:', 17:04:37 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:37 INFO - 'TEST1': 'testie', 17:04:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:37 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:37 INFO - 'USERNAME': 'cltbld', 17:04:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:37 INFO - 'WINDIR': 'C:\\windows', 17:04:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 17:04:37 INFO - Cleaning up... 17:04:37 INFO - Return code: 0 17:04:37 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:37 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:37 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 17:04:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:04:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:37 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:37 INFO - 'DCLOCATION': 'SCL3', 17:04:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:37 INFO - 'HOMEDRIVE': 'C:', 17:04:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:37 INFO - 'KTS_VERSION': '1.19c', 17:04:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:37 INFO - 'MOZ_NO_REMOTE': '1', 17:04:37 INFO - 'NO_EM_RESTART': '1', 17:04:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:37 INFO - 'OS': 'Windows_NT', 17:04:37 INFO - 'OURDRIVE': 'C:', 17:04:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:04:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:37 INFO - 'PROCESSOR_LEVEL': '6', 17:04:37 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:37 INFO - 'PROMPT': '$P$G', 17:04:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:37 INFO - 'PWD': 'C:\\slave\\test', 17:04:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:37 INFO - 'SYSTEMDRIVE': 'C:', 17:04:37 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:37 INFO - 'TEST1': 'testie', 17:04:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:37 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:37 INFO - 'USERNAME': 'cltbld', 17:04:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:37 INFO - 'WINDIR': 'C:\\windows', 17:04:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:38 INFO - Downloading/unpacking blobuploader==1.2.4 17:04:38 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 17:04:38 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 17:04:39 INFO - Downloading blobuploader-1.2.4.tar.gz 17:04:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 17:04:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 17:04:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:04:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:04:40 INFO - Downloading docopt-0.6.1.tar.gz 17:04:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 17:04:40 INFO - Installing collected packages: blobuploader, requests, docopt 17:04:40 INFO - Running setup.py install for blobuploader 17:04:40 INFO - Running setup.py install for requests 17:04:41 INFO - Running setup.py install for docopt 17:04:41 INFO - Successfully installed blobuploader requests docopt 17:04:41 INFO - Cleaning up... 17:04:42 INFO - Return code: 0 17:04:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 17:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:42 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:42 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 17:04:42 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 17:04:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:42 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:42 INFO - 'DCLOCATION': 'SCL3', 17:04:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:42 INFO - 'HOMEDRIVE': 'C:', 17:04:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:42 INFO - 'KTS_VERSION': '1.19c', 17:04:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:42 INFO - 'MOZ_NO_REMOTE': '1', 17:04:42 INFO - 'NO_EM_RESTART': '1', 17:04:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:42 INFO - 'OS': 'Windows_NT', 17:04:42 INFO - 'OURDRIVE': 'C:', 17:04:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:04:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:42 INFO - 'PROCESSOR_LEVEL': '6', 17:04:42 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:42 INFO - 'PROMPT': '$P$G', 17:04:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:42 INFO - 'PWD': 'C:\\slave\\test', 17:04:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:42 INFO - 'SYSTEMDRIVE': 'C:', 17:04:42 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:42 INFO - 'TEST1': 'testie', 17:04:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:42 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:42 INFO - 'USERNAME': 'cltbld', 17:04:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:42 INFO - 'WINDIR': 'C:\\windows', 17:04:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u1hgpd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5bhnkz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6r0xf7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkvwqw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u660gb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-coxxms-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 17:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9bf4i8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 17:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 17:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-54yykh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 17:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 17:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rkn2_s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 17:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 17:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hpppcr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 17:04:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 17:04:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tpau3s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 17:04:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 17:04:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1mttlo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 17:04:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 17:04:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rskwy4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 17:04:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 17:04:46 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lehm2r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 17:04:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 17:04:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvcifl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 17:04:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 17:04:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mm7cpc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 17:04:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 17:04:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nrorvq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 17:04:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:04:49 INFO - Running setup.py install for manifestparser 17:04:49 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 17:04:49 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 17:04:50 INFO - Running setup.py install for mozcrash 17:04:50 INFO - Running setup.py install for mozdebug 17:04:51 INFO - Running setup.py install for mozdevice 17:04:51 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 17:04:51 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 17:04:51 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 17:04:51 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 17:04:51 INFO - Running setup.py install for mozfile 17:04:53 INFO - Running setup.py install for mozhttpd 17:04:53 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 17:04:53 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 17:04:53 INFO - Running setup.py install for mozinfo 17:04:53 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 17:04:53 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 17:04:53 INFO - Running setup.py install for mozInstall 17:04:54 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 17:04:54 INFO - Running setup.py install for mozleak 17:04:55 INFO - Running setup.py install for mozlog 17:04:55 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 17:04:55 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 17:04:55 INFO - Running setup.py install for moznetwork 17:04:56 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 17:04:56 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 17:04:56 INFO - Running setup.py install for mozprocess 17:04:56 INFO - Running setup.py install for mozprofile 17:04:57 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Running setup.py install for mozrunner 17:04:57 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 17:04:57 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 17:04:58 INFO - Running setup.py install for mozscreenshot 17:04:58 INFO - Running setup.py install for moztest 17:04:58 INFO - Running setup.py install for mozversion 17:04:59 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 17:04:59 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 17:04:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:04:59 INFO - Cleaning up... 17:04:59 INFO - Return code: 0 17:04:59 INFO - Installing None into virtualenv C:\slave\test\build/venv 17:04:59 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')]} 17:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:04:59 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')]} 17:04:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:04:59 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:04:59 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 17:04:59 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 17:04:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:04:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:04:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:04:59 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:04:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:04:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:04:59 INFO - 'DCLOCATION': 'SCL3', 17:04:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:04:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:04:59 INFO - 'HOMEDRIVE': 'C:', 17:04:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:04:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:04:59 INFO - 'KTS_VERSION': '1.19c', 17:04:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:04:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:04:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:04:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:04:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:59 INFO - 'MOZ_NO_REMOTE': '1', 17:04:59 INFO - 'NO_EM_RESTART': '1', 17:04:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:04:59 INFO - 'OS': 'Windows_NT', 17:04:59 INFO - 'OURDRIVE': 'C:', 17:04:59 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;', 17:04:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:04:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:04:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:04:59 INFO - 'PROCESSOR_LEVEL': '6', 17:04:59 INFO - 'PROCESSOR_REVISION': '1e05', 17:04:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:04:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:04:59 INFO - 'PROMPT': '$P$G', 17:04:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:04:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:04:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:04:59 INFO - 'PWD': 'C:\\slave\\test', 17:04:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:04:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:04:59 INFO - 'SYSTEMDRIVE': 'C:', 17:04:59 INFO - 'SYSTEMROOT': 'C:\\windows', 17:04:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:59 INFO - 'TEST1': 'testie', 17:04:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:04:59 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:04:59 INFO - 'USERNAME': 'cltbld', 17:04:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:04:59 INFO - 'WINDIR': 'C:\\windows', 17:04:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:04:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:04:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:04:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:04:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 17:04:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7eimtk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 17:04:59 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)) 17:04:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 17:04:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z5aixh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b1aza7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t1rezj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o38wkx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqdoby-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-om5kxp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8jybcj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 17:05:00 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)) 17:05:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 17:05:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-okxpkt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 17:05:01 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)) 17:05:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 17:05:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-odgjlf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 17:05:01 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)) 17:05:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 17:05:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6pfrdw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 17:05:01 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)) 17:05:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 17:05:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-akkpxj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 17:05:05 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)) 17:05:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 17:05:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ye3xaw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 17:05:06 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)) 17:05:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 17:05:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hmejms-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 17:05:06 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)) 17:05:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 17:05:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h9lfzn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 17:05:06 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)) 17:05:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 17:05:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xuhip5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 17:05:06 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)) 17:05:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 17:05:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0pt6mz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 17:05:06 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)) 17:05:06 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)) 17:05:06 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)) 17:05:06 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)) 17:05:06 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)) 17:05:06 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)) 17:05:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 17:05:06 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 17:05:06 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 17:05:07 INFO - Downloading blessings-1.6.tar.gz 17:05:07 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 17:05:08 INFO - Installing collected packages: blessings 17:05:08 INFO - Running setup.py install for blessings 17:05:08 INFO - Successfully installed blessings 17:05:08 INFO - Cleaning up... 17:05:11 INFO - Return code: 0 17:05:11 INFO - Done creating virtualenv C:\slave\test\build/venv. 17:05:11 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 17:05:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 17:05:11 INFO - Reading from file tmpfile_stdout 17:05:11 INFO - Using _rmtree_windows ... 17:05:11 INFO - Using _rmtree_windows ... 17:05:11 INFO - Current package versions: 17:05:11 INFO - blessings == 1.6 17:05:11 INFO - blobuploader == 1.2.4 17:05:11 INFO - docopt == 0.6.1 17:05:11 INFO - functools32 == 3.2.3-2 17:05:11 INFO - jsonschema == 2.5.1 17:05:11 INFO - manifestparser == 1.1 17:05:11 INFO - mozInstall == 1.12 17:05:11 INFO - mozcrash == 0.17 17:05:11 INFO - mozdebug == 0.1 17:05:11 INFO - mozdevice == 0.48 17:05:11 INFO - mozfile == 1.2 17:05:11 INFO - mozhttpd == 0.7 17:05:11 INFO - mozinfo == 0.9 17:05:11 INFO - mozleak == 0.1 17:05:11 INFO - mozlog == 3.4 17:05:11 INFO - moznetwork == 0.27 17:05:11 INFO - mozprocess == 0.23 17:05:11 INFO - mozprofile == 0.28 17:05:11 INFO - mozrunner == 6.12 17:05:11 INFO - mozscreenshot == 0.1 17:05:11 INFO - mozsystemmonitor == 0.3 17:05:11 INFO - moztest == 0.7 17:05:11 INFO - mozversion == 1.4 17:05:11 INFO - psutil == 3.1.1 17:05:11 INFO - requests == 1.2.3 17:05:11 INFO - Installing None into virtualenv C:\slave\test\build/venv 17:05:11 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')]} 17:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:11 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')]} 17:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:11 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:05:11 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 17:05:11 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 17:05:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:05:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:05:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:05:11 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:05:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:05:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:05:11 INFO - 'DCLOCATION': 'SCL3', 17:05:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:05:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:05:11 INFO - 'HOMEDRIVE': 'C:', 17:05:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:05:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:05:11 INFO - 'KTS_VERSION': '1.19c', 17:05:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:05:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:05:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:05:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:11 INFO - 'MOZ_NO_REMOTE': '1', 17:05:11 INFO - 'NO_EM_RESTART': '1', 17:05:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:05:11 INFO - 'OS': 'Windows_NT', 17:05:11 INFO - 'OURDRIVE': 'C:', 17:05:11 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;', 17:05:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:05:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:05:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:05:11 INFO - 'PROCESSOR_LEVEL': '6', 17:05:11 INFO - 'PROCESSOR_REVISION': '1e05', 17:05:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:05:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:05:11 INFO - 'PROMPT': '$P$G', 17:05:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:05:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:05:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:05:11 INFO - 'PWD': 'C:\\slave\\test', 17:05:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:05:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:05:11 INFO - 'SYSTEMDRIVE': 'C:', 17:05:11 INFO - 'SYSTEMROOT': 'C:\\windows', 17:05:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:11 INFO - 'TEST1': 'testie', 17:05:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:11 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:05:11 INFO - 'USERNAME': 'cltbld', 17:05:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:05:11 INFO - 'WINDIR': 'C:\\windows', 17:05:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:05:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 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)) 17:05:12 INFO - Cleaning up... 17:05:12 INFO - Return code: 0 17:05:12 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 17:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:05:12 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 0x01858640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01892E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017D2B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-015', 'USERDOMAIN': 'T-W732-IX-015', '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-015', '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 17:05:12 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 17:05:12 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 17:05:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:05:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:05:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:05:12 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:05:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:05:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:05:12 INFO - 'DCLOCATION': 'SCL3', 17:05:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:05:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:05:12 INFO - 'HOMEDRIVE': 'C:', 17:05:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:05:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:05:12 INFO - 'KTS_VERSION': '1.19c', 17:05:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:05:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:05:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:05:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:12 INFO - 'MOZ_NO_REMOTE': '1', 17:05:12 INFO - 'NO_EM_RESTART': '1', 17:05:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:05:12 INFO - 'OS': 'Windows_NT', 17:05:12 INFO - 'OURDRIVE': 'C:', 17:05:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:05:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:05:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:05:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:05:12 INFO - 'PROCESSOR_LEVEL': '6', 17:05:12 INFO - 'PROCESSOR_REVISION': '1e05', 17:05:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:05:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:05:12 INFO - 'PROMPT': '$P$G', 17:05:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:05:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:05:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:05:12 INFO - 'PWD': 'C:\\slave\\test', 17:05:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:05:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:05:12 INFO - 'SYSTEMDRIVE': 'C:', 17:05:12 INFO - 'SYSTEMROOT': 'C:\\windows', 17:05:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:12 INFO - 'TEST1': 'testie', 17:05:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:12 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:05:12 INFO - 'USERNAME': 'cltbld', 17:05:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:05:12 INFO - 'WINDIR': 'C:\\windows', 17:05:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:05:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 17:05:12 INFO - Cleaning up... 17:05:12 INFO - Return code: 0 17:05:12 INFO - Running post-action listener: _resource_record_post_action 17:05:12 INFO - Running post-action listener: _start_resource_monitoring 17:05:12 INFO - Starting resource monitoring. 17:05:12 INFO - [mozharness: 2016-10-21 00:05:12.536000Z] Finished create-virtualenv step (success) 17:05:12 INFO - [mozharness: 2016-10-21 00:05:12.536000Z] Running install step. 17:05:12 INFO - Running pre-action listener: _resource_record_pre_action 17:05:12 INFO - Running main action method: install 17:05:12 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 17:05:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 17:05:12 INFO - Reading from file tmpfile_stdout 17:05:12 INFO - Using _rmtree_windows ... 17:05:12 INFO - Using _rmtree_windows ... 17:05:12 INFO - Detecting whether we're running mozinstall >=1.0... 17:05:12 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 17:05:12 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 17:05:13 INFO - Reading from file tmpfile_stdout 17:05:13 INFO - Output received: 17:05:13 INFO - Usage: mozinstall-script.py [options] installer 17:05:13 INFO - Options: 17:05:13 INFO - -h, --help show this help message and exit 17:05:13 INFO - -d DEST, --destination=DEST 17:05:13 INFO - Directory to install application into. [default: 17:05:13 INFO - "C:\slave\test"] 17:05:13 INFO - --app=APP Application being installed. [default: firefox] 17:05:13 INFO - Using _rmtree_windows ... 17:05:13 INFO - Using _rmtree_windows ... 17:05:13 INFO - mkdir: C:\slave\test\build\application 17:05:13 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'] 17:05:13 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 17:05:21 INFO - Reading from file tmpfile_stdout 17:05:21 INFO - Output received: 17:05:21 INFO - C:\slave\test\build\application\firefox\firefox.exe 17:05:21 INFO - Using _rmtree_windows ... 17:05:21 INFO - Using _rmtree_windows ... 17:05:21 INFO - Running post-action listener: _resource_record_post_action 17:05:21 INFO - [mozharness: 2016-10-21 00:05:21.568000Z] Finished install step (success) 17:05:21 INFO - [mozharness: 2016-10-21 00:05:21.568000Z] Running run-tests step. 17:05:21 INFO - Running pre-action listener: _resource_record_pre_action 17:05:21 INFO - Running main action method: run_tests 17:05:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 17:05:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 17:05:21 INFO - Python 2.7.3 17:05:21 INFO - Return code: 0 17:05:21 INFO - grabbing minidump binary from tooltool 17:05: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')]} 17:05:21 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 0x018ABE90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01918520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 17:05:21 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 17:05:21 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 17:05:21 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 17:05:21 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 17:05:23 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp8qwixe 17:05:23 INFO - INFO - File integrity verified, renaming tmp8qwixe to win32-minidump_stackwalk.exe 17:05:23 INFO - Return code: 0 17:05:23 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 17:05:23 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 17:05:23 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 17:05:23 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 17:05:23 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 17:05:23 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-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build 17:05:23 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-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log 17:05:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:05:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 17:05:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 17:05:23 INFO - 'COMPUTERNAME': 'T-W732-IX-015', 17:05:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:05:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 17:05:23 INFO - 'DCLOCATION': 'SCL3', 17:05:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:05:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:05:23 INFO - 'HOMEDRIVE': 'C:', 17:05:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 17:05:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:05:23 INFO - 'KTS_VERSION': '1.19c', 17:05:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 17:05:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-015', 17:05:23 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 17:05:23 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 17:05:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:05:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:05:23 INFO - 'MOZ_NO_REMOTE': '1', 17:05:23 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 17:05:23 INFO - 'NO_EM_RESTART': '1', 17:05:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:05:23 INFO - 'OS': 'Windows_NT', 17:05:23 INFO - 'OURDRIVE': 'C:', 17:05:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 17:05:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:05:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:05:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 17:05:23 INFO - 'PROCESSOR_LEVEL': '6', 17:05:23 INFO - 'PROCESSOR_REVISION': '1e05', 17:05:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:05:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 17:05:23 INFO - 'PROMPT': '$P$G', 17:05:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:05:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:05:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:05:23 INFO - 'PWD': 'C:\\slave\\test', 17:05:23 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 17:05:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:05:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:05:23 INFO - 'SYSTEMDRIVE': 'C:', 17:05:23 INFO - 'SYSTEMROOT': 'C:\\windows', 17:05:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:23 INFO - 'TEST1': 'testie', 17:05:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 17:05:23 INFO - 'USERDOMAIN': 'T-W732-IX-015', 17:05:23 INFO - 'USERNAME': 'cltbld', 17:05:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 17:05:23 INFO - 'WINDIR': 'C:\\windows', 17:05:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 17:05:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 17:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:05:23 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600 17:05:24 INFO - mozversion application_buildid: 20161020123554 17:05:24 INFO - mozversion application_changeset: 4be40c5468626cd5065cbc79674c933b908a2450 17:05:24 INFO - mozversion application_display_name: Nightly 17:05:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:05:24 INFO - mozversion application_name: Firefox 17:05:24 INFO - mozversion application_remotingname: firefox 17:05:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:05:24 INFO - mozversion application_vendor: Mozilla 17:05:24 INFO - mozversion application_version: 52.0a1 17:05:24 INFO - mozversion platform_buildid: 20161020123554 17:05:24 INFO - mozversion platform_changeset: 4be40c5468626cd5065cbc79674c933b908a2450 17:05:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:05:24 INFO - mozversion platform_version: 52.0a1 17:05:24 INFO - using testdate: 1477008324 17:05:24 INFO - actual date: 1477008324 17:05:24 INFO - starting webserver on 'localhost:49783' 17:05:24 INFO - SUITE-START | Running 2 tests 17:05:24 INFO - TEST-START | dromaeo_css 17:05:24 INFO - operating with platform_type : w7_ 17:05:24 INFO - Initialising browser for dromaeo_css test... 17:05:25 INFO - TEST-INFO | started process 2460 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpces_9q\profile http://localhost:49783/getInfo.html) 17:05:53 INFO - PROCESS | 2460 | __metrics Screen width/height:1600/1200 17:05:53 INFO - PROCESS | 2460 | colorDepth:24 17:05:53 INFO - PROCESS | 2460 | Browser inner width/height: 1010/674 17:05:53 INFO - PROCESS | 2460 | __metrics 17:05:53 ERROR - PROCESS | 2460 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 17:05:53 INFO - PROCESS | 2460 | [Child 2540] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 17:05:53 INFO - PROCESS | 2460 | [Child 2540] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 17:05:56 INFO - TEST-INFO | 2460: exit 0 17:05:56 INFO - Browser initialized. 17:05:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:05:56 INFO - TEST-INFO | started process 2396 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpces_9q\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:05:57 INFO - PROCESS | 2396 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 17:06:08 INFO - PROCESS | 2396 | RSS: Main: 130248704 17:06:08 INFO - PROCESS | 2396 | 17:06:57 INFO - PROCESS | 2396 | 1477008417606 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] 17:09:06 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49783/tests/dromaeo/cssquery-ext.html) 17:09:08 INFO - PROCESS | 2396 | RSS: Main: 111386624 17:09:08 INFO - PROCESS | 2396 | 17:12:09 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-ext.html (next: http://localhost:49783/tests/dromaeo/cssquery-jquery.html) 17:12:10 INFO - PROCESS | 2396 | RSS: Main: 83619840 17:12:10 INFO - PROCESS | 2396 | 17:15:12 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49783/tests/dromaeo/cssquery-mootools.html) 17:15:12 INFO - PROCESS | 2396 | RSS: Main: 76963840 17:15:12 INFO - PROCESS | 2396 | 17:18:15 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49783/tests/dromaeo/cssquery-prototype.html) 17:18:16 INFO - PROCESS | 2396 | RSS: Main: 72818688 17:18:16 INFO - PROCESS | 2396 | 17:21:18 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49783/tests/dromaeo/cssquery-yui.html) 17:21:19 INFO - PROCESS | 2396 | RSS: Main: 78753792 17:21:19 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | Cycle 1(1): loaded http://localhost:49783/tests/dromaeo/cssquery-yui.html (next: http://localhost:49783/tests/dromaeo/cssquery-dojo.html) 17:24:21 INFO - PROCESS | 2396 | RSS: Main: 73916416 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | __start_tp_report 17:24:21 INFO - PROCESS | 2396 | _x_x_mozilla_page_load 17:24:21 INFO - PROCESS | 2396 | _x_x_mozilla_page_load_details 17:24:21 INFO - PROCESS | 2396 | |i|pagename|runs| 17:24:21 INFO - PROCESS | 2396 | |0;dojo.html;2795.944081118378;2803.579463080539;2809.101087651952;2815.648043994502;2819.379736457979;4720.079584481027;4754;4765.95234047659;4841.975790121044;4859.635527335447;4740.739259340744;4818.662693611449;4824.228123500241;4838.854834354966;4895.8776030599165;4201.6218540331365;4237.089025859432;4268.850590229343;4277.059047009669;4289.978550107245;4142.565030671782;4169.97756088297;4265.360195970598;4298.376735373369;4332.588404101605;4189.07840275139;4254.191703576326;4316.6978311518205;4321.956780432187;4321.956780432187;4204.096119334357;4206.305959516676;4212.578742125794;4217.240896638604;4223.134257477241;4148.274052040911;4167.499900012018;4224.746515209066;4228.069824638575;4235;4594.517575654541;4642.187617166982;4739.668223224376;4796.688215265996;4799.064182484417;4918.852434426973;4957.628177886673;4958.851234462972;5006.073876332854;5020.548150666421;5024.070546948826;5026.1204289249235;5115.2327150927285;5120.3855537335385;5130.743462826844;4670.673052886299;4672.088942656217;4752.42970843501;4758.547937945889;4759.143354196209;4857.295692124693;5045.974770126126;5095.210242412432;5125.4874512549195;5130.79476820923;4885.877853053702;4893.584045356113;4982.5515703586125;4987.1771157759285;4999.200127979579;4724.126036683225;4859.24681674334;4980.302757613937;5077.822276220389;5078.238264260391;4895.31465594817;4942.184539551005;4944;4997.375328083989;5024.547790698854;4739.478657347689;4989.426215985136;4996.200607902718;5078.390593128848;5085.364329458817;3328.8834890778708;3351.6815902489225;3404.710599599062;3410.5907291125222;3412.522246885438;2893.146521776081;2940.8970686025887;3086.3518661080975;3108.9533656995163;3111.5488254203015;4626.306054091846;4650;4698.577128058491;4709.364235828188;4728.929066064012;4943.629227807857;5008.474110218447;5049.8737531562;5057.671251368649;5144.15121504955;4498.932516012263;4588.77056147198;4801.303810947393;4811.206150985118;4813.614910807197;4173.1862286853775;4205.831766729358;4224.049588842548;4227.87316380509;4260.147970405931;5236.162214045735;5255.316808814906;5266.868328291824;5270.894582108336;5276.15581506957;5017.4731653175895;5053.620978426707;5077.847664570068;5119.360079990001;5123.923141152811;5216.686998780086;5226.346706661668;5320.17537281722;5323.600729945192;5326.8934621308135;4717.575418212377;4882.804687812448;4930.9506904930495;5026.919212369358;5029.220470827027;5024.572911302434;5026.944341688287;5054.949450505448;5062.949370506248;5067.543921047123;4910.361652985232;4919.3604831371695;4994.875128121681;5001;5009.123403404463;4890.9021819562695;4906.582940449959;4986.80052797884;4988.301637770643;4999.475055119306;4763.69036012658;4806.423229212517;4806.95193048079;4862.781174847211;4922.187839006667;4873.366462359871;4914.483979182134;4948.084604348207;4960.330355402119;5019.874503137451;4759.95281038171;4846.588040016638;4849.636277279147;4874.122657921608;4969.105560999054 17:24:21 INFO - PROCESS | 2396 | |1;ext.html;560.6944215402606;568.6929058308514;577.1717585265146;584.1792281844006;592.7154965616502;38645.61354386455;39646.603533964655;40049.199016019666;40531.39202911959;41719.58280417195;40957.385639215434;41280;41350.17299654005;41357.7932110339;41447;8158.877616835753;8257;8267.917320826806;8280.585970701472;8311.16888311167;7901.09137449195;8088.919110808875;8162.3878209134255;8177.427580069398;8179.182081791832;8085.555294458803;8193.910552809026;8212.34301255898;8224.62989165486;8231.629576669064;7811.609419529058;8130.227628375294;8145.144759800224;8152.103268640445;8157.632906519221;7973.401994850409;8139.75580732579;8189.95905020477;8218.520666280067;8220.958895205546;37820.24359512794;38110.618893810984;38141.23717525662;38168.23663526714;38176.427353589715;2967.1840243932875;3025.3495498467714;3036.225762430566;3058.4341896749174;3117.9220519487194;29417.55873661897;29493.115206543836;29820.70179298201;30273.394532109454;30468.238294042603;3404.131946353689;3416.1288871337897;3432.673895979878;3433.8626454941773;3455.1362159460136;300153;303593;305921;306296.46851765824;310072;52368.47631523712;52433.2135017975;52661.47338526642;52743.945121097364;52781.2082818758;54632.45367546273;54795;54863;54895.17657235145;55029.174562381595;11101;11286.774264514665;11474.48364823585;11486.483108259981;11489.655310340537;8826.735197944072;8888.155625215593;8907.777305567282;8935.731928042169;8938.4190027648;16249.187540622921;16267.59331016734;16291.674166516603;16305.347786088423;16367.099809510353;6389.201349831271;6426.35736426363;6484.351564843478;6535.117759102555;6547.639879806657;743.1490942870491;761.3908872901657;853.8676505141713;884.4516399832016;943.1511639524346;26731.73268267331;27368.315792105357;27752.30619234495;27828;27888.44223115567;888.8400087984224;896.1173244354305;928.6703220356811;970.3110791338088;1014.9495272393037;14127.71744565103;14431.278436078153;14531.709365812625;14619.122852628663;14668.55994320172;435015;438550;443116;443876;445225;48160.7591962045;48465.273020904715;49115.75442122838;49134.26298605524;49212.26181607279;1081.7782354617284;1116.263266244275;1125.7410795517078;1134.5307826364876;1137.1982752159872;26666.733332666427;26930.73069269282;26987.730122698522;27081.45837083287;27084.1874743758;41730.58269417267;41980;42061.78969105135;42123;42301.36547951845;381607;386423;386506.2144406904;388802;390122;21580.46048848791;22879.542409151392;36351.63648363482;36579.26841463103;36660.633393665725;41811.581884181986;43007;43031;43227.35158972681;43348.93114963845;34684.30631387308;36753;37535.812320939316;38309.61690383172;38803.80598096991;36757.26485470222;36832.44751328786;37108.257834843695;37128.628713713595;37283.627163728015;27765.722342776313;32580.185495361857;33872.3225535483;34307;34593.65406346005;29704.554431683973;31485.842570787914;31765.68234317627;31803.681963180996;32134.517982229816;24053.75946240585;24254.63618045766;24376.75623243745;24593.754062459146;24644.50710985806 17:24:21 INFO - PROCESS | 2396 | |2;jquery.html;296.3835222736708;299.9490086685264;301.25740050774834;303.12548298159817;303.4053255618987;14278.857211427883;16312.673746525063;16858.83141168588;17889.91055044726;17901.194446249916;9181.678641247558;9197.080291970817;9382.624695012191;9453.291003174754;9467.952660236706;4514.661400394975;4635.745034023127;4930.309756634074;5280;5287.968846075017;5266.552343050846;5268.130758424855;5297.841064768044;5299.099153143975;5303.814366497173;5130.333056702623;5177.352830896138;5270.393904700951;5275.129603615399;5285.603579731535;4968.751562421882;5236.8690782730355;5260.263563101169;5263.947210557865;5263.973680131613;5108.106081435771;5110.310108135429;5118.411382690963;5122.948770512285;5124.180131178993;10640.20198485109;16074.633656139242;20544.897275513675;21245.681314780137;21313.467163321042;1720.552656309356;1757.2092558348795;1775.689254380491;1778.8043315235443;1779.030428416516;8455.873161902577;8500.489970601784;8575.056743758181;8604.655813767442;8628.007779105421;1670.599056226497;1680.6806706725683;1709.7435384692271;1713.6144367517334;1772.6454709058112;82143.17856821415;83839;84603;84731.57634211851;86103.70844437402;8501.404901656948;8580;8852.20330170275;8892.15524525169;8915.197632213132;11268.718282043015;11390.430478476042;11442.37066961325;11443.198976071595;11450.19848610606;5963.5825492216;6125.019996800491;6188.504919606421;6195.81412557624;6214.192155019909;5845.853853653693;5892.351841297454;5952.226210592596;5955.910661340084;5964;3571.321448924696;3608.6030536640947;3628.637136286403;3656.689181419556;3680.871169509054;1518.8936774425906;1521.8097737782778;1548.8451154884422;1550.5425899359716;1553.1768162873695;788.2354116506979;814.8940637717177;872.7381785464462;902.5577467041234;915.6886658535997;17557.64884702299;17684.734728979074;17774.289028438976;18030.075038116876;18035.458936231935;817.6402382951483;818.7748369198519;827.4084029918637;828.8632375658069;872.9825403491894;9687.965527239217;9791.97184295653;9972.850407243897;10007.499625018865;10065.547050382751;373288;398478;401313;406602;409944;94758;99579;100096;101485;101856.49071754594;890.5413711938357;891.6923661336958;903.2728653433917;907.4464576608264;922.9630814767335;16770.32918683239;16828.326866925185;16867.91566042162;16965.491035268962;17332;43236;44932.550674492835;45814;46426;46903.29645055259;60790.696046519486;61066;61520.38479615146;62250.37749622627;62275;7262.782116536512;7279.1628962667855;7296.087989001375;7315.853682926205;7446.180920098893;11384.316940983568;13091.345432728516;13200.603981880558;13234.867651323364;13410.865891340962;10556.472176390997;10770.569177232823;10818.567257309935;11145.888541114486;11237.603749195185;10558.208134389797;10759.623413180501;10785.245032847626;10999.285046471954;11116.16628752863;10609.628662996758;11006.394648294101;11061.778764424827;11222.49498772541;11311.208215425171;7189.173245076883;7259.9274007261365;7274.927250727636;7281.05346304977;7383.630818459163;8497.575121244037;11030.669079927615;11132.554697811996;11161.944190278997;11202.49588768491 17:24:21 INFO - PROCESS | 2396 | |3;mootools.html;1204.5061524774844;1212.8059510478324;1267.2269915351637;1492.432875507307;1494.9177795221258;6024;6164.291106522751;6437.163168788057;6554.213494380681;6758.155230596176;6705.966470167642;6845.31546845314;6885.173779146497;6885.552439091455;6903.723851045952;1830.7162389829598;1838.6690395728763;1855.1188185611861;1863.925442982279;1866.943991680245;1839.13560626506;1856.6750818606758;1863.3757691173473;1872.5880306332585;1875.3436297295905;1840.291487777202;1849.6855534559156;1849.7687789026372;1864.2636158717298;1873.1289950173166;1764.5412192829892;1782.0466050662833;1794.8564114870778;1802.044916194419;1803.0624075480807;1759.12043978011;1785.4822101590523;1785.8660600455019;1787.4637608717462;1791.3819732192374;6629.270780214172;6699.39705426509;6699.928011518183;6715.764948226837;6723.193216814014;3862.3240932836643;3897.8830635080994;3924.529056513237;3937.291287568265;3939.3500072488005;2547.414094758216;2554.5822068751836;2639.1158961747838;2652.681678198609;2663.1078588672817;839.7228914458215;868.5136323658771;871.8648609465542;871.9433236839585;877.2280393253895;8557.786055348604;8612.267957223707;8630.136986301382;8632.956835215846;8642.827143457158;3263.1352691536763;3438.381091403521;3440.139965008748;3440.9655903440644;3441.4493681011418;6979.267176946449;6979.755708550177;6981.1622605286675;6983.9650801745665;7004.194517630436;3996.9400458993136;4004.279229738617;4006;4010.63904248619;4012.959870401317;3371.4268574342423;3462.4633181856852;3777.86777462793;3816.885493435201;3828.617138286205;2888.956665650017;2942.6910174431378;2984.6418429788564;3008.9919876841063;3012.1415396612297;886.4902680958346;887.4143065576689;896.7264984179888;897.2194191053825;900.2213085680839;629.8236493781749;729.1869565434503;730.4923078460419;744.2743325257939;747.8840779679158;4458.576435238647;4464.6428285737065;4527.479339875957;4553.931691024637;4558.156741002925;733.4682355292477;769.7883082152453;792.6036981509245;792.9841403171904;803.269025187077;3064.356485138146;3105;3124;3125.156207823875;3178.110129163838;7794;8050.677972881136;8054.516728996279;8077.717279895175;8133.146019667968;8036.879446808302;8077.798055048554;8077.959610201911;8095.069067057246;8107;6161.291451482957;6309.684515774285;6418.165638467063;6452.806415807533;6497.642629655415;4352.93470597935;4598.356230127718;4744.312074749211;4753;4814.927776083291;7248.275172482709;7275.6362181889635;7350.485466017328;7614.6954121836725;7736.613169341623;7560.773176804704;7724.806879827825;7738.92261077382;7771.106322772954;7787.182345853604;5777.6822274775295;5806.042003069446;5923.378045305353;6023.066424704178;6035.637861728311;6300.928842096763;6533.575317604341;6551.082848401323;6598.472122230114;6631.1379520662;5877.529797616095;6027.819165425044;6093.451589356979;6130.417610327012;6145.692715364304;5868.970655146697;5925.614835035709;5996.88006239864;6056.939430605814;6069.635821850703;5372.355317361941;5379;5382.058139825594;5390.407055223844;5391.272178256042;3380.3577320309064;3582.982085089558;3603.6756691897854;3604.8197590120913;3610.9097272568397;3692.9076773080888;3725.3666876630496;3726.329260733094;3752.061984503874;3796.1079146401125 17:24:21 INFO - PROCESS | 2396 | |4;prototype.html;235.12416249470735;238.381400266309;242.63362322892434;243.09568405531428;249.55205406295698;5277.577793776498;5396.5952553558445;5397.811076612319;5421.21392398102;5451.781928722851;5218.712970786605;5312.9203061954;5317.016351974878;5331.520163185312;5350.5452036576935;3827.4450204720297;3864.574896761354;3867.748596341236;3874.9225015499674;3875;3830.1382189007527;3872.2255548890334;3882.320593896071;3883.3010058189516;3885.863994760184;3680.963190368089;3717.8326975286177;3811.904702382435;3841.5390153181656;3848.634379733921;3815.9236815263816;3822.2928758179687;3825.598312177209;3829.3107240696663;3840.462335273064;3184.5223216517475;3265.297960938386;3268.019594121754;3279.425875579735;3285.293661862711;5099.974500127512;5190.195519694454;5198.480151984809;5200.427952925175;5213.165893457029;4455.131249406367;4481.977590112061;4482.417285752831;4484.461864576239;4490.124425736983;5370.489803468703;5468.425815289377;5515.944840551623;5519.889602207933;5536.723163841792;3605.3510368133725;3613.3495970725257;3626.4197728363597;3631.564212294515;3634.8182590870615;5779.913301300484;5791.247137872049;5791.95744765942;5820.243368362086;5824.912626310609;3698.926021479609;3701.1857391373856;3704.5369328833895;3708.3325001499447;3709.4250391189407;3471.010761932837;3666.3767159582935;3673.687736542424;3734.2344819311975;3760.2667479841716;4273.589715393912;4295.6778241632;4407.316865885793;4407.647388208936;4420.690551661352;5598.944010559923;5625.496653989017;5665.688387138664;5667.914981275284;5670.206171136044;1251.4305990774176;1262.066071107374;1262.5265525427965;1264.9114561980757;1266;739.2164305835723;761.7410080572521;764.2701220334567;780.5511830697371;796.4385108498494;5246.606504512177;5284.128118860422;5298.231756395301;5304.124819404755;5342.572594192456;5740.56945729072;5751.584334579403;5753.683547404849;5754.798582049608;5760.308762948473;5283.313169287968;5294.708791016454;5309.177077552986;5324.7870085196155;5326.627136100436;2989.805662631922;3069.3861227754537;3079.13784140441;3093.8917137900066;3105.928454683131;6002.919474494633;6016.398360164036;6022.109387494027;6042.093685947143;6044.425779550936;5822.184894114827;5858.472737453564;5860.062390017577;5861.32594751609;5875.970620146872;5821.592488525763;5898.056310990307;5973.731182096816;6042.456178943916;6046.637201767908;5708.5147762439;5736.942630573641;5749.223854779551;5767.509761670305;5772.913406298993;5553.250311207935;5640.67444150616;5646.237757902631;5666.660000399989;5671.035923892866;5819.15622234782;5996.610220335664;6016.2479690038745;6022.45797878193;6038.3357830637715;3558.323918455485;3583.7849729016343;3587.0673624857204;3590.3537363274863;3605.6214097519382;5751.626144300607;5753.597248192754;5753.8561535961935;5770.596058276048;5773.191753154478;5351.277577527139;5565.914646643865;5569.498745112959;5580.497755202051;5585.888282234414;5439.836804895859;5499.725013749377;5515.200295957144;5516.558675305886;5536.7231638418725;5401.243825864461;5416.7562459688625;5418;5453.890922181455;5483.232347471437;5453.400125986218;5570.5265052470995;5579.107342825291;5589.608727389043;5595.860103497445;5431.891362172655;5571.804986825442;5576.247206627054;5589.720513974366;5591.490297619701 17:24:21 INFO - PROCESS | 2396 | |5;yui.html;592.4579010205662;671.1275342055327;684.6508280776802;695.9756408525701;698.5983059740647;14654.633634159138;15167.696646067072;15300.770488442668;15487.767683484757;15707.921460392696;14029.088109272916;14189.361478733455;14247.643808904782;14266.572002839905;14291.071080379768;650.6356440393383;656.3010393930473;663.1842833315026;665.105433192358;666.070831190489;632.7469012395056;654.1071437487818;659.2583343738295;661.1471202149222;661.7022339947019;666.9366410191024;696.1819861662551;699.063255237981;699.6781480518941;699.832040310327;646.9159009328804;690.9378155965937;692.7402224165937;693.2997672350937;694.0248950224917;506.9949300506985;507.6624045010064;508.23256882107916;508.4610313068123;508.85497633174737;14275;14799;14916.925415372962;14930.850691493055;14945;13301.733965320736;13443.193408395528;13517.459301628021;13531.59405217845;13677.521286755014;11642.359670218135;11719.648410547697;11823.586174483851;11838.822417663656;11993.460294286691;3222.5810644616286;3279.8524066417067;3291.2759192977505;3326.417876871538;3416.1630400551776;67007.66496167537;81586.18413815845;83461.16538834595;83623.49129526062;84567.15432845715;9759.756006099762;9820.558074886505;9886;10006.09945104944;10057.748556286151;18790.530236743918;18862.62274754497;18897.81102188988;18994.81005189958;19039.143238554298;6787.2534021257625;6927.307269273032;6969.895451568231;7045.92954070463;7151;3716.8699095532065;3726.142987112925;3767.416050512175;3876.437916502148;3878.864239751595;2792.2879665685527;2860.1705505403615;2870.454613623405;2873.7126287371516;2875.8274503529437;480.0518975024369;488.21884984025985;489.4273699771276;491.6018025399438;493.2231735017362;627.1439485102802;657.6678777217475;658.6574981009848;671.6272468779777;688.4870771275432;9137.269018478639;9441.339106262496;9721;9735.221182305399;9830.476276177204;613.6103574230395;649.68013451264;676.4824908944661;684.384054351078;685.7805502239236;4601.102784956901;4623.884402889955;4627.444706635225;4656.3946686930485;4659.627229821606;96329;99209;101808.49095754474;102364.48817756012;105909.47045264725;86753.5662321697;94823.52588237014;98990.50504747574;99582.5020874891;100193;86551.13448865556;87028.12971870328;87107.56446217855;88583.55708221547;89630.31109066738;8205.753827385188;8430.578471076544;8472.237498624907;8487.5331856746;8560.91439085626;48415.27377089417;49330.506694934025;49582.752086239336;50028.74985625194;50187;66417.66791166013;67446.66276668585;70329.29670703431;72459.63770181115;74034;10764.569417223223;10883.564657413615;11043.779124417628;11109.666709998713;11158.497867595815;12274.324912129561;12492.812607811073;12504.687382815502;12712.74574508523;12846.743065138831;11874;11932.22440541362;11948.641540753792;12028.699282517657;12090.637280881587;11174.553017879194;11198.444937324126;11485.770284594095;11514.19400641947;11730.941345293219;11267.155283102726;11666.241694289844;11784.11454167329;11828.940855295668;11989.346323689453;10335.483225838829;10401.219908507044;10468.057874791306;10634.09610183112;10659.840102398315;9551.474668893277;9557.90442095598;10100.141487973604;10205.336653117523;10393.84409233847 17:24:21 INFO - PROCESS | 2396 | __end_tp_report 17:24:21 INFO - PROCESS | 2396 | __start_cc_report 17:24:21 INFO - PROCESS | 2396 | _x_x_mozilla_cycle_collect,274 17:24:21 INFO - PROCESS | 2396 | __end_cc_report 17:24:21 INFO - PROCESS | 2396 | __startTimestamp1477009461681__endTimestamp 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | ------- Summary: start ------- 17:24:21 INFO - PROCESS | 2396 | Number of tests: 6 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#0] dojo.html Cycles:165 Average:4641.99 Median:4857.30 stddev:589.54 (12.1%) stddev-sans-first:573.28 17:24:21 INFO - PROCESS | 2396 | Values: 2795.9 2803.6 2809.1 2815.6 2819.4 4720.1 4754.0 4766.0 4842.0 4859.6 4740.7 4818.7 4824.2 4838.9 4895.9 4201.6 4237.1 4268.9 4277.1 4290.0 4142.6 4170.0 4265.4 4298.4 4332.6 4189.1 4254.2 4316.7 4322.0 4322.0 4204.1 4206.3 4212.6 4217.2 4223.1 4148.3 4167.5 4224.7 4228.1 4235.0 4594.5 4642.2 4739.7 4796.7 4799.1 4918.9 4957.6 4958.9 5006.1 5020.5 5024.1 5026.1 5115.2 5120.4 5130.7 4670.7 4672.1 4752.4 4758.5 4759.1 4857.3 5046.0 5095.2 5125.5 5130.8 4885.9 4893.6 4982.6 4987.2 4999.2 4724.1 4859.2 4980.3 5077.8 5078.2 4895.3 4942.2 4944.0 4997.4 5024.5 4739.5 4989.4 4996.2 5078.4 5085.4 3328.9 3351.7 3404.7 3410.6 3412.5 2893.1 2940.9 3086.4 3109.0 3111.5 4626.3 4650.0 4698.6 4709.4 4728.9 4943.6 5008.5 5049.9 5057.7 5144.2 4498.9 4588.8 4801.3 4811.2 4813.6 4173.2 4205.8 4224.0 4227.9 4260.1 5236.2 5255.3 5266.9 5270.9 5276.2 5017.5 5053.6 5077.8 5119.4 5123.9 5216.7 5226.3 5320.2 5323.6 5326.9 4717.6 4882.8 4931.0 5026.9 5029.2 5024.6 5026.9 5054.9 5062.9 5067.5 4910.4 4919.4 4994.9 5001.0 5009.1 4890.9 4906.6 4986.8 4988.3 4999.5 4763.7 4806.4 4807.0 4862.8 4922.2 4873.4 4914.5 4948.1 4960.3 5019.9 4760.0 4846.6 4849.6 4874.1 4969.1 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#1] ext.html Cycles:180 Average:52215.17 Median:389462.00 stddev:101053.04 (25.9%) stddev-sans-first:101262.10 17:24:21 INFO - PROCESS | 2396 | Values: 560.7 568.7 577.2 584.2 592.7 38645.6 39646.6 40049.2 40531.4 41719.6 40957.4 41280.0 41350.2 41357.8 41447.0 8158.9 8257.0 8267.9 8280.6 8311.2 7901.1 8088.9 8162.4 8177.4 8179.2 8085.6 8193.9 8212.3 8224.6 8231.6 7811.6 8130.2 8145.1 8152.1 8157.6 7973.4 8139.8 8190.0 8218.5 8221.0 37820.2 38110.6 38141.2 38168.2 38176.4 2967.2 3025.3 3036.2 3058.4 3117.9 29417.6 29493.1 29820.7 30273.4 30468.2 3404.1 3416.1 3432.7 3433.9 3455.1 300153.0 303593.0 305921.0 306296.5 310072.0 52368.5 52433.2 52661.5 52743.9 52781.2 54632.5 54795.0 54863.0 54895.2 55029.2 11101.0 11286.8 11474.5 11486.5 11489.7 8826.7 8888.2 8907.8 8935.7 8938.4 16249.2 16267.6 16291.7 16305.3 16367.1 6389.2 6426.4 6484.4 6535.1 6547.6 743.1 761.4 853.9 884.5 943.2 26731.7 27368.3 27752.3 27828.0 27888.4 888.8 896.1 928.7 970.3 1014.9 14127.7 14431.3 14531.7 14619.1 14668.6 435015.0 438550.0 443116.0 443876.0 445225.0 48160.8 48465.3 49115.8 49134.3 49212.3 1081.8 1116.3 1125.7 1134.5 1137.2 26666.7 26930.7 26987.7 27081.5 27084.2 41730.6 41980.0 42061.8 42123.0 42301.4 381607.0 386423.0 386506.2 388802.0 390122.0 21580.5 22879.5 36351.6 36579.3 36660.6 41811.6 43007.0 43031.0 43227.4 43348.9 34684.3 36753.0 37535.8 38309.6 38803.8 36757.3 36832.4 37108.3 37128.6 37283.6 27765.7 32580.2 33872.3 34307.0 34593.7 29704.6 31485.8 31765.7 31803.7 32134.5 24053.8 24254.6 24376.8 24593.8 24644.5 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#2] jquery.html Cycles:180 Average:25731.78 Median:25916.80 stddev:66899.14 (258.1%) stddev-sans-first:67059.55 17:24:21 INFO - PROCESS | 2396 | Values: 296.4 299.9 301.3 303.1 303.4 14278.9 16312.7 16858.8 17889.9 17901.2 9181.7 9197.1 9382.6 9453.3 9468.0 4514.7 4635.7 4930.3 5280.0 5288.0 5266.6 5268.1 5297.8 5299.1 5303.8 5130.3 5177.4 5270.4 5275.1 5285.6 4968.8 5236.9 5260.3 5263.9 5264.0 5108.1 5110.3 5118.4 5122.9 5124.2 10640.2 16074.6 20544.9 21245.7 21313.5 1720.6 1757.2 1775.7 1778.8 1779.0 8455.9 8500.5 8575.1 8604.7 8628.0 1670.6 1680.7 1709.7 1713.6 1772.6 82143.2 83839.0 84603.0 84731.6 86103.7 8501.4 8580.0 8852.2 8892.2 8915.2 11268.7 11390.4 11442.4 11443.2 11450.2 5963.6 6125.0 6188.5 6195.8 6214.2 5845.9 5892.4 5952.2 5955.9 5964.0 3571.3 3608.6 3628.6 3656.7 3680.9 1518.9 1521.8 1548.8 1550.5 1553.2 788.2 814.9 872.7 902.6 915.7 17557.6 17684.7 17774.3 18030.1 18035.5 817.6 818.8 827.4 828.9 873.0 9688.0 9792.0 9972.9 10007.5 10065.5 373288.0 398478.0 401313.0 406602.0 409944.0 94758.0 99579.0 100096.0 101485.0 101856.5 890.5 891.7 903.3 907.4 923.0 16770.3 16828.3 16867.9 16965.5 17332.0 43236.0 44932.6 45814.0 46426.0 46903.3 60790.7 61066.0 61520.4 62250.4 62275.0 7262.8 7279.2 7296.1 7315.9 7446.2 11384.3 13091.3 13200.6 13234.9 13410.9 10556.5 10770.6 10818.6 11145.9 11237.6 10558.2 10759.6 10785.2 10999.3 11116.2 10609.6 11006.4 11061.8 11222.5 11311.2 7189.2 7259.9 7274.9 7281.1 7383.6 8497.6 11030.7 11132.6 11161.9 11202.5 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#3] mootools.html Cycles:180 Average:4357.68 Median:5791.86 stddev:2408.68 (41.6%) stddev-sans-first:2403.78 17:24:21 INFO - PROCESS | 2396 | Values: 1204.5 1212.8 1267.2 1492.4 1494.9 6024.0 6164.3 6437.2 6554.2 6758.2 6706.0 6845.3 6885.2 6885.6 6903.7 1830.7 1838.7 1855.1 1863.9 1866.9 1839.1 1856.7 1863.4 1872.6 1875.3 1840.3 1849.7 1849.8 1864.3 1873.1 1764.5 1782.0 1794.9 1802.0 1803.1 1759.1 1785.5 1785.9 1787.5 1791.4 6629.3 6699.4 6699.9 6715.8 6723.2 3862.3 3897.9 3924.5 3937.3 3939.4 2547.4 2554.6 2639.1 2652.7 2663.1 839.7 868.5 871.9 871.9 877.2 8557.8 8612.3 8630.1 8633.0 8642.8 3263.1 3438.4 3440.1 3441.0 3441.4 6979.3 6979.8 6981.2 6984.0 7004.2 3996.9 4004.3 4006.0 4010.6 4013.0 3371.4 3462.5 3777.9 3816.9 3828.6 2889.0 2942.7 2984.6 3009.0 3012.1 886.5 887.4 896.7 897.2 900.2 629.8 729.2 730.5 744.3 747.9 4458.6 4464.6 4527.5 4553.9 4558.2 733.5 769.8 792.6 793.0 803.3 3064.4 3105.0 3124.0 3125.2 3178.1 7794.0 8050.7 8054.5 8077.7 8133.1 8036.9 8077.8 8078.0 8095.1 8107.0 6161.3 6309.7 6418.2 6452.8 6497.6 4352.9 4598.4 4744.3 4753.0 4814.9 7248.3 7275.6 7350.5 7614.7 7736.6 7560.8 7724.8 7738.9 7771.1 7787.2 5777.7 5806.0 5923.4 6023.1 6035.6 6300.9 6533.6 6551.1 6598.5 6631.1 5877.5 6027.8 6093.5 6130.4 6145.7 5869.0 5925.6 5996.9 6056.9 6069.6 5372.4 5379.0 5382.1 5390.4 5391.3 3380.4 3583.0 3603.7 3604.8 3610.9 3692.9 3725.4 3726.3 3752.1 3796.1 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#4] prototype.html Cycles:180 Average:4584.57 Median:5350.91 stddev:1471.27 (27.5%) stddev-sans-first:1438.72 17:24:21 INFO - PROCESS | 2396 | Values: 235.1 238.4 242.6 243.1 249.6 5277.6 5396.6 5397.8 5421.2 5451.8 5218.7 5312.9 5317.0 5331.5 5350.5 3827.4 3864.6 3867.7 3874.9 3875.0 3830.1 3872.2 3882.3 3883.3 3885.9 3681.0 3717.8 3811.9 3841.5 3848.6 3815.9 3822.3 3825.6 3829.3 3840.5 3184.5 3265.3 3268.0 3279.4 3285.3 5100.0 5190.2 5198.5 5200.4 5213.2 4455.1 4482.0 4482.4 4484.5 4490.1 5370.5 5468.4 5515.9 5519.9 5536.7 3605.4 3613.3 3626.4 3631.6 3634.8 5779.9 5791.2 5792.0 5820.2 5824.9 3698.9 3701.2 3704.5 3708.3 3709.4 3471.0 3666.4 3673.7 3734.2 3760.3 4273.6 4295.7 4407.3 4407.6 4420.7 5598.9 5625.5 5665.7 5667.9 5670.2 1251.4 1262.1 1262.5 1264.9 1266.0 739.2 761.7 764.3 780.6 796.4 5246.6 5284.1 5298.2 5304.1 5342.6 5740.6 5751.6 5753.7 5754.8 5760.3 5283.3 5294.7 5309.2 5324.8 5326.6 2989.8 3069.4 3079.1 3093.9 3105.9 6002.9 6016.4 6022.1 6042.1 6044.4 5822.2 5858.5 5860.1 5861.3 5876.0 5821.6 5898.1 5973.7 6042.5 6046.6 5708.5 5736.9 5749.2 5767.5 5772.9 5553.3 5640.7 5646.2 5666.7 5671.0 5819.2 5996.6 6016.2 6022.5 6038.3 3558.3 3583.8 3587.1 3590.4 3605.6 5751.6 5753.6 5753.9 5770.6 5773.2 5351.3 5565.9 5569.5 5580.5 5585.9 5439.8 5499.7 5515.2 5516.6 5536.7 5401.2 5416.8 5418.0 5453.9 5483.2 5453.4 5570.5 5579.1 5589.6 5595.9 5431.9 5571.8 5576.2 5589.7 5591.5 17:24:21 INFO - PROCESS | 2396 | 17:24:21 INFO - PROCESS | 2396 | [#5] yui.html Cycles:180 Average:19650.02 Median:24451.75 stddev:28816.33 (117.8%) stddev-sans-first:28861.64 17:24:21 INFO - PROCESS | 2396 | Values: 592.5 671.1 684.7 696.0 698.6 14654.6 15167.7 15300.8 15487.8 15707.9 14029.1 14189.4 14247.6 14266.6 14291.1 650.6 656.3 663.2 665.1 666.1 632.7 654.1 659.3 661.1 661.7 666.9 696.2 699.1 699.7 699.8 646.9 690.9 692.7 693.3 694.0 507.0 507.7 508.2 508.5 508.9 14275.0 14799.0 14916.9 14930.9 14945.0 13301.7 13443.2 13517.5 13531.6 13677.5 11642.4 11719.6 11823.6 11838.8 11993.5 3222.6 3279.9 3291.3 3326.4 3416.2 67007.7 81586.2 83461.2 83623.5 84567.2 9759.8 9820.6 9886.0 10006.1 10057.7 18790.5 18862.6 18897.8 18994.8 19039.1 6787.3 6927.3 6969.9 7045.9 7151.0 3716.9 3726.1 3767.4 3876.4 3878.9 2792.3 2860.2 2870.5 2873.7 2875.8 480.1 488.2 489.4 491.6 493.2 627.1 657.7 658.7 671.6 688.5 9137.3 9441.3 9721.0 9735.2 9830.5 613.6 649.7 676.5 684.4 685.8 4601.1 4623.9 4627.4 4656.4 4659.6 96329.0 99209.0 101808.5 102364.5 105909.5 86753.6 94823.5 98990.5 99582.5 100193.0 86551.1 87028.1 87107.6 88583.6 89630.3 8205.8 8430.6 8472.2 8487.5 8560.9 48415.3 49330.5 49582.8 50028.7 50187.0 66417.7 67446.7 70329.3 72459.6 74034.0 10764.6 10883.6 11043.8 11109.7 11158.5 12274.3 12492.8 12504.7 12712.7 12846.7 11874.0 11932.2 11948.6 12028.7 12090.6 11174.6 11198.4 11485.8 11514.2 11730.9 11267.2 11666.2 11784.1 11828.9 11989.3 10335.5 10401.2 10468.1 10634.1 10659.8 9551.5 9557.9 10100.1 10205.3 10393.8 17:24:21 INFO - PROCESS | 2396 | -------- Summary: end -------- 17:24:21 INFO - PROCESS | 2396 | 17:24:22 INFO - PROCESS | 2396 | [Child 2572] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 17:24:22 INFO - TEST-INFO | 2396: exit 0 17:24:23 INFO - TEST-OK | dromaeo_css | took 1138384ms 17:24:23 INFO - TEST-START | kraken 17:24:23 INFO - operating with platform_type : w7_ 17:24:23 INFO - Initialising browser for kraken test... 17:24:23 INFO - TEST-INFO | started process 824 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpjzjyo4\profile http://localhost:49783/getInfo.html) 17:24:33 INFO - PROCESS | 824 | __metrics Screen width/height:1600/1200 17:24:33 INFO - PROCESS | 824 | colorDepth:24 17:24:33 INFO - PROCESS | 824 | Browser inner width/height: 1010/674 17:24:33 INFO - PROCESS | 824 | __metrics 17:24:35 ERROR - PROCESS | 824 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 17:24:35 INFO - PROCESS | 824 | [Child 2260] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 17:24:35 INFO - PROCESS | 824 | [Child 2260] WARNING: pipe error: 109: file c:/builds/moz2_slave/fx-team-w32-pgo-00000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 17:24:35 INFO - PROCESS | 824 | console.error: 17:24:35 INFO - PROCESS | 824 | Message: Error: Connection closed before committing the transaction. 17:24:35 INFO - PROCESS | 824 | Stack: 17:24:35 INFO - PROCESS | 824 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 17:24:35 INFO - PROCESS | 824 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 17:24:35 INFO - PROCESS | 824 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 17:24:35 INFO - PROCESS | 824 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 17:24:35 INFO - PROCESS | 824 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 17:24:35 INFO - PROCESS | 824 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 17:24:35 INFO - PROCESS | 824 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 17:24:35 INFO - PROCESS | 824 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 17:24:35 INFO - PROCESS | 824 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 17:24:35 INFO - PROCESS | 824 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 17:24:35 INFO - PROCESS | 824 | Barrier/this.client.addBlocker/promise13.5% 17:25:34 INFO - TinderboxPrint: I/O read bytes / time
427,670,528 / 10,457,110 17:25:34 INFO - TinderboxPrint: I/O write bytes / time
579,709,440 / 8,768,910 17:25:34 INFO - TinderboxPrint: CPU idle
8,448.3 (86.5%) 17:25:34 INFO - TinderboxPrint: CPU user
1,235.3 (12.6%) 17:25:34 INFO - install - Wall time: 9s; CPU: 3.0%; Read bytes: 0; Write bytes: 81932800; Read time: 0; Write time: 827010 17:25:34 INFO - run-tests - Wall time: 1213s; CPU: 14.0%; Read bytes: 427670528; Write bytes: 485136384; Read time: 10457110; Write time: 7782100 17:25:35 INFO - Running post-run listener: _upload_blobber_files 17:25:35 INFO - Blob upload gear active. 17:25:35 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 17:25:35 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:25:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 17:25:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 17:25:36 INFO - (blobuploader) - INFO - Open directory for files ... 17:25:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 17:25:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:25:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:37 INFO - (blobuploader) - INFO - Done attempting. 17:25:37 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 17:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:38 INFO - (blobuploader) - INFO - Done attempting. 17:25:38 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 17:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:25:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:25:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:25:46 INFO - (blobuploader) - INFO - Done attempting. 17:25:46 INFO - (blobuploader) - INFO - Iteration through files over. 17:25:46 INFO - Return code: 0 17:25:46 INFO - rmtree: C:\slave\test\build\uploaded_files.json 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 17:25:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23d21efb64013436e8a0c82044ee1509b4336f8c85b6580b465ab34894c8b8ef4b3c9df67c32d41d8875fa34e3a96bf2c7165d48fd22b6cdbccef358b38b7cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/089f59ab2b83041aced7e2515c39bd4762c1f773ad6c6b32bd02c186249955148370cbcddbb9866ca2d049e36496a0a33e8bc59acb8b0b520cddccc2561adaac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d26cc007f08df882c068a2b6735a16e2ecc75de899afe11806ccf0ffd308a2c0fccba5c88e2dcd88cadf5fc5209f80a9be1758b4e888ddf9b7744905101128e6"} 17:25:46 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 17:25:46 INFO - Writing to file C:\slave\test\properties\blobber_files 17:25:46 INFO - Contents: 17:25:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23d21efb64013436e8a0c82044ee1509b4336f8c85b6580b465ab34894c8b8ef4b3c9df67c32d41d8875fa34e3a96bf2c7165d48fd22b6cdbccef358b38b7cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/089f59ab2b83041aced7e2515c39bd4762c1f773ad6c6b32bd02c186249955148370cbcddbb9866ca2d049e36496a0a33e8bc59acb8b0b520cddccc2561adaac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d26cc007f08df882c068a2b6735a16e2ecc75de899afe11806ccf0ffd308a2c0fccba5c88e2dcd88cadf5fc5209f80a9be1758b4e888ddf9b7744905101128e6"} 17:25:46 INFO - Running post-run listener: copy_logs_to_upload_dir 17:25:46 INFO - Copying logs to upload dir... 17:25:46 INFO - mkdir: C:\slave\test\build\upload\logs 17:25:46 INFO - Copying logs to upload dir... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... 17:25:46 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1612.176000 ========= master_lag: 0.66 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 52 secs) (at 2016-10-20 17:25:46.241445) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 17:25:46.242363) ========= '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-015 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-015 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-015 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23d21efb64013436e8a0c82044ee1509b4336f8c85b6580b465ab34894c8b8ef4b3c9df67c32d41d8875fa34e3a96bf2c7165d48fd22b6cdbccef358b38b7cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/089f59ab2b83041aced7e2515c39bd4762c1f773ad6c6b32bd02c186249955148370cbcddbb9866ca2d049e36496a0a33e8bc59acb8b0b520cddccc2561adaac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d26cc007f08df882c068a2b6735a16e2ecc75de899afe11806ccf0ffd308a2c0fccba5c88e2dcd88cadf5fc5209f80a9be1758b4e888ddf9b7744905101128e6"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476992154/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23d21efb64013436e8a0c82044ee1509b4336f8c85b6580b465ab34894c8b8ef4b3c9df67c32d41d8875fa34e3a96bf2c7165d48fd22b6cdbccef358b38b7cf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/089f59ab2b83041aced7e2515c39bd4762c1f773ad6c6b32bd02c186249955148370cbcddbb9866ca2d049e36496a0a33e8bc59acb8b0b520cddccc2561adaac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d26cc007f08df882c068a2b6735a16e2ecc75de899afe11806ccf0ffd308a2c0fccba5c88e2dcd88cadf5fc5209f80a9be1758b4e888ddf9b7744905101128e6"}' ========= master_lag: 0.32 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 17:25:46.664460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 17:25:46.664840) ========= '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-015 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-015 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-015 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-20 17:25:46.778508) ========= ========= Started reboot slave lost (results: 0, elapsed: 1 secs) (at 2016-10-20 17:25:46.779242) ========= ========= Finished reboot slave lost (results: 0, elapsed: 1 secs) (at 2016-10-20 17:25:47.912943) ========= ========= Total master_lag: 2.67 =========