builder: mozilla-central_win7_ix_test-dromaeojs slave: t-w732-ix-014 starttime: 1480479377.12 results: success (0) buildid: 20161129191659 builduid: 55d9e579101848a2a05715ee69c2c2a4 revision: 5d49c9792a3417455bc5683ee2264b3a36d68682 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:17.123543) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:17.123962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:17.124250) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:17.962954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:16:17.963233) ========= '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-014 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-014 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-014 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 --20:16:19-- 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% 18.25 MB/s 20:16:19 (18.25 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.205000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:16:19.187740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-11-29 20:16:19.188054) ========= '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-014 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-014 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-014 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=16.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-11-29 20:16:35.310411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:16:35.310798) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 5d49c9792a3417455bc5683ee2264b3a36d68682 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5d49c9792a3417455bc5683ee2264b3a36d68682 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-014 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-014 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-014 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-29 20:16:36,213 truncating revision to first 12 chars 2016-11-29 20:16:36,213 Setting DEBUG logging. 2016-11-29 20:16:36,213 attempt 1/10 2016-11-29 20:16:36,213 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5d49c9792a34?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 20:16:36,984 unpacking tar archive at: mozilla-central-5d49c9792a34/testing/mozharness/ 2016-11-29 20:16:37,111 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.705000 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:16:37.106887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:37.107218) ========= script_repo_revision: 5d49c9792a3417455bc5683ee2264b3a36d68682 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:37.107635) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:37.107902) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:16:37.116711) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 23 secs) (at 2016-11-29 20:16:37.117009) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-014 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-014 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-014 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 20:16:38 INFO - MultiFileLogger online at 20161129 20:16:38 in C:\slave\test 20:16:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:16:38 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 20:16:38 INFO - {'append_to_log': False, 20:16:38 INFO - 'base_work_dir': 'C:\\slave\\test', 20:16:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:16:38 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 20:16:38 INFO - 'branch': 'Firefox-Non-PGO', 20:16:38 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:38 INFO - 'config_files': ('talos/windows_config.py',), 20:16:38 INFO - 'default_actions': ('clobber', 20:16:38 INFO - 'read-buildbot-config', 20:16:38 INFO - 'download-and-extract', 20:16:38 INFO - 'populate-webroot', 20:16:38 INFO - 'create-virtualenv', 20:16:38 INFO - 'install', 20:16:38 INFO - 'run-tests'), 20:16:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:38 INFO - 'download_minidump_stackwalk': True, 20:16:38 INFO - 'download_symbols': 'ondemand', 20:16:38 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 20:16:38 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 20:16:38 INFO - 'hg': 'c:/mozilla-build/hg/hg', 20:16:38 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 20:16:38 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 20:16:38 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 20:16:38 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 20:16:38 INFO - 'C:/mozilla-build/tooltool.py'), 20:16:38 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 20:16:38 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 20:16:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:38 INFO - 'installer_path': 'installer.exe', 20:16:38 INFO - 'log_level': 'info', 20:16:38 INFO - 'log_name': 'talos', 20:16:38 INFO - 'log_to_console': True, 20:16:38 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 20:16:38 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 20:16:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 20:16:38 INFO - 'opt_config_files': (), 20:16:38 INFO - 'pip_index': False, 20:16:38 INFO - 'sps_profile': False, 20:16:38 INFO - 'sps_profile_interval': 0, 20:16:38 INFO - 'suite': 'dromaeojs', 20:16:38 INFO - 'system_bits': '32', 20:16:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:38 INFO - 'title': 'T-W732-IX-014', 20:16:38 INFO - 'use_talos_json': True, 20:16:38 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 20:16:38 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 20:16:38 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 20:16:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:38 INFO - 'work_dir': 'build'} 20:16:38 INFO - [mozharness: 2016-11-30 04:16:38.176000Z] Running clobber step. 20:16:38 INFO - Running pre-action listener: _resource_record_pre_action 20:16:38 INFO - Running main action method: clobber 20:16:38 INFO - rmtree: C:\slave\test\build 20:16:38 INFO - Using _rmtree_windows ... 20:16:38 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 20:18:05 INFO - Running post-action listener: _resource_record_post_action 20:18:05 INFO - [mozharness: 2016-11-30 04:18:05.312000Z] Finished clobber step (success) 20:18:05 INFO - [mozharness: 2016-11-30 04:18:05.312000Z] Running read-buildbot-config step. 20:18:05 INFO - Running pre-action listener: _resource_record_pre_action 20:18:05 INFO - Running main action method: read_buildbot_config 20:18:05 INFO - Using buildbot properties: 20:18:05 INFO - { 20:18:05 INFO - "project": "", 20:18:05 INFO - "product": "firefox", 20:18:05 INFO - "script_repo_revision": "production", 20:18:05 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 20:18:05 INFO - "repository": "", 20:18:05 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 20:18:05 INFO - "buildid": "20161129191659", 20:18:05 INFO - "pgo_build": "False", 20:18:05 INFO - "basedir": "C:\\slave\\test", 20:18:05 INFO - "buildnumber": 29, 20:18:05 INFO - "slavename": "t-w732-ix-014", 20:18:05 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 20:18:05 INFO - "platform": "win7_ix", 20:18:05 INFO - "scheduler": "tests-mozilla-central-win32-talos", 20:18:05 INFO - "branch": "mozilla-central", 20:18:05 INFO - "repo_path": "mozilla-central", 20:18:05 INFO - "revision": "5d49c9792a3417455bc5683ee2264b3a36d68682", 20:18:05 INFO - "stage_platform": "win32", 20:18:05 INFO - "builduid": "55d9e579101848a2a05715ee69c2c2a4", 20:18:05 INFO - "slavebuilddir": "test" 20:18:05 INFO - } 20:18:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 20:18:05 INFO - Running post-action listener: _resource_record_post_action 20:18:05 INFO - [mozharness: 2016-11-30 04:18:05.316000Z] Finished read-buildbot-config step (success) 20:18:05 INFO - [mozharness: 2016-11-30 04:18:05.316000Z] Running download-and-extract step. 20:18:05 INFO - Running pre-action listener: _resource_record_pre_action 20:18:05 INFO - Running main action method: download_and_extract 20:18:05 INFO - mkdir: C:\slave\test\build\tests 20:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:05 INFO - trying https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 20:18:05 INFO - Downloading https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 20:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 20:18:06 INFO - Downloaded 1274 bytes. 20:18:06 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 20:18:06 INFO - Using the following test package requirements: 20:18:06 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 20:18:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 20:18:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'], 20:18:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'jsshell-win32.zip'], 20:18:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 20:18:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 20:18:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 20:18:06 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 20:18:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 20:18:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:18:06 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 20:18:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 20:18:06 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 20:18:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 20:18:06 INFO - Fetch https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 20:18:06 INFO - Http code: 200 20:18:06 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 20:18:06 INFO - x-amz-cf-id: A9IaJa6WsLIAISpMGq2mUF15gGY2jaLOOLHGPn2NjUubTq2lk_5FjA== 20:18:06 INFO - x-amz-version-id: z0bOWd1PYvAOEWjlc4r7gHfXf7yWqHRo 20:18:07 INFO - Expected file size: 16901376 20:18:07 INFO - Obtained file size: 16901376 20:18:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 20:18:33 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 20:18:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 20:18:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 20:18:33 INFO - Http code: 200 20:18:33 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 20:18:33 INFO - x-amz-cf-id: gQhP1Nt_FpCKzmlTFwmje9fnrqUNp-iDDt3LCHlmXgtDfCZ6M7ajyQ== 20:18:33 INFO - x-amz-version-id: z0bOWd1PYvAOEWjlc4r7gHfXf7yWqHRo 20:18:34 INFO - Expected file size: 16901376 20:18:34 INFO - Obtained file size: 16901376 20:18:45 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 20:18:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 20:18:45 INFO - Fetch https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 20:18:45 INFO - Http code: 200 20:18:45 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 20:18:45 INFO - x-amz-cf-id: pK89E-igC8V8nSc9mmXsJRXuZcZmk01zNfMg3JXCgfTKi8mJvJPsnw== 20:18:45 INFO - x-amz-version-id: glZwCvj_5pJRrR7QIuRrIIjaKjUvpPxs 20:18:46 INFO - Expected file size: 14409702 20:18:46 INFO - Obtained file size: 14409702 20:18:49 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')]} 20:18:49 INFO - trying https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:18:49 INFO - Downloading https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 20:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 20:18:52 INFO - Downloaded 53361301 bytes. 20:18:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:18:52 INFO - mkdir: C:\slave\test\properties 20:18:52 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 20:18:52 INFO - Writing to file C:\slave\test\properties\build_url 20:18:52 INFO - Contents: 20:18:52 INFO - build_url:https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:18:53 INFO - Running post-action listener: _resource_record_post_action 20:18:53 INFO - Running post-action listener: set_extra_try_arguments 20:18:53 INFO - [mozharness: 2016-11-30 04:18:53.353000Z] Finished download-and-extract step (success) 20:18:53 INFO - [mozharness: 2016-11-30 04:18:53.353000Z] Running populate-webroot step. 20:18:53 INFO - Running pre-action listener: _resource_record_pre_action 20:18:53 INFO - Running main action method: populate_webroot 20:18:53 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 20:18:53 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 20:18:53 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 20:18:53 INFO - u'--tppagecycles', 20:18:53 INFO - u'7'], 20:18:53 INFO - u'tests': [u'tsvgm']}}, 20:18:53 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 20:18:53 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 20:18:53 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 20:18:53 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 20:18:53 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:18:53 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:18:53 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'damp', u'tps']}, 20:18:53 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'tests': [u'damp', u'tps']}, 20:18:53 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'dromaeo_dom']}, 20:18:53 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 20:18:53 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'basic_compositor_video']}, 20:18:53 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 20:18:53 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'other-e10s': {u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'a11yr', 20:18:53 INFO - u'ts_paint', 20:18:53 INFO - u'tpaint', 20:18:53 INFO - u'sessionrestore', 20:18:53 INFO - u'sessionrestore_no_auto_restore', 20:18:53 INFO - u'tabpaint']}, 20:18:53 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'tsvgx', 20:18:53 INFO - u'tsvgr_opacity', 20:18:53 INFO - u'tart', 20:18:53 INFO - u'tscrollx', 20:18:53 INFO - u'cart', 20:18:53 INFO - u'tsvg_static']}, 20:18:53 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 20:18:53 INFO - u'tsvgr_opacity', 20:18:53 INFO - u'tart', 20:18:53 INFO - u'tscrollx', 20:18:53 INFO - u'cart', 20:18:53 INFO - u'tsvg_static']}, 20:18:53 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'talos_options': [u'--disable-e10s'], 20:18:53 INFO - u'tests': [u'tp5o']}, 20:18:53 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'tests': [u'tp5o']}, 20:18:53 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'talos_options': [u'--disable-e10s', 20:18:53 INFO - u'--xperf_path', 20:18:53 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:53 INFO - u'tests': [u'tp5n']}, 20:18:53 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 20:18:53 INFO - u'talos_options': [u'--xperf_path', 20:18:53 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:18:53 INFO - u'tests': [u'tp5n']}}, 20:18:53 INFO - u'talos.zip': {u'path': u'', 20:18:53 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:18:53 INFO - Running post-action listener: _resource_record_post_action 20:18:53 INFO - [mozharness: 2016-11-30 04:18:53.414000Z] Finished populate-webroot step (success) 20:18:53 INFO - [mozharness: 2016-11-30 04:18:53.414000Z] Running create-virtualenv step. 20:18:53 INFO - Running pre-action listener: _resource_record_pre_action 20:18:53 INFO - Running main action method: create_virtualenv 20:18:53 INFO - Creating virtualenv C:\slave\test\build/venv 20:18:53 INFO - mkdir: C:\slave\test\build\venv\Scripts 20:18:53 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 20:18:53 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'! 20:18:53 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 20:18:53 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 20:19:03 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 20:19:07 INFO - Installing distribute......................................................................................................................................................................................done. 20:19:09 INFO - Return code: 0 20:19:09 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 20:19:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 20:19:09 INFO - Reading from file tmpfile_stdout 20:19:09 INFO - Output received: 20:19:09 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 20:19:09 INFO - Using _rmtree_windows ... 20:19:09 INFO - Using _rmtree_windows ... 20:19:09 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 20:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 20:19:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:19:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:09 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:09 INFO - 'DCLOCATION': 'SCL3', 20:19:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:09 INFO - 'HOMEDRIVE': 'C:', 20:19:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:09 INFO - 'KTS_VERSION': '1.19c', 20:19:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:09 INFO - 'MOZ_NO_REMOTE': '1', 20:19:09 INFO - 'NO_EM_RESTART': '1', 20:19:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:09 INFO - 'OS': 'Windows_NT', 20:19:09 INFO - 'OURDRIVE': 'C:', 20:19:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:09 INFO - 'PROCESSOR_LEVEL': '6', 20:19:09 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:09 INFO - 'PROMPT': '$P$G', 20:19:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:09 INFO - 'PWD': 'C:\\slave\\test', 20:19:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:09 INFO - 'SYSTEMDRIVE': 'C:', 20:19:09 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:09 INFO - 'TEST1': 'testie', 20:19:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:09 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:09 INFO - 'USERNAME': 'cltbld', 20:19:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:09 INFO - 'WINDIR': 'C:\\windows', 20:19:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:10 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:19:10 INFO - Downloading/unpacking pip>=1.5 20:19:14 INFO - Running setup.py egg_info for package pip 20:19:14 INFO - warning: no files found matching 'pip\cacert.pem' 20:19:14 INFO - warning: no files found matching '*.html' under directory 'docs' 20:19:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:19:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:19:14 INFO - Installing collected packages: pip 20:19:14 INFO - Found existing installation: pip 0.8.2 20:19:14 INFO - Uninstalling pip: 20:19:14 INFO - Successfully uninstalled pip 20:19:14 INFO - Running setup.py install for pip 20:19:14 INFO - warning: no files found matching 'pip\cacert.pem' 20:19:14 INFO - warning: no files found matching '*.html' under directory 'docs' 20:19:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:19:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:19:14 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 20:19:14 INFO - Successfully installed pip 20:19:14 INFO - Cleaning up... 20:19:14 INFO - Return code: 0 20:19:14 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 20:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:14 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:14 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 20:19:14 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 20:19:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:14 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:14 INFO - 'DCLOCATION': 'SCL3', 20:19:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:14 INFO - 'HOMEDRIVE': 'C:', 20:19:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:14 INFO - 'KTS_VERSION': '1.19c', 20:19:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:14 INFO - 'MOZ_NO_REMOTE': '1', 20:19:14 INFO - 'NO_EM_RESTART': '1', 20:19:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:14 INFO - 'OS': 'Windows_NT', 20:19:14 INFO - 'OURDRIVE': 'C:', 20:19:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:14 INFO - 'PROCESSOR_LEVEL': '6', 20:19:14 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:14 INFO - 'PROMPT': '$P$G', 20:19:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:14 INFO - 'PWD': 'C:\\slave\\test', 20:19:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:14 INFO - 'SYSTEMDRIVE': 'C:', 20:19:14 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:14 INFO - 'TEST1': 'testie', 20:19:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:14 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:14 INFO - 'USERNAME': 'cltbld', 20:19:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:14 INFO - 'WINDIR': 'C:\\windows', 20:19:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:16 INFO - Downloading/unpacking psutil>=3.1.1 20:19:16 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 20:19:16 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 20:19:17 INFO - Installing collected packages: psutil 20:19:17 INFO - Successfully installed psutil 20:19:17 INFO - Cleaning up... 20:19:18 INFO - Return code: 0 20:19:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 20:19: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')]} 20:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19: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')]} 20:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build 20:19: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 mozsystemmonitor==0.3 20:19:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:18 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:18 INFO - 'DCLOCATION': 'SCL3', 20:19:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:18 INFO - 'HOMEDRIVE': 'C:', 20:19:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:18 INFO - 'KTS_VERSION': '1.19c', 20:19:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:18 INFO - 'MOZ_NO_REMOTE': '1', 20:19:18 INFO - 'NO_EM_RESTART': '1', 20:19:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:18 INFO - 'OS': 'Windows_NT', 20:19:18 INFO - 'OURDRIVE': 'C:', 20:19: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;', 20:19:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:18 INFO - 'PROCESSOR_LEVEL': '6', 20:19:18 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:18 INFO - 'PROMPT': '$P$G', 20:19:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:18 INFO - 'PWD': 'C:\\slave\\test', 20:19:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:18 INFO - 'SYSTEMDRIVE': 'C:', 20:19:18 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:18 INFO - 'TEST1': 'testie', 20:19:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:18 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:18 INFO - 'USERNAME': 'cltbld', 20:19:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:18 INFO - 'WINDIR': 'C:\\windows', 20:19:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:19:18 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 20:19:18 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 20:19:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:19:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 20:19:19 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) 20:19:19 INFO - Installing collected packages: mozsystemmonitor 20:19:19 INFO - Running setup.py install for mozsystemmonitor 20:19:19 INFO - Successfully installed mozsystemmonitor 20:19:19 INFO - Cleaning up... 20:19:20 INFO - Return code: 0 20:19:20 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 20:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 20:19:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:19:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:20 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:20 INFO - 'DCLOCATION': 'SCL3', 20:19:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:20 INFO - 'HOMEDRIVE': 'C:', 20:19:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:20 INFO - 'KTS_VERSION': '1.19c', 20:19:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:20 INFO - 'MOZ_NO_REMOTE': '1', 20:19:20 INFO - 'NO_EM_RESTART': '1', 20:19:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:20 INFO - 'OS': 'Windows_NT', 20:19:20 INFO - 'OURDRIVE': 'C:', 20:19:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:20 INFO - 'PROCESSOR_LEVEL': '6', 20:19:20 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:20 INFO - 'PROMPT': '$P$G', 20:19:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:20 INFO - 'PWD': 'C:\\slave\\test', 20:19:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:20 INFO - 'SYSTEMDRIVE': 'C:', 20:19:20 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:20 INFO - 'TEST1': 'testie', 20:19:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:20 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:20 INFO - 'USERNAME': 'cltbld', 20:19:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:20 INFO - 'WINDIR': 'C:\\windows', 20:19:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:20 INFO - Downloading/unpacking jsonschema==2.5.1 20:19:20 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 20:19:20 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 20:19:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:19:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:19:21 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 20:19:21 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 20:19:21 INFO - Downloading functools32-3.2.3-2.tar.gz 20:19:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 20:19:22 INFO - warning: no files found matching '*.txt' 20:19:22 INFO - no previously-included directories found matching 'build' 20:19:22 INFO - no previously-included directories found matching 'dist' 20:19:22 INFO - no previously-included directories found matching '.git*' 20:19:22 INFO - Installing collected packages: jsonschema, functools32 20:19:22 INFO - Running setup.py install for functools32 20:19:22 INFO - warning: no files found matching '*.txt' 20:19:22 INFO - no previously-included directories found matching 'build' 20:19:22 INFO - no previously-included directories found matching 'dist' 20:19:22 INFO - no previously-included directories found matching '.git*' 20:19:22 INFO - Successfully installed jsonschema functools32 20:19:22 INFO - Cleaning up... 20:19:23 INFO - Return code: 0 20:19:23 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 20:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:23 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:23 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 20:19:23 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 20:19:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:23 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:23 INFO - 'DCLOCATION': 'SCL3', 20:19:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:23 INFO - 'HOMEDRIVE': 'C:', 20:19:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:23 INFO - 'KTS_VERSION': '1.19c', 20:19:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:23 INFO - 'MOZ_NO_REMOTE': '1', 20:19:23 INFO - 'NO_EM_RESTART': '1', 20:19:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:23 INFO - 'OS': 'Windows_NT', 20:19:23 INFO - 'OURDRIVE': 'C:', 20:19: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;', 20:19:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:23 INFO - 'PROCESSOR_LEVEL': '6', 20:19:23 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:23 INFO - 'PROMPT': '$P$G', 20:19:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:23 INFO - 'PWD': 'C:\\slave\\test', 20:19:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:23 INFO - 'SYSTEMDRIVE': 'C:', 20:19:23 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:23 INFO - 'TEST1': 'testie', 20:19:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:23 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:23 INFO - 'USERNAME': 'cltbld', 20:19:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:23 INFO - 'WINDIR': 'C:\\windows', 20:19:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 20:19:23 INFO - Cleaning up... 20:19:23 INFO - Return code: 0 20:19:23 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 20:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:23 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:23 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 20:19:23 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 20:19:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:23 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:23 INFO - 'DCLOCATION': 'SCL3', 20:19:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:23 INFO - 'HOMEDRIVE': 'C:', 20:19:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:23 INFO - 'KTS_VERSION': '1.19c', 20:19:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:23 INFO - 'MOZ_NO_REMOTE': '1', 20:19:23 INFO - 'NO_EM_RESTART': '1', 20:19:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:23 INFO - 'OS': 'Windows_NT', 20:19:23 INFO - 'OURDRIVE': 'C:', 20:19: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;', 20:19:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:23 INFO - 'PROCESSOR_LEVEL': '6', 20:19:23 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:23 INFO - 'PROMPT': '$P$G', 20:19:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:23 INFO - 'PWD': 'C:\\slave\\test', 20:19:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:23 INFO - 'SYSTEMDRIVE': 'C:', 20:19:23 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:23 INFO - 'TEST1': 'testie', 20:19:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:23 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:23 INFO - 'USERNAME': 'cltbld', 20:19:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:23 INFO - 'WINDIR': 'C:\\windows', 20:19:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:23 INFO - Downloading/unpacking blobuploader==1.2.4 20:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:24 INFO - Downloading blobuploader-1.2.4.tar.gz 20:19:24 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 20:19:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:19:25 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 20:19:25 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 20:19:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 20:19:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:19:25 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 20:19:25 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 20:19:25 INFO - Downloading docopt-0.6.1.tar.gz 20:19:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 20:19:25 INFO - Installing collected packages: blobuploader, requests, docopt 20:19:25 INFO - Running setup.py install for blobuploader 20:19:26 INFO - Running setup.py install for requests 20:19:26 INFO - Running setup.py install for docopt 20:19:26 INFO - Successfully installed blobuploader requests docopt 20:19:26 INFO - Cleaning up... 20:19:28 INFO - Return code: 0 20:19:28 INFO - Installing None into virtualenv C:\slave\test\build/venv 20:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:28 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:28 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 20:19:28 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 20:19:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:28 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:28 INFO - 'DCLOCATION': 'SCL3', 20:19:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:28 INFO - 'HOMEDRIVE': 'C:', 20:19:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:28 INFO - 'KTS_VERSION': '1.19c', 20:19:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:28 INFO - 'MOZ_NO_REMOTE': '1', 20:19:28 INFO - 'NO_EM_RESTART': '1', 20:19:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:28 INFO - 'OS': 'Windows_NT', 20:19:28 INFO - 'OURDRIVE': 'C:', 20:19:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:28 INFO - 'PROCESSOR_LEVEL': '6', 20:19:28 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:28 INFO - 'PROMPT': '$P$G', 20:19:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:28 INFO - 'PWD': 'C:\\slave\\test', 20:19:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:28 INFO - 'SYSTEMDRIVE': 'C:', 20:19:28 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'TEST1': 'testie', 20:19:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:28 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:28 INFO - 'USERNAME': 'cltbld', 20:19:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:28 INFO - 'WINDIR': 'C:\\windows', 20:19:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 20:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s1t_md-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 20:19:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 20:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rpqplh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 20:19:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 20:19:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n2uayo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m0cnpo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-woukgv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ewg2rq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pc5ysl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wgbhc6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rneusg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aci_rg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 20:19:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 20:19:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-stifnf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lfs47h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-baso0r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yhuqpa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2o6umm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b5f1nm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 20:19:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 20:19:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ml43uy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 20:19:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:19:30 INFO - Running setup.py install for manifestparser 20:19:31 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 20:19:31 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 20:19:31 INFO - Running setup.py install for mozcrash 20:19:31 INFO - Running setup.py install for mozdebug 20:19:32 INFO - Running setup.py install for mozdevice 20:19:32 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 20:19:32 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 20:19:32 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 20:19:32 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 20:19:32 INFO - Running setup.py install for mozfile 20:19:33 INFO - Running setup.py install for mozhttpd 20:19:33 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 20:19:33 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 20:19:33 INFO - Running setup.py install for mozinfo 20:19:33 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 20:19:33 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Running setup.py install for mozInstall 20:19:34 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Running setup.py install for mozleak 20:19:34 INFO - Running setup.py install for mozlog 20:19:34 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 20:19:34 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 20:19:35 INFO - Running setup.py install for moznetwork 20:19:35 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 20:19:35 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 20:19:35 INFO - Running setup.py install for mozprocess 20:19:36 INFO - Running setup.py install for mozprofile 20:19:36 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 20:19:36 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 20:19:36 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 20:19:36 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 20:19:36 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 20:19:36 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 20:19:37 INFO - Running setup.py install for mozrunner 20:19:37 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 20:19:37 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 20:19:37 INFO - Running setup.py install for mozscreenshot 20:19:38 INFO - Running setup.py install for moztest 20:19:38 INFO - Running setup.py install for mozversion 20:19:38 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 20:19:38 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 20:19:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:19:38 INFO - Cleaning up... 20:19:38 INFO - Return code: 0 20:19:38 INFO - Installing None into virtualenv C:\slave\test\build/venv 20:19:38 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')]} 20:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:38 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')]} 20:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:38 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:39 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 20:19:39 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 20:19:39 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:39 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:39 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:39 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:39 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:39 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:39 INFO - 'DCLOCATION': 'SCL3', 20:19:39 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:39 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:39 INFO - 'HOMEDRIVE': 'C:', 20:19:39 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:39 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:39 INFO - 'KTS_VERSION': '1.19c', 20:19:39 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:39 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:39 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:39 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:39 INFO - 'MOZ_NO_REMOTE': '1', 20:19:39 INFO - 'NO_EM_RESTART': '1', 20:19:39 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:39 INFO - 'OS': 'Windows_NT', 20:19:39 INFO - 'OURDRIVE': 'C:', 20:19:39 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:39 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:39 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:39 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:39 INFO - 'PROCESSOR_LEVEL': '6', 20:19:39 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:39 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:39 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:39 INFO - 'PROMPT': '$P$G', 20:19:39 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:39 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:39 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:39 INFO - 'PWD': 'C:\\slave\\test', 20:19:39 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:39 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:39 INFO - 'SYSTEMDRIVE': 'C:', 20:19:39 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:39 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:39 INFO - 'TEST1': 'testie', 20:19:39 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:39 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:39 INFO - 'USERNAME': 'cltbld', 20:19:39 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:39 INFO - 'WINDIR': 'C:\\windows', 20:19:39 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:39 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 20:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ec47dz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 20:19:39 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)) 20:19:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 20:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uccnrm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 20:19:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 20:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fneimn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 20:19:39 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)) 20:19:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 20:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vmgqbf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 20:19:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 20:19:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f3pc0e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-auldrn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ragq_j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1g2vxo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pokac0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uydbww-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wfm7ju-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 20:19:40 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)) 20:19:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 20:19:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hths1p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 20:19:41 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)) 20:19:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 20:19:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hqqbwm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 20:19:41 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)) 20:19:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 20:19:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frmn1m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 20:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 20:19:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 20:19:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uuolmp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 20:19:41 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)) 20:19:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 20:19:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xycacy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 20:19:41 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)) 20:19:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 20:19:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqzlhw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 20:19:41 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)) 20:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 20:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 20:19:41 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)) 20:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 20:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 20:19:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 20:19:41 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 20:19:41 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 20:19:42 INFO - Downloading blessings-1.6.tar.gz 20:19:42 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 20:19:43 INFO - Installing collected packages: blessings 20:19:43 INFO - Running setup.py install for blessings 20:19:44 INFO - Successfully installed blessings 20:19:44 INFO - Cleaning up... 20:19:46 INFO - Return code: 0 20:19:46 INFO - Done creating virtualenv C:\slave\test\build/venv. 20:19:46 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 20:19:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 20:19:47 INFO - Reading from file tmpfile_stdout 20:19:47 INFO - Using _rmtree_windows ... 20:19:47 INFO - Using _rmtree_windows ... 20:19:47 INFO - Current package versions: 20:19:47 INFO - blessings == 1.6 20:19:47 INFO - blobuploader == 1.2.4 20:19:47 INFO - docopt == 0.6.1 20:19:47 INFO - functools32 == 3.2.3-2 20:19:47 INFO - jsonschema == 2.5.1 20:19:47 INFO - manifestparser == 1.1 20:19:47 INFO - mozInstall == 1.12 20:19:47 INFO - mozcrash == 1.0 20:19:47 INFO - mozdebug == 0.1 20:19:47 INFO - mozdevice == 0.49 20:19:47 INFO - mozfile == 1.2 20:19:47 INFO - mozhttpd == 0.7 20:19:47 INFO - mozinfo == 0.9 20:19:47 INFO - mozleak == 0.1 20:19:47 INFO - mozlog == 3.4 20:19:47 INFO - moznetwork == 0.27 20:19:47 INFO - mozprocess == 0.23 20:19:47 INFO - mozprofile == 0.28 20:19:47 INFO - mozrunner == 6.13 20:19:47 INFO - mozscreenshot == 0.1 20:19:47 INFO - mozsystemmonitor == 0.3 20:19:47 INFO - moztest == 0.7 20:19:47 INFO - mozversion == 1.4 20:19:47 INFO - psutil == 3.1.1 20:19:47 INFO - requests == 1.2.3 20:19:47 INFO - Installing None into virtualenv C:\slave\test\build/venv 20:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:47 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 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:47 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 20:19:47 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 20:19:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:47 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:47 INFO - 'DCLOCATION': 'SCL3', 20:19:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:47 INFO - 'HOMEDRIVE': 'C:', 20:19:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:47 INFO - 'KTS_VERSION': '1.19c', 20:19:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:47 INFO - 'MOZ_NO_REMOTE': '1', 20:19:47 INFO - 'NO_EM_RESTART': '1', 20:19:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:47 INFO - 'OS': 'Windows_NT', 20:19:47 INFO - 'OURDRIVE': 'C:', 20:19:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:47 INFO - 'PROCESSOR_LEVEL': '6', 20:19:47 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:47 INFO - 'PROMPT': '$P$G', 20:19:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:47 INFO - 'PWD': 'C:\\slave\\test', 20:19:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:47 INFO - 'SYSTEMDRIVE': 'C:', 20:19:47 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:47 INFO - 'TEST1': 'testie', 20:19:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:47 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:47 INFO - 'USERNAME': 'cltbld', 20:19:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:47 INFO - 'WINDIR': 'C:\\windows', 20:19:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 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)) 20:19:47 INFO - Cleaning up... 20:19:47 INFO - Return code: 0 20:19:47 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 20:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01FDF020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-014', 'USERDOMAIN': 'T-W732-IX-014', '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-014', '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 20:19:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 20:19:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:19:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:47 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:47 INFO - 'DCLOCATION': 'SCL3', 20:19:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:47 INFO - 'HOMEDRIVE': 'C:', 20:19:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:47 INFO - 'KTS_VERSION': '1.19c', 20:19:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:47 INFO - 'MOZ_NO_REMOTE': '1', 20:19:47 INFO - 'NO_EM_RESTART': '1', 20:19:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:47 INFO - 'OS': 'Windows_NT', 20:19:47 INFO - 'OURDRIVE': 'C:', 20:19:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:19:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:47 INFO - 'PROCESSOR_LEVEL': '6', 20:19:47 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:47 INFO - 'PROMPT': '$P$G', 20:19:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:47 INFO - 'PWD': 'C:\\slave\\test', 20:19:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:47 INFO - 'SYSTEMDRIVE': 'C:', 20:19:47 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:47 INFO - 'TEST1': 'testie', 20:19:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:47 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:47 INFO - 'USERNAME': 'cltbld', 20:19:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:47 INFO - 'WINDIR': 'C:\\windows', 20:19:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 20:19:48 INFO - Cleaning up... 20:19:48 INFO - Return code: 0 20:19:48 INFO - Running post-action listener: _resource_record_post_action 20:19:48 INFO - Running post-action listener: _start_resource_monitoring 20:19:48 INFO - Starting resource monitoring. 20:19:48 INFO - [mozharness: 2016-11-30 04:19:48.165000Z] Finished create-virtualenv step (success) 20:19:48 INFO - [mozharness: 2016-11-30 04:19:48.165000Z] Running install step. 20:19:48 INFO - Running pre-action listener: _resource_record_pre_action 20:19:48 INFO - Running main action method: install 20:19:48 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 20:19:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 20:19:48 INFO - Reading from file tmpfile_stdout 20:19:48 INFO - Using _rmtree_windows ... 20:19:48 INFO - Using _rmtree_windows ... 20:19:48 INFO - Detecting whether we're running mozinstall >=1.0... 20:19:48 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 20:19:48 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 20:19:48 INFO - Reading from file tmpfile_stdout 20:19:48 INFO - Output received: 20:19:48 INFO - Usage: mozinstall-script.py [options] installer 20:19:48 INFO - Options: 20:19:48 INFO - -h, --help show this help message and exit 20:19:48 INFO - -d DEST, --destination=DEST 20:19:48 INFO - Directory to install application into. [default: 20:19:48 INFO - "C:\slave\test"] 20:19:48 INFO - --app=APP Application being installed. [default: firefox] 20:19:48 INFO - Using _rmtree_windows ... 20:19:48 INFO - Using _rmtree_windows ... 20:19:48 INFO - mkdir: C:\slave\test\build\application 20:19:48 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 20:19:48 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 20:19:51 INFO - Reading from file tmpfile_stdout 20:19:51 INFO - Output received: 20:19:51 INFO - C:\slave\test\build\application\firefox\firefox.exe 20:19:51 INFO - Using _rmtree_windows ... 20:19:51 INFO - Using _rmtree_windows ... 20:19:51 INFO - Running post-action listener: _resource_record_post_action 20:19:51 INFO - [mozharness: 2016-11-30 04:19:51.122000Z] Finished install step (success) 20:19:51 INFO - [mozharness: 2016-11-30 04:19:51.122000Z] Running run-tests step. 20:19:51 INFO - Running pre-action listener: _resource_record_pre_action 20:19:51 INFO - Running main action method: run_tests 20:19:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 20:19:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 20:19:51 INFO - Python 2.7.3 20:19:51 INFO - Return code: 0 20:19:51 INFO - grabbing minidump binary from tooltool 20:19:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:51 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 0x01973E90>, '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 0x01FC15C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 20:19:51 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 20:19:51 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 20:19:51 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 20:19:51 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 20:19:53 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp7ba9xz 20:19:53 INFO - INFO - File integrity verified, renaming tmp7ba9xz to win32-minidump_stackwalk.exe 20:19:53 INFO - Return code: 0 20:19:53 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 20:19:53 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 20:19:53 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 20:19:53 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 20:19:53 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 20:19:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 20:19:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 20:19:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:53 INFO - 'COMPUTERNAME': 'T-W732-IX-014', 20:19:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:53 INFO - 'DCLOCATION': 'SCL3', 20:19:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:53 INFO - 'HOMEDRIVE': 'C:', 20:19:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:53 INFO - 'KTS_VERSION': '1.19c', 20:19:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-014', 20:19:53 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 20:19:53 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 20:19:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:53 INFO - 'MOZ_NO_REMOTE': '1', 20:19:53 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 20:19:53 INFO - 'NO_EM_RESTART': '1', 20:19:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:53 INFO - 'OS': 'Windows_NT', 20:19:53 INFO - 'OURDRIVE': 'C:', 20:19:53 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;', 20:19:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:53 INFO - 'PROCESSOR_LEVEL': '6', 20:19:53 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:53 INFO - 'PROMPT': '$P$G', 20:19:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:19:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:53 INFO - 'PWD': 'C:\\slave\\test', 20:19:53 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 20:19:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:53 INFO - 'SYSTEMDRIVE': 'C:', 20:19:53 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:53 INFO - 'TEST1': 'testie', 20:19:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:53 INFO - 'USERDOMAIN': 'T-W732-IX-014', 20:19:53 INFO - 'USERNAME': 'cltbld', 20:19:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:53 INFO - 'WINDIR': 'C:\\windows', 20:19:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:53 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 20:19:54 INFO - mozversion application_buildid: 20161129191659 20:19:54 INFO - mozversion application_changeset: 5d49c9792a3417455bc5683ee2264b3a36d68682 20:19:54 INFO - mozversion application_display_name: Nightly 20:19:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:19:54 INFO - mozversion application_name: Firefox 20:19:54 INFO - mozversion application_remotingname: firefox 20:19:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:19:54 INFO - mozversion application_vendor: Mozilla 20:19:54 INFO - mozversion application_version: 53.0a1 20:19:54 INFO - mozversion platform_buildid: 20161129191659 20:19:54 INFO - mozversion platform_changeset: 5d49c9792a3417455bc5683ee2264b3a36d68682 20:19:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:19:54 INFO - mozversion platform_version: 53.0a1 20:19:54 INFO - using testdate: 1480479594 20:19:54 INFO - actual date: 1480479594 20:19:54 INFO - starting webserver on 'localhost:49589' 20:19:54 INFO - SUITE-START | Running 2 tests 20:19:54 INFO - TEST-START | dromaeo_css 20:19:54 INFO - operating with platform_type : w7_ 20:19:54 INFO - Initialising browser for dromaeo_css test... 20:19:54 INFO - TEST-INFO | started process 1120 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplnpeex\profile http://localhost:49589/getInfo.html) 20:20:02 INFO - PROCESS | 1120 | __metrics Screen width/height:1600/1200 20:20:02 INFO - PROCESS | 1120 | colorDepth:24 20:20:02 INFO - PROCESS | 1120 | Browser inner width/height: 1010/674 20:20:02 INFO - PROCESS | 1120 | __metrics 20:20:03 INFO - PROCESS | 1120 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:20:03 INFO - PROCESS | 1120 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:20:04 INFO - TEST-INFO | 1120: exit 0 20:20:04 INFO - Browser initialized. 20:20:04 INFO - Running cycle 1/1 for dromaeo_css test... 20:20:04 INFO - TEST-INFO | started process 3164 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplnpeex\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:05 INFO - PROCESS | 3164 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 20:20:16 INFO - PROCESS | 3164 | RSS: Main: 138399744 20:20:16 INFO - PROCESS | 3164 | 20:21:08 INFO - PROCESS | 3164 | 1480479668162 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] 20:23:23 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49589/tests/dromaeo/cssquery-ext.html) 20:23:25 INFO - PROCESS | 3164 | RSS: Main: 185602048 20:23:25 INFO - PROCESS | 3164 | 20:26:27 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-ext.html (next: http://localhost:49589/tests/dromaeo/cssquery-jquery.html) 20:26:28 INFO - PROCESS | 3164 | RSS: Main: 209248256 20:26:28 INFO - PROCESS | 3164 | 20:29:31 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49589/tests/dromaeo/cssquery-mootools.html) 20:29:31 INFO - PROCESS | 3164 | RSS: Main: 153305088 20:29:31 INFO - PROCESS | 3164 | 20:32:34 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49589/tests/dromaeo/cssquery-prototype.html) 20:32:34 INFO - PROCESS | 3164 | RSS: Main: 141230080 20:32:34 INFO - PROCESS | 3164 | 20:35:37 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49589/tests/dromaeo/cssquery-yui.html) 20:35:38 INFO - PROCESS | 3164 | RSS: Main: 141455360 20:35:38 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | Cycle 1(1): loaded http://localhost:49589/tests/dromaeo/cssquery-yui.html (next: http://localhost:49589/tests/dromaeo/cssquery-dojo.html) 20:38:41 INFO - PROCESS | 3164 | RSS: Main: 189235200 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | __start_tp_report 20:38:41 INFO - PROCESS | 3164 | _x_x_mozilla_page_load 20:38:41 INFO - PROCESS | 3164 | _x_x_mozilla_page_load_details 20:38:41 INFO - PROCESS | 3164 | |i|pagename|runs| 20:38:41 INFO - PROCESS | 3164 | |0;dojo.html;2320.9419764505883;2357.7760112789283;2358.2453614843257;2367.976320236797;2381.2141993142263;3967.801609919507;3983.88048358549;4022.8994275143136;4088.8568900088503;4098.446709694186;3940.133170702441;3954.782486963216;3971.3844354125085;4036.636702696757;4055.026793569544;3433.021588847179;3460.636633153508;3487.7907325560423;3498.860045598173;3530.43513037914;3475.826208689568;3525.7531972761917;3529.3823580873245;3529.894103176896;3534.982325088371;3414.368341856752;3516.7889926604485;3521.506989021539;3530.6292839251896;3532.982335088334;3455.982720086409;3476.235228249781;3484.9651503484893;3487.8779242726637;3513.9472907906147;3437.05480992729;3469.7224222062173;3483.425234836249;3547.450145227494;3552.626974167701;3817.160224750578;3935.5277366716177;3937.5274967003857;4002.3596224604216;4021.095253567924;4137.81379837905;4152.958470415288;4182.707210495236;4210.789460526961;4220.113776107021;4131.958680413188;4179.686523510749;4220.662347012231;4246.40550322955;4253.191893540217;3815.980920095382;3890.2219556088894;3897.279003384383;3922.941155882664;3926.09699769049;4238.660907127422;4240;4305.461817272841;4320.416743739618;4327.242732521796;3959.970407693963;4085.9591404086173;4163.042500224965;4169.395437661522;4169.687273454442;4012.35802271641;4049.0484736086996;4058.939115913264;4231.153769246163;4233.449651545279;4048.1701251243426;4050.736702114353;4064.5732198119363;4070.898227544335;4075.5924407559005;4143.08852052548;4143.627073563333;4148;4149.502059752788;4152.501699796044;2965.733084022448;2968.1540760883045;2969.8069625474277;2996.7602591792606;2997.010986374491;2670.2923725212836;2723.8638068096516;2756.6691996960094;2790.637217161797;2804.439112177532;3829.885103446901;3897.785621790772;3928.155446578999;3971.7418367806;3980.8805735827973;4167.8332866685;4171.457710497616;4206.390073439334;4231.809568569422;4243.469566304212;3985.5815139410524;3988.760674359548;3991.820368083443;3995.04119011441;3996.1208534122447;3599.460080987873;3630;3647.6899463545365;3648.3615367310613;3649.361361761681;4321.092570560218;4341.848035318749;4342.652587792906;4363.672724545609;4381;4144.772037537964;4183.142455796616;4261.232978063978;4271.188474189894;4279.165562715365;4242.342436922282;4281;4397.714148580332;4412.404325416028;4428.31361139024;4033;4065.593440655911;4077.612626800449;4185.142045880526;4194;4164.12553363797;4174.979125104355;4192.434021407071;4203.369494575838;4232.576742325743;3997.9000524985945;3998.980005099956;4026.1545075534464;4028.9524723571494;4107.445494858275;3997.360422332413;4030.677545796388;4091.1204091120553;4104.4048612950355;4116.773577453268;3853.7687738735767;3978.5623581406603;3986.123052928351;4017.919641607093;4026.6376026156613;3954.1893911747447;4123.463949686521;4157.210130075276;4162.521310049383;4162.666986641001;3919.2161567686007;4015.4177644242004;4058.350663893763;4115.753054816721;4116.732412393185 20:38:41 INFO - PROCESS | 3164 | |1;ext.html;535.4886083789982;537.12448708605;540.2032002795876;555.1700208188759;555.3502402189438;24382.75617243827;31264.843675781616;34344.1413964651;34374.31251374971;36604.81697591515;33472.66527334726;33837;33857.153571160736;33911.15222119448;34021.489677654856;6392.1370614966845;6912.205096413914;7088.397486213678;7103.076600041987;7120.430365570742;6398.584092034026;6437.0988061671405;6461;6461.095446637474;8241.711540096097;8912.955435222815;9179.127982841619;9193.908060919372;9197.540122993856;9212.49331286779;8819.426737262058;9101.408408453462;9155.45067295961;9210.355275130743;9243.26053915685;8801.899762529683;8876.289896808239;8920.8661870072;8959.283257339463;8975.147361000694;31955.201119971953;32057.428227213364;32197;32300.67699323;32321.838390807894;2545.8727063646743;2576.935576610581;2743.5747459143863;2869.4834929712642;3038.225252560605;24044.398890027715;25003;25450.872745636338;25526.10658626939;25865.353366165808;2897.840618765967;2931.2525306046823;2977.880884764607;3009.5184770436626;3027.303720144357;190137;202389;202831;203370;204920;43358;45410;45769;45776.313355299695;45795.542044579124;47157.292640610416;47833.521664783606;47921;47961.5203847964;48055.63610547298;9395.530223488799;9558.66544670947;9639;9689.660861869801;9699.757506062404;6866.313368663094;7213.098362704662;7217.8917316240295;7230.0239467672245;7252.093488313961;12284.877151228373;12782.488700451877;13773.311334433238;13998.790018149735;14762.188079655507;5732.7706891723865;5791;5799.4780469757925;5806.30324361071;5819.476247137778;740.9777706668808;772.1737740617598;819.2217393476221;826.557791581501;908.8545832666741;22893.08427662875;24185.436907663945;24431.26706198817;24703.41034432866;26314.342141446617;838.3419016072354;849.8682704180862;857.7984173719171;883.8364902493059;887.3211992825494;12181.69545761363;12320.322382268881;12327.83608195902;12654.936725316315;12775.872241277468;301329;306826;314271;314618;314809;40178.39732404016;41713;41931.37102943458;42020.94947626273;42141;921.6267411698273;968.1073501305073;1010.7523656704154;1024.656739992111;1086.766345235778;22945.655815162452;25020.62469062929;25065;25114.44932029326;25209.24372268835;36279.274414511034;36383;37099.81450092732;37142.8142859284;37278.6272137286;274942;284513;286092;287938;385143;32146.678533215305;33487;33520.32959340751;33851;33873.49189762106;36303;36380;37089.44365834461;37107.07232319214;37375.252494950495;30489.390212196075;31597.052088437384;31839.6816031846;32077.509148719666;32860.83569582137;31042;31266.374672506878;31557;31862;32090.679093208768;29244.853775730986;30387.2403189922;30513.542296865122;30643.387132257674;31003.224919377197;27726.584101238095;28746.568801468413;28881.566776497948;29315.706842931297;29371.412571748016;22030.228941986952;22089.889550552143;22109;22655.660165097866;22687 20:38:41 INFO - PROCESS | 3164 | |2;jquery.html;262.41743329807844;297.4497180216599;308.41401337458825;312.7701139662349;314.33204440563804;11146.275492093007;12008.75982480351;12020.51917923282;15159.469418570352;16301.51095467138;14631.195284259362;15261.618459538517;15295.617609559766;15346.079235245894;15356.923215383908;4483.484399294074;4553.772311384434;4636.791344389502;4766.547178018082;4837.105135549917;4744.288356746499;4751.14479393709;4753.0493901219725;4764.90470190596;4795.424549054101;3101.7208451239385;3266.6896644818703;5276.3668359796875;5549.445055494439;5559.749056462304;5582.218489411485;5584.357798853143;5591.189277554752;5601.075822489284;5610.775568977236;4615.746133962631;4634.814607415699;4635.582797548237;4665.766711664437;4817.325574419584;9195.494247816367;9262.953685231598;9416.387934784218;9519.666811661558;9521.190698790611;1469.7060587882359;1550.9069455832687;1557.5171696773923;1564.2100739126781;1581.3121292237913;7360.153582338046;7417.888731669029;7518.736844210481;7591.089069311663;7639.809004774925;1553.8756899448017;1562.6093476630842;1591.9363225470968;1629.706652802495;1666.166916541729;61484.69257653728;61974.69012654908;63319.683401583156;63552.364476355106;66645.00032499517;7777.338926191224;7790.688372465152;7800.297973182441;8059.234372734581;8135.145809689898;9043.638254469879;9061.864072038925;9370.531473426301;10234.385936843813;10265.076143147004;5541.085720856014;5562.443755624486;5571.554275657938;5654.067078932012;5677.290338707661;5102.413222479388;5163.14808056674;5258.2638430619745;5377.704226267515;5473.534749546254;3237.4981877808505;3287.4247006773717;3337.766356355032;3341.3484370547994;3346.6318704942437;1346.7575836349552;1410.6614412540916;1418.3971811980073;1419.6025112968384;1435.9712805743827;738.8522295540806;776.4300166449071;777;855.2943821347413;868.3487384461654;15142.318595663222;15157;15458.38166473351;15656.686866262611;16033.919830401006;750.7860259826033;766.4634755671052;782.9530228186327;786.4809225910872;790.9248621380959;8535.573221338907;8628.309735221168;8630.352723545759;8745.169208925143;8808.075152109064;268950;270463;270902;271178;273470;58996.41003589909;61267.6936615323;67883;69287.65356173187;70592;761.1931352766086;773.7523992322402;793.7539362797552;809.8420807942513;824.3034635732796;14613.269336532918;14712.485063022743;14780.556583302518;14883.925580372028;15300.923495382453;37205.62794372022;37238.25523489569;37238.88283351504;37542.812285939486;37806.24387512289;49825;50417;50619;50755.74622126866;50988.23517647312;5967.4629283362965;6043.697815109139;6062.333143354315;6119.418655227746;6199.1011303359855;9543.95016548193;10339.483025848827;10974.780504390028;11010.944945275221;11124.833127502932;9045.547722613974;9054.320925930713;9143.13140251675;9268.860967085364;9337.253019758538;8717.346199034972;8755.956220218859;8759.868601971102;9036.502992335485;9206.9539652304;9331.58007889633;9382.624695012122;9488.288378371511;9822.410655360702;9880.654177103766;6064.363241859542;6092.360302168208;6186.443220110032;6243.250809902841;6373;4987.950120498748;6201.503879689524;8025.759227223192;9048;9329.766755831159 20:38:41 INFO - PROCESS | 3164 | |3;mootools.html;1015.7003683913244;1146.7419830538126;1150.045462266319;1153.9538418463262;1373.979390309145;4971.900561988763;5263.842084737455;5267.078261304266;5270.86822829429;5459.563234941205;5636.633618814774;5674.801381951633;5689.573282003856;5692.459216374448;5695;1702.5743564108973;1732.6881161390945;1733.1854028606597;1734;1734.9219285132135;1460.7808828675677;1546.5283088657955;1661.775660285864;1718.7078196706527;1726.2231995601967;1630.6738652269535;1634.3054201964117;1698.9975914210613;1699.5071429285492;1706.8378504042096;1632.616335161236;1639.0739232333694;1643.0306119389618;1646.2509558151012;1651.9256633451523;1413.2368520998648;1458.7009663019162;1645.7531370294432;1692.4837924433043;1693.8052124005774;4888.486708895551;5159.174532074888;5271.710055946922;5276.366835979707;5373.408925018245;3361.764676472648;3426.1777173478436;3515.6660117288816;3522.18989632386;3540.8583656653705;2252.8760918149496;2263.7283525976823;2305.4582173189287;2310.0759696121518;2314.0628045641542;676.8071099736551;682.3858527325398;719.6114098386864;719.8668246374385;720.7009091227135;6151.477124444427;6835.248122706498;6852.965735171292;6897.896531552031;6902.723891044352;3128.108489080601;3151.921201969969;3181.9204519886725;3208.4224839528642;3213.614366276062;6017.217761690952;6142.324344322121;6144.754209831646;6148;6150.477209437248;3477.7217822574135;3536.5579302587175;3568.910777230538;3598.2983318253214;3601.7838929664304;3062.20382700495;3094.706002929718;3248.918777030593;3260.3642289753743;3264.771465997357;2625.369911221293;2627.4482358704886;2652.5755879059257;2720.2519307190682;2762.2127693607226;930.776613612728;946.8485042393185;950.695777351241;956.3401253135314;957.1529196660866;587.6415386614162;593.3359217115208;709.5423451873513;723.9493235473466;727.0548287226584;3770.1140232045445;3814.5761031234506;3855.6529912307465;3887.183691424776;3941.92116157681;306.2160868177473;311.20642361976866;313.33260155867856;315.12395540397733;315.93365393267214;2659.79924730168;2675.745804148603;2728.4679487500143;2751.8073734838767;2756.145594865598;5945.910811337833;6196.938030619726;6240.812775616739;6272.059191121369;6342.143810585604;5952.702364881825;6179.104029915637;6492.707828147744;6551.868962620721;6574.802755917234;4962.925556116661;5122.462141475166;5124.718140502234;5166.844994650167;5241.161414173714;3647.799371034512;3675.2833197526224;3827.808609569455;3834.386498160281;3851.268259030775;5686.004949133968;5835.095560188178;5940.227770389822;5998.130271110752;6009.36901625323;5901.557383196192;5919.2235254257;6052.969735151296;6133.202683651097;6139.171211886517;4493.573110554492;4757.500462451531;4800.0639875224;4806.639502037431;4808.08646357191;5220.295260139985;5235.895282094413;5259.4477579853565;5261.000409922102;5272.103742363884;4720.834770783006;4750;4750.5249475052215;4790.664653474223;4827.975860120677;4496.662750293675;4548.522405147544;4649.372334734768;4745.572898439019;4828.275758636232;4057.655099316591;4363.498197707304;4370;4403.82384704621;4442.22261104299;3170.7304879084613;3194.3770964661667;3324.700776930088;3405.3189362127177;3410.420228561101;3257.5439438479107;3260.9021729348156;3293.934121317608;3311.503274508843;3331.06730115568 20:38:41 INFO - PROCESS | 3164 | |4;prototype.html;227.4836122003052;233.80360497182363;235.37977429473332;235.61241757309224;240.9156795121707;4327.697061205717;4364.388985542027;4390.407295015176;4392.802323895425;4405.823767049318;4055.736377135484;4174.582541745824;4240.830366785332;4287.764172970486;4297.1835351283235;3174.5238214267815;3191.2021994501374;3198.504231844068;3199.600049993751;3199.8000749718854;3175.047485754276;3199.744020478368;3209.9679003209903;3211.887583934563;3214.517822326646;2993.0721476342296;3035.4991426414617;3077.9230519237085;3144.1353627752324;3181.1569933967517;3007.2782532192336;3162.9562244459275;3168.667289934558;3170.6036745406823;3170.651228364878;2732.8658606678205;2739.1097893184797;2746.436980419009;2746.986265068682;2750.642416485864;4078.8980275493054;4161.708680392374;4222.746635201897;4232.9153416931795;4239.27932251518;3628.401313783249;3692.8707495237536;3698.519192504984;3706.5366829146355;3706.944395834065;4189.895252618679;4440.933385999213;4453.309736990772;4455.1535208310315;4485.820567177309;3055.175102722275;3063.846807659608;3066.187460322994;3078.0765770269054;3082.799618024821;4647.372604698357;4666.510016448292;4669.556392142741;4670.462896766848;4680.602148817321;3041.9391612168074;3153.7477001840257;3158.0683698309053;3159.557661927332;3159.684031596868;3071.2628969047714;3182.98408507956;3188.740447523215;3190.9042728718177;3193.3873393935914;3479.7216222702123;3580.493654441901;3607.1703508193264;3637.7635453695952;3711.480392745018;4337.7614231217585;4457.977710111429;4459.44256967879;4486.394336764561;4514.909701805946;1046.733083063829;1057.9471026448646;1083.4366129612558;1086.5002099034532;1086.8913108689069;678.1353773938268;690.1235431002699;691.8616276744672;705.9258777828254;709.1596458197052;4157.667386609064;4160.50073991123;4160.646345060703;4177.373393990925;4188.979055104705;4423.424954755926;4448.332750087513;4506.977465112654;4512.729236245836;4528.569785870337;4182.309918863414;4183.0339740322;4190.979045104755;4191.706580539394;4201.62185403309;2279.304812032346;2305.250793492122;2379.584721507671;2389.6535002424553;2396.7962723168375;4589.58693717567;4602.907941841145;4611.76941152941;4626.444826620827;4627.259638457831;4396.538363471854;4473.910521789546;4564.520725323793;4568.931466028012;4587.977060114678;4640.443146822403;4644.3265726470145;4646.930296045562;4647.930281045719;4648;4290.871273861789;4462.955370446254;4478.888027799201;4487.035287413222;4488.326750987378;4345.326474396474;4354.412154359121;4359.362585635645;4400.44994375703;4403.8458653946955;4469.128519938581;4593.358818679206;4616.130467160805;4618.792154352995;4632.953670463252;3055.5722198892618;3082.2910730531653;3083.3987372462157;3084.21352555097;3096.1330827368374;4292.678049146389;4418.823247070081;4442;4447.221736196088;4455.220336441045;3946.9013274668364;4269.188854117708;4273.145370925765;4289.63538099265;4328.675349348749;4224.89437764046;4243.469566304212;4271.270135594965;4301.2257793597455;4325.697201195886;4242.257604919188;4245.150969806113;4250.596193361625;4253;4254.978725106354;4346.304591265382;4347.41309923169;4347.782610869381;4362.847300344472;4365.956340436555;4252.831640427901;4291.935620965625;4293.50624678166;4372.431583894074;4441.267190913463 20:38:41 INFO - PROCESS | 3164 | |5;yui.html;535.0689799748436;551.0989532114993;567.869390040291;578.9578758235177;618.9659568723721;11819.822702659456;12374.319412432313;12580;12610.432530536136;12613.243205407682;10204.642837500689;11644.767104657902;11786.528538858436;11799.646010619674;12841.743165136691;617.5121653893419;633.0757094641806;635.1647583427778;635.3075148088583;636.312782195228;620.540799808141;635.5551114220042;636.3254949753253;637.7895294552786;639.0350570638323;658.2956236826598;665.866826634675;667.79966010197;668.1748041169152;668.9598624082546;647.4496677823859;665.3147258323934;666.5467482112138;666.7033170239246;667.2626747444077;475.87865094400706;490;490.4286506220249;490.6786055133893;490.921452567591;11875.52497900083;12088.697782555419;12670.239785612801;12743.235405875677;12795.104342696015;12469.438875250635;12506.249625022529;12526.624201273977;12602.55891043809;12895.355232238351;9796.510174491246;9887.950560247225;10078.984541312675;10136.239782016304;10138.290319677626;2895.406441679451;2924.6344206974127;2981.0907673159677;3001.2609083523885;3078.9839353013454;58964.7051764747;59159;60103;60515;61372.38627613756;9302;9502.952485237529;9527.618895244252;9761.974992625814;9922.156616687662;17645.558861028578;17818.18181818182;18036.63926721458;18046;18089.819101808815;6392.680365981682;6407.967960160169;6422.839429014219;6468.54720169593;6493.350664933469;3090.5449353533822;3167.762417818673;3183.777135600532;3189.7607679424136;3223.3875563642832;2222.4888275696358;2241.047554789208;2287.4281429642588;2311.5376924615143;2314.0281081945623;363.3006462559615;369.40710160193044;376.9415740560163;378.68379902781487;379.2907263417427;477.3007543948133;514.1105886815755;529.2220435959134;548.9533389661843;570.1789423230534;6860.725570977205;6922.7923162305215;6981.30186981308;7169.960355748387;7275.927240727525;419.7859091863111;468.40746455733523;482.38977693218106;504.5513912408654;515.2720070291439;3213.4858422652264;3389.881354152593;3408.250184959305;3431.4852772084387;3555.3244883472057;51738.48261517412;53349.46650533523;58687.41312586905;66742.66628666826;66997;50360.63936063936;50438;50901.236481452805;50989.23516147261;51529.742351288005;48283;48970;49897;50126.74936625294;50217.497825022016;7112.466565007749;7216.819579510554;7561.584112873845;7752.689892404466;7787.104482984303;33401.48262193125;34085.147871303416;35909.461358080174;36807;37937.24125517419;44352;51911.48088519218;53908.73045634747;54614.45385546094;55506;11567.826482602599;11898.881011189776;11905.107116966088;11968.341741204318;11992.100592455427;12166.513339466323;12656.240625562496;12948.15836970594;13394.196348219139;13463.932680336535;11375;11982.400879955792;12064.45709943037;12277.447514861675;12590.055745819283;11389.54441822351;11697.824532632187;11996.220245684002;12363.814542781685;12366.876331236572;11517;11829.645110646694;12011.879881201427;12026.699332516757;12052.819207711716;9102.226310763659;9254.213391861767;9415.764605884908;9646.614135434504;9711.125998659872;9860.802783944424;9895.604175833174;10232.757162333206;10416.479176041015;10506.58904683115 20:38:41 INFO - PROCESS | 3164 | __end_tp_report 20:38:41 INFO - PROCESS | 3164 | __start_cc_report 20:38:41 INFO - PROCESS | 3164 | _x_x_mozilla_cycle_collect,999 20:38:41 INFO - PROCESS | 3164 | __end_cc_report 20:38:41 INFO - PROCESS | 3164 | __startTimestamp1480480721819__endTimestamp 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | ------- Summary: start ------- 20:38:41 INFO - PROCESS | 3164 | Number of tests: 6 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#0] dojo.html Cycles:165 Average:3871.39 Median:4022.90 stddev:457.91 (11.4%) stddev-sans-first:442.87 20:38:41 INFO - PROCESS | 3164 | Values: 2320.9 2357.8 2358.2 2368.0 2381.2 3967.8 3983.9 4022.9 4088.9 4098.4 3940.1 3954.8 3971.4 4036.6 4055.0 3433.0 3460.6 3487.8 3498.9 3530.4 3475.8 3525.8 3529.4 3529.9 3535.0 3414.4 3516.8 3521.5 3530.6 3533.0 3456.0 3476.2 3485.0 3487.9 3513.9 3437.1 3469.7 3483.4 3547.5 3552.6 3817.2 3935.5 3937.5 4002.4 4021.1 4137.8 4153.0 4182.7 4210.8 4220.1 4132.0 4179.7 4220.7 4246.4 4253.2 3816.0 3890.2 3897.3 3922.9 3926.1 4238.7 4240.0 4305.5 4320.4 4327.2 3960.0 4086.0 4163.0 4169.4 4169.7 4012.4 4049.0 4058.9 4231.2 4233.4 4048.2 4050.7 4064.6 4070.9 4075.6 4143.1 4143.6 4148.0 4149.5 4152.5 2965.7 2968.2 2969.8 2996.8 2997.0 2670.3 2723.9 2756.7 2790.6 2804.4 3829.9 3897.8 3928.2 3971.7 3980.9 4167.8 4171.5 4206.4 4231.8 4243.5 3985.6 3988.8 3991.8 3995.0 3996.1 3599.5 3630.0 3647.7 3648.4 3649.4 4321.1 4341.8 4342.7 4363.7 4381.0 4144.8 4183.1 4261.2 4271.2 4279.2 4242.3 4281.0 4397.7 4412.4 4428.3 4033.0 4065.6 4077.6 4185.1 4194.0 4164.1 4175.0 4192.4 4203.4 4232.6 3997.9 3999.0 4026.2 4029.0 4107.4 3997.4 4030.7 4091.1 4104.4 4116.8 3853.8 3978.6 3986.1 4017.9 4026.6 3954.2 4123.5 4157.2 4162.5 4162.7 3919.2 4015.4 4058.4 4115.8 4116.7 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#1] ext.html Cycles:180 Average:40893.98 Median:33865.32 stddev:72947.55 (215.4%) stddev-sans-first:73089.25 20:38:41 INFO - PROCESS | 3164 | Values: 535.5 537.1 540.2 555.2 555.4 24382.8 31264.8 34344.1 34374.3 36604.8 33472.7 33837.0 33857.2 33911.2 34021.5 6392.1 6912.2 7088.4 7103.1 7120.4 6398.6 6437.1 6461.0 6461.1 8241.7 8913.0 9179.1 9193.9 9197.5 9212.5 8819.4 9101.4 9155.5 9210.4 9243.3 8801.9 8876.3 8920.9 8959.3 8975.1 31955.2 32057.4 32197.0 32300.7 32321.8 2545.9 2576.9 2743.6 2869.5 3038.2 24044.4 25003.0 25450.9 25526.1 25865.4 2897.8 2931.3 2977.9 3009.5 3027.3 190137.0 202389.0 202831.0 203370.0 204920.0 43358.0 45410.0 45769.0 45776.3 45795.5 47157.3 47833.5 47921.0 47961.5 48055.6 9395.5 9558.7 9639.0 9689.7 9699.8 6866.3 7213.1 7217.9 7230.0 7252.1 12284.9 12782.5 13773.3 13998.8 14762.2 5732.8 5791.0 5799.5 5806.3 5819.5 741.0 772.2 819.2 826.6 908.9 22893.1 24185.4 24431.3 24703.4 26314.3 838.3 849.9 857.8 883.8 887.3 12181.7 12320.3 12327.8 12654.9 12775.9 301329.0 306826.0 314271.0 314618.0 314809.0 40178.4 41713.0 41931.4 42020.9 42141.0 921.6 968.1 1010.8 1024.7 1086.8 22945.7 25020.6 25065.0 25114.4 25209.2 36279.3 36383.0 37099.8 37142.8 37278.6 274942.0 284513.0 286092.0 287938.0 385143.0 32146.7 33487.0 33520.3 33851.0 33873.5 36303.0 36380.0 37089.4 37107.1 37375.3 30489.4 31597.1 31839.7 32077.5 32860.8 31042.0 31266.4 31557.0 31862.0 32090.7 29244.9 30387.2 30513.5 30643.4 31003.2 27726.6 28746.6 28881.6 29315.7 29371.4 22030.2 22089.9 22109.0 22655.7 22687.0 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#2] jquery.html Cycles:180 Average:19264.40 Median:5545.27 stddev:45564.98 (821.7%) stddev-sans-first:45670.47 20:38:41 INFO - PROCESS | 3164 | Values: 262.4 297.4 308.4 312.8 314.3 11146.3 12008.8 12020.5 15159.5 16301.5 14631.2 15261.6 15295.6 15346.1 15356.9 4483.5 4553.8 4636.8 4766.5 4837.1 4744.3 4751.1 4753.0 4764.9 4795.4 3101.7 3266.7 5276.4 5549.4 5559.7 5582.2 5584.4 5591.2 5601.1 5610.8 4615.7 4634.8 4635.6 4665.8 4817.3 9195.5 9263.0 9416.4 9519.7 9521.2 1469.7 1550.9 1557.5 1564.2 1581.3 7360.2 7417.9 7518.7 7591.1 7639.8 1553.9 1562.6 1591.9 1629.7 1666.2 61484.7 61974.7 63319.7 63552.4 66645.0 7777.3 7790.7 7800.3 8059.2 8135.1 9043.6 9061.9 9370.5 10234.4 10265.1 5541.1 5562.4 5571.6 5654.1 5677.3 5102.4 5163.1 5258.3 5377.7 5473.5 3237.5 3287.4 3337.8 3341.3 3346.6 1346.8 1410.7 1418.4 1419.6 1436.0 738.9 776.4 777.0 855.3 868.3 15142.3 15157.0 15458.4 15656.7 16033.9 750.8 766.5 783.0 786.5 790.9 8535.6 8628.3 8630.4 8745.2 8808.1 268950.0 270463.0 270902.0 271178.0 273470.0 58996.4 61267.7 67883.0 69287.7 70592.0 761.2 773.8 793.8 809.8 824.3 14613.3 14712.5 14780.6 14883.9 15300.9 37205.6 37238.3 37238.9 37542.8 37806.2 49825.0 50417.0 50619.0 50755.7 50988.2 5967.5 6043.7 6062.3 6119.4 6199.1 9544.0 10339.5 10974.8 11010.9 11124.8 9045.5 9054.3 9143.1 9268.9 9337.3 8717.3 8756.0 8759.9 9036.5 9207.0 9331.6 9382.6 9488.3 9822.4 9880.7 6064.4 6092.4 6186.4 6243.3 6373.0 4988.0 6201.5 8025.8 9048.0 9329.8 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#3] mootools.html Cycles:180 Average:3600.87 Median:4210.58 stddev:1866.18 (44.3%) stddev-sans-first:1861.30 20:38:41 INFO - PROCESS | 3164 | Values: 1015.7 1146.7 1150.0 1154.0 1374.0 4971.9 5263.8 5267.1 5270.9 5459.6 5636.6 5674.8 5689.6 5692.5 5695.0 1702.6 1732.7 1733.2 1734.0 1734.9 1460.8 1546.5 1661.8 1718.7 1726.2 1630.7 1634.3 1699.0 1699.5 1706.8 1632.6 1639.1 1643.0 1646.3 1651.9 1413.2 1458.7 1645.8 1692.5 1693.8 4888.5 5159.2 5271.7 5276.4 5373.4 3361.8 3426.2 3515.7 3522.2 3540.9 2252.9 2263.7 2305.5 2310.1 2314.1 676.8 682.4 719.6 719.9 720.7 6151.5 6835.2 6853.0 6897.9 6902.7 3128.1 3151.9 3181.9 3208.4 3213.6 6017.2 6142.3 6144.8 6148.0 6150.5 3477.7 3536.6 3568.9 3598.3 3601.8 3062.2 3094.7 3248.9 3260.4 3264.8 2625.4 2627.4 2652.6 2720.3 2762.2 930.8 946.8 950.7 956.3 957.2 587.6 593.3 709.5 723.9 727.1 3770.1 3814.6 3855.7 3887.2 3941.9 306.2 311.2 313.3 315.1 315.9 2659.8 2675.7 2728.5 2751.8 2756.1 5945.9 6196.9 6240.8 6272.1 6342.1 5952.7 6179.1 6492.7 6551.9 6574.8 4962.9 5122.5 5124.7 5166.8 5241.2 3647.8 3675.3 3827.8 3834.4 3851.3 5686.0 5835.1 5940.2 5998.1 6009.4 5901.6 5919.2 6053.0 6133.2 6139.2 4493.6 4757.5 4800.1 4806.6 4808.1 5220.3 5235.9 5259.4 5261.0 5272.1 4720.8 4750.0 4750.5 4790.7 4828.0 4496.7 4548.5 4649.4 4745.6 4828.3 4057.7 4363.5 4370.0 4403.8 4442.2 3170.7 3194.4 3324.7 3405.3 3410.4 3257.5 3260.9 3293.9 3311.5 3331.1 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#4] prototype.html Cycles:180 Average:3655.79 Median:4241.54 stddev:1105.44 (26.1%) stddev-sans-first:1078.17 20:38:41 INFO - PROCESS | 3164 | Values: 227.5 233.8 235.4 235.6 240.9 4327.7 4364.4 4390.4 4392.8 4405.8 4055.7 4174.6 4240.8 4287.8 4297.2 3174.5 3191.2 3198.5 3199.6 3199.8 3175.0 3199.7 3210.0 3211.9 3214.5 2993.1 3035.5 3077.9 3144.1 3181.2 3007.3 3163.0 3168.7 3170.6 3170.7 2732.9 2739.1 2746.4 2747.0 2750.6 4078.9 4161.7 4222.7 4232.9 4239.3 3628.4 3692.9 3698.5 3706.5 3706.9 4189.9 4440.9 4453.3 4455.2 4485.8 3055.2 3063.8 3066.2 3078.1 3082.8 4647.4 4666.5 4669.6 4670.5 4680.6 3041.9 3153.7 3158.1 3159.6 3159.7 3071.3 3183.0 3188.7 3190.9 3193.4 3479.7 3580.5 3607.2 3637.8 3711.5 4337.8 4458.0 4459.4 4486.4 4514.9 1046.7 1057.9 1083.4 1086.5 1086.9 678.1 690.1 691.9 705.9 709.2 4157.7 4160.5 4160.6 4177.4 4189.0 4423.4 4448.3 4507.0 4512.7 4528.6 4182.3 4183.0 4191.0 4191.7 4201.6 2279.3 2305.3 2379.6 2389.7 2396.8 4589.6 4602.9 4611.8 4626.4 4627.3 4396.5 4473.9 4564.5 4568.9 4588.0 4640.4 4644.3 4646.9 4647.9 4648.0 4290.9 4463.0 4478.9 4487.0 4488.3 4345.3 4354.4 4359.4 4400.4 4403.8 4469.1 4593.4 4616.1 4618.8 4633.0 3055.6 3082.3 3083.4 3084.2 3096.1 4292.7 4418.8 4442.0 4447.2 4455.2 3946.9 4269.2 4273.1 4289.6 4328.7 4224.9 4243.5 4271.3 4301.2 4325.7 4242.3 4245.2 4250.6 4253.0 4255.0 4346.3 4347.4 4347.8 4362.8 4366.0 4252.8 4291.9 4293.5 4372.4 4441.3 20:38:41 INFO - PROCESS | 3164 | 20:38:41 INFO - PROCESS | 3164 | [#5] yui.html Cycles:180 Average:14230.86 Median:479.85 stddev:17659.08 (3680.2%) stddev-sans-first:17678.67 20:38:41 INFO - PROCESS | 3164 | Values: 535.1 551.1 567.9 579.0 619.0 11819.8 12374.3 12580.0 12610.4 12613.2 10204.6 11644.8 11786.5 11799.6 12841.7 617.5 633.1 635.2 635.3 636.3 620.5 635.6 636.3 637.8 639.0 658.3 665.9 667.8 668.2 669.0 647.4 665.3 666.5 666.7 667.3 475.9 490.0 490.4 490.7 490.9 11875.5 12088.7 12670.2 12743.2 12795.1 12469.4 12506.2 12526.6 12602.6 12895.4 9796.5 9888.0 10079.0 10136.2 10138.3 2895.4 2924.6 2981.1 3001.3 3079.0 58964.7 59159.0 60103.0 60515.0 61372.4 9302.0 9503.0 9527.6 9762.0 9922.2 17645.6 17818.2 18036.6 18046.0 18089.8 6392.7 6408.0 6422.8 6468.5 6493.4 3090.5 3167.8 3183.8 3189.8 3223.4 2222.5 2241.0 2287.4 2311.5 2314.0 363.3 369.4 376.9 378.7 379.3 477.3 514.1 529.2 549.0 570.2 6860.7 6922.8 6981.3 7170.0 7275.9 419.8 468.4 482.4 504.6 515.3 3213.5 3389.9 3408.3 3431.5 3555.3 51738.5 53349.5 58687.4 66742.7 66997.0 50360.6 50438.0 50901.2 50989.2 51529.7 48283.0 48970.0 49897.0 50126.7 50217.5 7112.5 7216.8 7561.6 7752.7 7787.1 33401.5 34085.1 35909.5 36807.0 37937.2 44352.0 51911.5 53908.7 54614.5 55506.0 11567.8 11898.9 11905.1 11968.3 11992.1 12166.5 12656.2 12948.2 13394.2 13463.9 11375.0 11982.4 12064.5 12277.4 12590.1 11389.5 11697.8 11996.2 12363.8 12366.9 11517.0 11829.6 12011.9 12026.7 12052.8 9102.2 9254.2 9415.8 9646.6 9711.1 9860.8 9895.6 10232.8 10416.5 10506.6 20:38:41 INFO - PROCESS | 3164 | -------- Summary: end -------- 20:38:41 INFO - PROCESS | 3164 | 20:38:42 INFO - TEST-INFO | 3164: exit 0 20:38:43 INFO - TEST-OK | dromaeo_css | took 1128718ms 20:38:43 INFO - TEST-START | kraken 20:38:43 INFO - operating with platform_type : w7_ 20:38:43 INFO - Initialising browser for kraken test... 20:38:43 INFO - TEST-INFO | started process 1088 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzigzze\profile http://localhost:49589/getInfo.html) 20:38:48 INFO - PROCESS | 1088 | __metrics Screen width/height:1600/1200 20:38:48 INFO - PROCESS | 1088 | colorDepth:24 20:38:48 INFO - PROCESS | 1088 | Browser inner width/height: 1010/674 20:38:48 INFO - PROCESS | 1088 | __metrics 20:38:48 INFO - PROCESS | 1088 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:38:48 INFO - PROCESS | 1088 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:38:48 INFO - PROCESS | 1088 | console.error: 20:38:48 INFO - PROCESS | 1088 | Message: Error: Connection closed before committing the transaction. 20:38:48 INFO - PROCESS | 1088 | Stack: 20:38:48 INFO - PROCESS | 1088 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 20:38:48 INFO - PROCESS | 1088 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 20:38:48 INFO - PROCESS | 1088 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 20:38:48 INFO - PROCESS | 1088 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 20:38:48 INFO - PROCESS | 1088 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 20:38:48 INFO - PROCESS | 1088 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 20:38:48 INFO - PROCESS | 1088 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 20:38:48 INFO - PROCESS | 1088 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 20:38:48 INFO - PROCESS | 1088 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 20:38:48 INFO - PROCESS | 1088 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 20:38:48 INFO - PROCESS | 1088 | Barrier/this.client.addBlocker/promise13.2% 20:39:49 INFO - TinderboxPrint: I/O read bytes / time
201,074,688 / 2,393,930 20:39:49 INFO - TinderboxPrint: I/O write bytes / time
486,213,120 / 3,621,410 20:39:49 INFO - TinderboxPrint: CPU idle
8,336.0 (86.8%) 20:39:49 INFO - TinderboxPrint: CPU user
1,233.6 (12.8%) 20:39:49 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 35327488; Read time: 0; Write time: 322170 20:39:49 INFO - run-tests - Wall time: 1199s; CPU: 13.0%; Read bytes: 201074688; Write bytes: 413681664; Read time: 2393930; Write time: 3276370 20:39:50 INFO - Running post-run listener: _upload_blobber_files 20:39:50 INFO - Blob upload gear active. 20:39:50 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 20:39:50 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 20:39:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 20:39:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 20:39:51 INFO - (blobuploader) - INFO - Open directory for files ... 20:39:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 20:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:39:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:51 INFO - (blobuploader) - INFO - Done attempting. 20:39:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 20:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:39:52 INFO - (blobuploader) - INFO - Done attempting. 20:39:52 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 20:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:40:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:00 INFO - (blobuploader) - INFO - Done attempting. 20:40:00 INFO - (blobuploader) - INFO - Iteration through files over. 20:40:00 INFO - Return code: 0 20:40:00 INFO - rmtree: C:\slave\test\build\uploaded_files.json 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 20:40:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/339e932bd7aa11dde6e00ff5ade2770283c24eb8b6c9bfc7311ebdf9b00d3f5c72102535ef6eeb1ec337113ecb984442de49f9bc1371ba57c49264aa59abd3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7db9943bb739ef4122a4085cef91d53667763710813de8fdc60e4e6cd8551d7f7c924c4060d189c013a950e94e4d6d1fdf1f2bbd4ab95bee443055096e8dae58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b98da0494b14c596af22dccf502db2811b298b59f2f55a4f2fe6275b8ce62a6ce5a1dcf3ed44f33bd6911412235e281e791bddcd3ade2ca124eddefba306619"} 20:40:00 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 20:40:00 INFO - Writing to file C:\slave\test\properties\blobber_files 20:40:00 INFO - Contents: 20:40:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/339e932bd7aa11dde6e00ff5ade2770283c24eb8b6c9bfc7311ebdf9b00d3f5c72102535ef6eeb1ec337113ecb984442de49f9bc1371ba57c49264aa59abd3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7db9943bb739ef4122a4085cef91d53667763710813de8fdc60e4e6cd8551d7f7c924c4060d189c013a950e94e4d6d1fdf1f2bbd4ab95bee443055096e8dae58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b98da0494b14c596af22dccf502db2811b298b59f2f55a4f2fe6275b8ce62a6ce5a1dcf3ed44f33bd6911412235e281e791bddcd3ade2ca124eddefba306619"} 20:40:00 INFO - Running post-run listener: copy_logs_to_upload_dir 20:40:00 INFO - Copying logs to upload dir... 20:40:00 INFO - mkdir: C:\slave\test\build\upload\logs 20:40:00 INFO - Copying logs to upload dir... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... 20:40:00 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1403.196000 ========= master_lag: 0.34 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 23 secs) (at 2016-11-29 20:40:00.657007) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:00.667124) ========= '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-014 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-014 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-014 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/339e932bd7aa11dde6e00ff5ade2770283c24eb8b6c9bfc7311ebdf9b00d3f5c72102535ef6eeb1ec337113ecb984442de49f9bc1371ba57c49264aa59abd3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7db9943bb739ef4122a4085cef91d53667763710813de8fdc60e4e6cd8551d7f7c924c4060d189c013a950e94e4d6d1fdf1f2bbd4ab95bee443055096e8dae58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b98da0494b14c596af22dccf502db2811b298b59f2f55a4f2fe6275b8ce62a6ce5a1dcf3ed44f33bd6911412235e281e791bddcd3ade2ca124eddefba306619"} build_url:https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/BmFi8CKIRcCq5ydotxV8iw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/339e932bd7aa11dde6e00ff5ade2770283c24eb8b6c9bfc7311ebdf9b00d3f5c72102535ef6eeb1ec337113ecb984442de49f9bc1371ba57c49264aa59abd3d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7db9943bb739ef4122a4085cef91d53667763710813de8fdc60e4e6cd8551d7f7c924c4060d189c013a950e94e4d6d1fdf1f2bbd4ab95bee443055096e8dae58", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b98da0494b14c596af22dccf502db2811b298b59f2f55a4f2fe6275b8ce62a6ce5a1dcf3ed44f33bd6911412235e281e791bddcd3ade2ca124eddefba306619"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:00.779687) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:00.780032) ========= '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-014 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-014 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-014 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.100000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:00.889490) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:00.889781) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-29 20:40:01.343222) ========= ========= Total master_lag: 0.50 =========