builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-146 starttime: 1478334147.15 results: success (0) buildid: 20161104223009 builduid: ea2d9ab8cd6e4a629a59797116b6ea40 revision: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:27.154486) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:27.155012) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:27.155327) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:27.291409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 01:22:27.291837) ========= '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-146 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-146 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-146 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 --01:22:28-- 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% 6.96 MB/s 01:22:28 (6.96 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.451000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 01:22:28.774049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-11-05 01:22:28.774374) ========= '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-146 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-146 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-146 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=13.504000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 13 secs) (at 2016-11-05 01:22:42.288222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 01:22:42.288601) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd --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 a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-146 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-146 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-146 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-05 01:22:42,592 truncating revision to first 12 chars 2016-11-05 01:22:42,592 Setting DEBUG logging. 2016-11-05 01:22:42,592 attempt 1/10 2016-11-05 01:22:42,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a7c654513f2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 01:22:43,239 unpacking tar archive at: mozilla-central-a7c654513f2f/testing/mozharness/ 2016-11-05 01:22:43,421 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.468000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 01:22:43.810313) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:43.810861) ========= script_repo_revision: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:43.811461) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:43.811882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 01:22:43.823353) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 35 secs) (at 2016-11-05 01:22:43.824001) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' 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', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-146 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-146 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-146 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 01:22:44 INFO - MultiFileLogger online at 20161105 01:22:44 in C:\slave\test 01:22:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:22:44 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 01:22:44 INFO - {'append_to_log': False, 01:22:44 INFO - 'base_work_dir': 'C:\\slave\\test', 01:22:44 INFO - 'blob_upload_branch': 'Firefox', 01:22:44 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 01:22:44 INFO - 'branch': 'Firefox', 01:22:44 INFO - 'buildbot_json_path': 'buildprops.json', 01:22:44 INFO - 'config_files': ('talos/windows_config.py',), 01:22:44 INFO - 'default_actions': ('clobber', 01:22:44 INFO - 'read-buildbot-config', 01:22:44 INFO - 'download-and-extract', 01:22:44 INFO - 'populate-webroot', 01:22:44 INFO - 'create-virtualenv', 01:22:44 INFO - 'install', 01:22:44 INFO - 'run-tests'), 01:22:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:22:44 INFO - 'download_minidump_stackwalk': True, 01:22:44 INFO - 'download_symbols': 'ondemand', 01:22:44 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 01:22:44 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 01:22:44 INFO - 'hg': 'c:/mozilla-build/hg/hg', 01:22:44 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 01:22:44 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 01:22:44 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 01:22:44 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 01:22:44 INFO - 'C:/mozilla-build/tooltool.py'), 01:22:44 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 01:22:44 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 01:22:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:22:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:22:44 INFO - 'installer_path': 'installer.exe', 01:22:44 INFO - 'log_level': 'info', 01:22:44 INFO - 'log_name': 'talos', 01:22:44 INFO - 'log_to_console': True, 01:22:44 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 01:22:44 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 01:22:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 01:22:44 INFO - 'opt_config_files': (), 01:22:44 INFO - 'pip_index': False, 01:22:44 INFO - 'sps_profile': False, 01:22:44 INFO - 'sps_profile_interval': 0, 01:22:44 INFO - 'suite': 'dromaeojs', 01:22:44 INFO - 'system_bits': '32', 01:22:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:22:44 INFO - 'title': 'T-W732-IX-146', 01:22:44 INFO - 'use_talos_json': True, 01:22:44 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 01:22:44 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 01:22:44 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 01:22:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:22:44 INFO - 'work_dir': 'build'} 01:22:44 INFO - [mozharness: 2016-11-05 08:22:44.649000Z] Running clobber step. 01:22:44 INFO - Running pre-action listener: _resource_record_pre_action 01:22:44 INFO - Running main action method: clobber 01:22:44 INFO - rmtree: C:\slave\test\build 01:22:44 INFO - Using _rmtree_windows ... 01:22:44 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 01:25:33 INFO - Running post-action listener: _resource_record_post_action 01:25:33 INFO - [mozharness: 2016-11-05 08:25:33.286000Z] Finished clobber step (success) 01:25:33 INFO - [mozharness: 2016-11-05 08:25:33.287000Z] Running read-buildbot-config step. 01:25:33 INFO - Running pre-action listener: _resource_record_pre_action 01:25:33 INFO - Running main action method: read_buildbot_config 01:25:33 INFO - Using buildbot properties: 01:25:33 INFO - { 01:25:33 INFO - "project": "", 01:25:33 INFO - "product": "firefox", 01:25:33 INFO - "script_repo_revision": "production", 01:25:33 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 01:25:33 INFO - "repository": "", 01:25:33 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 01:25:33 INFO - "buildid": "20161104223009", 01:25:33 INFO - "pgo_build": "True", 01:25:33 INFO - "basedir": "C:\\slave\\test", 01:25:33 INFO - "buildnumber": 70, 01:25:33 INFO - "slavename": "t-w732-ix-146", 01:25:33 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 01:25:33 INFO - "platform": "win7_ix", 01:25:33 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 01:25:33 INFO - "branch": "mozilla-central", 01:25:33 INFO - "repo_path": "mozilla-central", 01:25:33 INFO - "revision": "a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd", 01:25:33 INFO - "stage_platform": "win32-pgo", 01:25:33 INFO - "builduid": "ea2d9ab8cd6e4a629a59797116b6ea40", 01:25:33 INFO - "slavebuilddir": "test" 01:25:33 INFO - } 01:25:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 01:25:33 INFO - Running post-action listener: _resource_record_post_action 01:25:33 INFO - [mozharness: 2016-11-05 08:25:33.290000Z] Finished read-buildbot-config step (success) 01:25:33 INFO - [mozharness: 2016-11-05 08:25:33.290000Z] Running download-and-extract step. 01:25:33 INFO - Running pre-action listener: _resource_record_pre_action 01:25:33 INFO - Running main action method: download_and_extract 01:25:33 INFO - mkdir: C:\slave\test\build\tests 01:25:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:25:33 INFO - trying https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 01:25:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 01:25:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 01:25:34 INFO - Downloaded 1218 bytes. 01:25:34 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 01:25:34 INFO - Using the following test package requirements: 01:25:34 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:25:34 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 01:25:34 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:25:34 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'jsshell-win32.zip'], 01:25:34 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 01:25:34 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:25:34 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 01:25:34 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 01:25:34 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 01:25:34 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:25:34 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 01:25:34 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 01:25:34 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:25:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:25:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:25:35 INFO - Http code: 200 01:25:35 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 01:25:35 INFO - x-amz-cf-id: dIQJhbitKbOqjkDRCIzmGuRzhX7Yohaxpke4FmRLWmE6EEQ1MZz8ZA== 01:25:35 INFO - x-amz-version-id: wcDhUqXeOt59GpPEijDjy7ppo2McRykv 01:25:35 INFO - Expected file size: 17054171 01:25:35 INFO - Obtained file size: 17054171 01:26:03 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 01:26:03 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:26:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:26:03 INFO - Fetch https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:26:03 INFO - Http code: 200 01:26:03 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 01:26:03 INFO - x-amz-cf-id: 9n4LxoDhJfffCBpgPoruQd7rDg7Tv1NQCpce8BLbDSnhblFB_X8ASw== 01:26:03 INFO - x-amz-version-id: wcDhUqXeOt59GpPEijDjy7ppo2McRykv 01:26:04 INFO - Expected file size: 17054171 01:26:04 INFO - Obtained file size: 17054171 01:26:16 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 01:26:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 01:26:16 INFO - Fetch https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 01:26:16 INFO - Http code: 200 01:26:16 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 01:26:16 INFO - x-amz-cf-id: ckd2XPCsuNstCKKTW1u-UleA4qMrn9xb63X0l8XwgIWs-6iWH-EesQ== 01:26:16 INFO - x-amz-version-id: c8ZbvrbeLMx9NDtpIAqq1byTnZa3m5tt 01:26:17 INFO - Expected file size: 14409038 01:26:17 INFO - Obtained file size: 14409038 01:26:19 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')]} 01:26:19 INFO - trying https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:26:19 INFO - Downloading https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 01:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 01:26:20 INFO - Downloaded 54260097 bytes. 01:26:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:26:20 INFO - mkdir: C:\slave\test\properties 01:26:20 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 01:26:20 INFO - Writing to file C:\slave\test\properties\build_url 01:26:20 INFO - Contents: 01:26:20 INFO - build_url:https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:26:21 INFO - Running post-action listener: _resource_record_post_action 01:26:21 INFO - Running post-action listener: set_extra_try_arguments 01:26:21 INFO - [mozharness: 2016-11-05 08:26:21.598000Z] Finished download-and-extract step (success) 01:26:21 INFO - [mozharness: 2016-11-05 08:26:21.598000Z] Running populate-webroot step. 01:26:21 INFO - Running pre-action listener: _resource_record_pre_action 01:26:21 INFO - Running main action method: populate_webroot 01:26:21 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 01:26:21 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 01:26:21 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 01:26:21 INFO - u'--tppagecycles', 01:26:21 INFO - u'7'], 01:26:21 INFO - u'tests': [u'tsvgm']}}, 01:26:21 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 01:26:21 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 01:26:21 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 01:26:21 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 01:26:21 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:26:21 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:26:21 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'damp', u'tps']}, 01:26:21 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'tests': [u'damp', u'tps']}, 01:26:21 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'dromaeo_dom']}, 01:26:21 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 01:26:21 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'basic_compositor_video']}, 01:26:21 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 01:26:21 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'other-e10s': {u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'a11yr', 01:26:21 INFO - u'ts_paint', 01:26:21 INFO - u'tpaint', 01:26:21 INFO - u'sessionrestore', 01:26:21 INFO - u'sessionrestore_no_auto_restore', 01:26:21 INFO - u'tabpaint']}, 01:26:21 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'tsvgx', 01:26:21 INFO - u'tsvgr_opacity', 01:26:21 INFO - u'tart', 01:26:21 INFO - u'tscrollx', 01:26:21 INFO - u'cart']}, 01:26:21 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 01:26:21 INFO - u'tsvgr_opacity', 01:26:21 INFO - u'tart', 01:26:21 INFO - u'tscrollx', 01:26:21 INFO - u'cart']}, 01:26:21 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'talos_options': [u'--disable-e10s'], 01:26:21 INFO - u'tests': [u'tp5o']}, 01:26:21 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'tests': [u'tp5o']}, 01:26:21 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'talos_options': [u'--disable-e10s', 01:26:21 INFO - u'--xperf_path', 01:26:21 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:21 INFO - u'tests': [u'tp5n']}, 01:26:21 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:21 INFO - u'talos_options': [u'--xperf_path', 01:26:21 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:21 INFO - u'tests': [u'tp5n']}}, 01:26:21 INFO - u'talos.zip': {u'path': u'', 01:26:21 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:21 INFO - Running post-action listener: _resource_record_post_action 01:26:21 INFO - [mozharness: 2016-11-05 08:26:21.613000Z] Finished populate-webroot step (success) 01:26:21 INFO - [mozharness: 2016-11-05 08:26:21.613000Z] Running create-virtualenv step. 01:26:21 INFO - Running pre-action listener: _resource_record_pre_action 01:26:21 INFO - Running main action method: create_virtualenv 01:26:21 INFO - Creating virtualenv C:\slave\test\build/venv 01:26:21 INFO - mkdir: C:\slave\test\build\venv\Scripts 01:26:22 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 01:26:22 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'! 01:26:22 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 01:26:22 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 01:26:34 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 01:26:38 INFO - Installing distribute......................................................................................................................................................................................done. 01:26:40 INFO - Return code: 0 01:26:40 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 01:26:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 01:26:40 INFO - Reading from file tmpfile_stdout 01:26:40 INFO - Output received: 01:26:40 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 01:26:40 INFO - Using _rmtree_windows ... 01:26:40 INFO - Using _rmtree_windows ... 01:26:40 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 01:26:40 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')]} 01:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:40 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')]} 01:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:40 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:40 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 01:26:40 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 01:26:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:40 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:40 INFO - 'DCLOCATION': 'SCL3', 01:26:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:40 INFO - 'HOMEDRIVE': 'C:', 01:26:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:40 INFO - 'KTS_VERSION': '1.19c', 01:26:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:40 INFO - 'MOZ_NO_REMOTE': '1', 01:26:40 INFO - 'NO_EM_RESTART': '1', 01:26:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:40 INFO - 'OS': 'Windows_NT', 01:26:40 INFO - 'OURDRIVE': 'C:', 01:26:40 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;', 01:26:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:40 INFO - 'PROCESSOR_LEVEL': '6', 01:26:40 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:40 INFO - 'PROMPT': '$P$G', 01:26:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:40 INFO - 'PWD': 'C:\\slave\\test', 01:26:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:40 INFO - 'SYSTEMDRIVE': 'C:', 01:26:40 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:40 INFO - 'TEST1': 'testie', 01:26:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:40 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:40 INFO - 'USERNAME': 'cltbld', 01:26:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:40 INFO - 'WINDIR': 'C:\\windows', 01:26:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:26:41 INFO - Downloading/unpacking pip>=1.5 01:26:45 INFO - Running setup.py egg_info for package pip 01:26:45 INFO - warning: no files found matching 'pip\cacert.pem' 01:26:45 INFO - warning: no files found matching '*.html' under directory 'docs' 01:26:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:26:45 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:26:45 INFO - Installing collected packages: pip 01:26:45 INFO - Found existing installation: pip 0.8.2 01:26:45 INFO - Uninstalling pip: 01:26:45 INFO - Successfully uninstalled pip 01:26:45 INFO - Running setup.py install for pip 01:26:45 INFO - warning: no files found matching 'pip\cacert.pem' 01:26:45 INFO - warning: no files found matching '*.html' under directory 'docs' 01:26:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:26:45 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:26:45 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 01:26:45 INFO - Successfully installed pip 01:26:45 INFO - Cleaning up... 01:26:45 INFO - Return code: 0 01:26:45 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 01:26:45 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')]} 01:26:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:45 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')]} 01:26:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:45 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:45 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 01:26:45 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 01:26:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:45 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:45 INFO - 'DCLOCATION': 'SCL3', 01:26:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:45 INFO - 'HOMEDRIVE': 'C:', 01:26:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:45 INFO - 'KTS_VERSION': '1.19c', 01:26:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:45 INFO - 'MOZ_NO_REMOTE': '1', 01:26:45 INFO - 'NO_EM_RESTART': '1', 01:26:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:45 INFO - 'OS': 'Windows_NT', 01:26:45 INFO - 'OURDRIVE': 'C:', 01:26:45 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;', 01:26:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:45 INFO - 'PROCESSOR_LEVEL': '6', 01:26:45 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:45 INFO - 'PROMPT': '$P$G', 01:26:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:45 INFO - 'PWD': 'C:\\slave\\test', 01:26:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:45 INFO - 'SYSTEMDRIVE': 'C:', 01:26:45 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:45 INFO - 'TEST1': 'testie', 01:26:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:45 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:45 INFO - 'USERNAME': 'cltbld', 01:26:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:45 INFO - 'WINDIR': 'C:\\windows', 01:26:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:47 INFO - Downloading/unpacking psutil>=3.1.1 01:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:49 INFO - Installing collected packages: psutil 01:26:49 INFO - Successfully installed psutil 01:26:49 INFO - Cleaning up... 01:26:49 INFO - Return code: 0 01:26:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 01:26: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')]} 01:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26: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')]} 01:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:49 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:49 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 01:26:49 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 01:26:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:49 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:49 INFO - 'DCLOCATION': 'SCL3', 01:26:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:49 INFO - 'HOMEDRIVE': 'C:', 01:26:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:49 INFO - 'KTS_VERSION': '1.19c', 01:26:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:49 INFO - 'MOZ_NO_REMOTE': '1', 01:26:49 INFO - 'NO_EM_RESTART': '1', 01:26:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:49 INFO - 'OS': 'Windows_NT', 01:26:49 INFO - 'OURDRIVE': 'C:', 01:26:49 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;', 01:26:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:49 INFO - 'PROCESSOR_LEVEL': '6', 01:26:49 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:49 INFO - 'PROMPT': '$P$G', 01:26:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:49 INFO - 'PWD': 'C:\\slave\\test', 01:26:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:49 INFO - 'SYSTEMDRIVE': 'C:', 01:26:49 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:49 INFO - 'TEST1': 'testie', 01:26:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:49 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:49 INFO - 'USERNAME': 'cltbld', 01:26:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:49 INFO - 'WINDIR': 'C:\\windows', 01:26:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:26:49 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 01:26:49 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 01:26:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:26:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 01:26:50 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) 01:26:50 INFO - Installing collected packages: mozsystemmonitor 01:26:50 INFO - Running setup.py install for mozsystemmonitor 01:26:50 INFO - Successfully installed mozsystemmonitor 01:26:50 INFO - Cleaning up... 01:26:51 INFO - Return code: 0 01:26:51 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 01:26: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')]} 01:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26: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')]} 01:26:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:51 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:51 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 01:26:51 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 01:26:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:51 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:51 INFO - 'DCLOCATION': 'SCL3', 01:26:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:51 INFO - 'HOMEDRIVE': 'C:', 01:26:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:51 INFO - 'KTS_VERSION': '1.19c', 01:26:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:51 INFO - 'MOZ_NO_REMOTE': '1', 01:26:51 INFO - 'NO_EM_RESTART': '1', 01:26:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:51 INFO - 'OS': 'Windows_NT', 01:26:51 INFO - 'OURDRIVE': 'C:', 01:26:51 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;', 01:26:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:51 INFO - 'PROCESSOR_LEVEL': '6', 01:26:51 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:51 INFO - 'PROMPT': '$P$G', 01:26:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:51 INFO - 'PWD': 'C:\\slave\\test', 01:26:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:51 INFO - 'SYSTEMDRIVE': 'C:', 01:26:51 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:51 INFO - 'TEST1': 'testie', 01:26:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:51 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:51 INFO - 'USERNAME': 'cltbld', 01:26:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:51 INFO - 'WINDIR': 'C:\\windows', 01:26:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:51 INFO - Downloading/unpacking jsonschema==2.5.1 01:26:51 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 01:26:51 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 01:26:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:26:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:26:52 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 01:26:52 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 01:26:52 INFO - Downloading functools32-3.2.3-2.tar.gz 01:26:52 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 01:26:52 INFO - warning: no files found matching '*.txt' 01:26:52 INFO - no previously-included directories found matching 'build' 01:26:52 INFO - no previously-included directories found matching 'dist' 01:26:52 INFO - no previously-included directories found matching '.git*' 01:26:52 INFO - Installing collected packages: jsonschema, functools32 01:26:52 INFO - Running setup.py install for functools32 01:26:52 INFO - warning: no files found matching '*.txt' 01:26:52 INFO - no previously-included directories found matching 'build' 01:26:52 INFO - no previously-included directories found matching 'dist' 01:26:52 INFO - no previously-included directories found matching '.git*' 01:26:52 INFO - Successfully installed jsonschema functools32 01:26:52 INFO - Cleaning up... 01:26:53 INFO - Return code: 0 01:26:53 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:53 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:53 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 01:26:53 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 01:26:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:53 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:53 INFO - 'DCLOCATION': 'SCL3', 01:26:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:53 INFO - 'HOMEDRIVE': 'C:', 01:26:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:53 INFO - 'KTS_VERSION': '1.19c', 01:26:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:53 INFO - 'MOZ_NO_REMOTE': '1', 01:26:53 INFO - 'NO_EM_RESTART': '1', 01:26:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:53 INFO - 'OS': 'Windows_NT', 01:26:53 INFO - 'OURDRIVE': 'C:', 01:26: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;', 01:26:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:53 INFO - 'PROCESSOR_LEVEL': '6', 01:26:53 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:53 INFO - 'PROMPT': '$P$G', 01:26:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:53 INFO - 'PWD': 'C:\\slave\\test', 01:26:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:53 INFO - 'SYSTEMDRIVE': 'C:', 01:26:53 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:53 INFO - 'TEST1': 'testie', 01:26:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:53 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:53 INFO - 'USERNAME': 'cltbld', 01:26:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:53 INFO - 'WINDIR': 'C:\\windows', 01:26:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 01:26:53 INFO - Cleaning up... 01:26:53 INFO - Return code: 0 01:26:53 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:53 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:53 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 01:26:53 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 01:26:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:53 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:53 INFO - 'DCLOCATION': 'SCL3', 01:26:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:53 INFO - 'HOMEDRIVE': 'C:', 01:26:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:53 INFO - 'KTS_VERSION': '1.19c', 01:26:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:53 INFO - 'MOZ_NO_REMOTE': '1', 01:26:53 INFO - 'NO_EM_RESTART': '1', 01:26:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:53 INFO - 'OS': 'Windows_NT', 01:26:53 INFO - 'OURDRIVE': 'C:', 01:26: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;', 01:26:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:53 INFO - 'PROCESSOR_LEVEL': '6', 01:26:53 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:53 INFO - 'PROMPT': '$P$G', 01:26:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:53 INFO - 'PWD': 'C:\\slave\\test', 01:26:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:53 INFO - 'SYSTEMDRIVE': 'C:', 01:26:53 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:53 INFO - 'TEST1': 'testie', 01:26:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:53 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:53 INFO - 'USERNAME': 'cltbld', 01:26:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:53 INFO - 'WINDIR': 'C:\\windows', 01:26:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:54 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:54 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 01:26:54 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 01:26:55 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 01:26:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:55 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 01:26:55 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 01:26:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 01:26:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26:55 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 01:26:55 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 01:26:55 INFO - Downloading docopt-0.6.1.tar.gz 01:26:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 01:26:56 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:56 INFO - Running setup.py install for blobuploader 01:26:56 INFO - Running setup.py install for requests 01:26:57 INFO - Running setup.py install for docopt 01:26:57 INFO - Successfully installed blobuploader requests docopt 01:26:57 INFO - Cleaning up... 01:26:58 INFO - Return code: 0 01:26:58 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:26:58 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')]} 01:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:58 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')]} 01:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:58 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:26:58 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 01:26:58 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 01:26:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:26:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:26:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:26:58 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:26:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:26:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:26:58 INFO - 'DCLOCATION': 'SCL3', 01:26:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:26:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:26:58 INFO - 'HOMEDRIVE': 'C:', 01:26:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:26:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:26:58 INFO - 'KTS_VERSION': '1.19c', 01:26:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:26:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:26:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:26:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:58 INFO - 'MOZ_NO_REMOTE': '1', 01:26:58 INFO - 'NO_EM_RESTART': '1', 01:26:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:26:58 INFO - 'OS': 'Windows_NT', 01:26:58 INFO - 'OURDRIVE': 'C:', 01:26:58 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;', 01:26:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:26:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:26:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:26:58 INFO - 'PROCESSOR_LEVEL': '6', 01:26:58 INFO - 'PROCESSOR_REVISION': '1e05', 01:26:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:26:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:26:58 INFO - 'PROMPT': '$P$G', 01:26:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:26:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:26:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:26:58 INFO - 'PWD': 'C:\\slave\\test', 01:26:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:26:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:26:58 INFO - 'SYSTEMDRIVE': 'C:', 01:26:58 INFO - 'SYSTEMROOT': 'C:\\windows', 01:26:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:58 INFO - 'TEST1': 'testie', 01:26:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:26:58 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:26:58 INFO - 'USERNAME': 'cltbld', 01:26:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:26:58 INFO - 'WINDIR': 'C:\\windows', 01:26:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:26:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:26:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bdya15-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:26:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:26:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8x857g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ekvpoz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-luu_pv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kqdk_p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-us_jsv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7q3tzn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dyhm5w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:26:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dxhfob-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:26:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:27:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-quv9ri-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:27:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:27:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cwpeeb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:27:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ez5fog-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nahkgn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ynfkft-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k7rkql-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jkpzra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:27:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:27:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kd2kud-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:27:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:27:01 INFO - Running setup.py install for manifestparser 01:27:01 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 01:27:01 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 01:27:02 INFO - Running setup.py install for mozcrash 01:27:02 INFO - Running setup.py install for mozdebug 01:27:03 INFO - Running setup.py install for mozdevice 01:27:03 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 01:27:03 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 01:27:03 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 01:27:03 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 01:27:03 INFO - Running setup.py install for mozfile 01:27:04 INFO - Running setup.py install for mozhttpd 01:27:04 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 01:27:04 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 01:27:04 INFO - Running setup.py install for mozinfo 01:27:04 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 01:27:04 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 01:27:04 INFO - Running setup.py install for mozInstall 01:27:05 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Running setup.py install for mozleak 01:27:05 INFO - Running setup.py install for mozlog 01:27:05 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 01:27:05 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 01:27:06 INFO - Running setup.py install for moznetwork 01:27:06 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 01:27:06 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 01:27:06 INFO - Running setup.py install for mozprocess 01:27:07 INFO - Running setup.py install for mozprofile 01:27:07 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 01:27:07 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 01:27:07 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 01:27:07 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 01:27:07 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 01:27:07 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 01:27:08 INFO - Running setup.py install for mozrunner 01:27:08 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 01:27:08 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 01:27:08 INFO - Running setup.py install for mozscreenshot 01:27:09 INFO - Running setup.py install for moztest 01:27:09 INFO - Running setup.py install for mozversion 01:27:09 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 01:27:09 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 01:27:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:10 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:27:10 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 01:27:10 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 01:27:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:27:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:27:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:27:10 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:27:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:27:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:27:10 INFO - 'DCLOCATION': 'SCL3', 01:27:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:27:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:27:10 INFO - 'HOMEDRIVE': 'C:', 01:27:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:27:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:27:10 INFO - 'KTS_VERSION': '1.19c', 01:27:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:27:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:27:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:27:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:10 INFO - 'MOZ_NO_REMOTE': '1', 01:27:10 INFO - 'NO_EM_RESTART': '1', 01:27:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:27:10 INFO - 'OS': 'Windows_NT', 01:27:10 INFO - 'OURDRIVE': 'C:', 01:27:10 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:27:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:27:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:27:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:27:10 INFO - 'PROCESSOR_LEVEL': '6', 01:27:10 INFO - 'PROCESSOR_REVISION': '1e05', 01:27:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:27:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:27:10 INFO - 'PROMPT': '$P$G', 01:27:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:27:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:27:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:27:10 INFO - 'PWD': 'C:\\slave\\test', 01:27:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:27:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:27:10 INFO - 'SYSTEMDRIVE': 'C:', 01:27:10 INFO - 'SYSTEMROOT': 'C:\\windows', 01:27:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:10 INFO - 'TEST1': 'testie', 01:27:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:10 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:27:10 INFO - 'USERNAME': 'cltbld', 01:27:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:27:10 INFO - 'WINDIR': 'C:\\windows', 01:27:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:27:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:27:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqt_bm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5v9erk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s2azzb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydab1m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:27:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8vyn7k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iajfmx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-twcixc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kpnowv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:27:11 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)) 01:27:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:27:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gckhvp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:27:12 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)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-htfxg6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:27:12 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)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jwhzdz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:27:12 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)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ul0l1a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:27:12 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)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqnotz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:27:12 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)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x7b8tt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:27:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 01:27:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:27:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pxb4xw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:27:13 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)) 01:27:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:27:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nkxrdt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:27:13 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)) 01:27:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:27:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gibswi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:27:13 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)) 01:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 01:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 01:27:13 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)) 01:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 01:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 01:27:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 01:27:13 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 01:27:13 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 01:27:14 INFO - Downloading blessings-1.6.tar.gz 01:27:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 01:27:14 INFO - Installing collected packages: blessings 01:27:14 INFO - Running setup.py install for blessings 01:27:15 INFO - Successfully installed blessings 01:27:15 INFO - Cleaning up... 01:27:18 INFO - Return code: 0 01:27:18 INFO - Done creating virtualenv C:\slave\test\build/venv. 01:27:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:27:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:27:18 INFO - Reading from file tmpfile_stdout 01:27:18 INFO - Using _rmtree_windows ... 01:27:18 INFO - Using _rmtree_windows ... 01:27:18 INFO - Current package versions: 01:27:18 INFO - blessings == 1.6 01:27:18 INFO - blobuploader == 1.2.4 01:27:18 INFO - docopt == 0.6.1 01:27:18 INFO - functools32 == 3.2.3-2 01:27:18 INFO - jsonschema == 2.5.1 01:27:18 INFO - manifestparser == 1.1 01:27:18 INFO - mozInstall == 1.12 01:27:18 INFO - mozcrash == 0.17 01:27:18 INFO - mozdebug == 0.1 01:27:18 INFO - mozdevice == 0.48 01:27:18 INFO - mozfile == 1.2 01:27:18 INFO - mozhttpd == 0.7 01:27:18 INFO - mozinfo == 0.9 01:27:18 INFO - mozleak == 0.1 01:27:18 INFO - mozlog == 3.4 01:27:18 INFO - moznetwork == 0.27 01:27:18 INFO - mozprocess == 0.23 01:27:18 INFO - mozprofile == 0.28 01:27:18 INFO - mozrunner == 6.12 01:27:18 INFO - mozscreenshot == 0.1 01:27:18 INFO - mozsystemmonitor == 0.3 01:27:18 INFO - moztest == 0.7 01:27:18 INFO - mozversion == 1.4 01:27:18 INFO - psutil == 3.1.1 01:27:18 INFO - requests == 1.2.3 01:27:18 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:27: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')]} 01:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27: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')]} 01:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:18 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:27:18 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 01:27:18 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 01:27:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:27:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:27:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:27:18 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:27:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:27:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:27:18 INFO - 'DCLOCATION': 'SCL3', 01:27:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:27:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:27:18 INFO - 'HOMEDRIVE': 'C:', 01:27:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:27:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:27:18 INFO - 'KTS_VERSION': '1.19c', 01:27:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:27:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:27:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:27:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:18 INFO - 'MOZ_NO_REMOTE': '1', 01:27:18 INFO - 'NO_EM_RESTART': '1', 01:27:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:27:18 INFO - 'OS': 'Windows_NT', 01:27:18 INFO - 'OURDRIVE': 'C:', 01:27: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;', 01:27:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:27:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:27:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:27:18 INFO - 'PROCESSOR_LEVEL': '6', 01:27:18 INFO - 'PROCESSOR_REVISION': '1e05', 01:27:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:27:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:27:18 INFO - 'PROMPT': '$P$G', 01:27:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:27:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:27:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:27:18 INFO - 'PWD': 'C:\\slave\\test', 01:27:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:27:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:27:18 INFO - 'SYSTEMDRIVE': 'C:', 01:27:18 INFO - 'SYSTEMROOT': 'C:\\windows', 01:27:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:18 INFO - 'TEST1': 'testie', 01:27:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:18 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:27:18 INFO - 'USERNAME': 'cltbld', 01:27:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:27:18 INFO - 'WINDIR': 'C:\\windows', 01:27:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:27:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 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)) 01:27:18 INFO - Cleaning up... 01:27:18 INFO - Return code: 0 01:27:18 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 01:27: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')]} 01:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27: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')]} 01:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01909E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', '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 01:27: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', 'jsonschema'] in C:\slave\test\build 01:27: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 jsonschema 01:27:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:27:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:27:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:27:18 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:27:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:27:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:27:18 INFO - 'DCLOCATION': 'SCL3', 01:27:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:27:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:27:18 INFO - 'HOMEDRIVE': 'C:', 01:27:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:27:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:27:18 INFO - 'KTS_VERSION': '1.19c', 01:27:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:27:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:27:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:27:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:18 INFO - 'MOZ_NO_REMOTE': '1', 01:27:18 INFO - 'NO_EM_RESTART': '1', 01:27:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:27:18 INFO - 'OS': 'Windows_NT', 01:27:18 INFO - 'OURDRIVE': 'C:', 01:27: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;', 01:27:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:27:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:27:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:27:18 INFO - 'PROCESSOR_LEVEL': '6', 01:27:18 INFO - 'PROCESSOR_REVISION': '1e05', 01:27:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:27:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:27:18 INFO - 'PROMPT': '$P$G', 01:27:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:27:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:27:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:27:18 INFO - 'PWD': 'C:\\slave\\test', 01:27:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:27:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:27:18 INFO - 'SYSTEMDRIVE': 'C:', 01:27:18 INFO - 'SYSTEMROOT': 'C:\\windows', 01:27:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:18 INFO - 'TEST1': 'testie', 01:27:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:18 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:27:18 INFO - 'USERNAME': 'cltbld', 01:27:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:27:18 INFO - 'WINDIR': 'C:\\windows', 01:27:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:27:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 01:27:19 INFO - Cleaning up... 01:27:19 INFO - Return code: 0 01:27:19 INFO - Running post-action listener: _resource_record_post_action 01:27:19 INFO - Running post-action listener: _start_resource_monitoring 01:27:19 INFO - Starting resource monitoring. 01:27:19 INFO - [mozharness: 2016-11-05 08:27:19.423000Z] Finished create-virtualenv step (success) 01:27:19 INFO - [mozharness: 2016-11-05 08:27:19.423000Z] Running install step. 01:27:19 INFO - Running pre-action listener: _resource_record_pre_action 01:27:19 INFO - Running main action method: install 01:27:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:27:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:27:19 INFO - Reading from file tmpfile_stdout 01:27:19 INFO - Using _rmtree_windows ... 01:27:19 INFO - Using _rmtree_windows ... 01:27:19 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:19 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 01:27:19 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 01:27:19 INFO - Reading from file tmpfile_stdout 01:27:19 INFO - Output received: 01:27:19 INFO - Usage: mozinstall-script.py [options] installer 01:27:19 INFO - Options: 01:27:19 INFO - -h, --help show this help message and exit 01:27:19 INFO - -d DEST, --destination=DEST 01:27:19 INFO - Directory to install application into. [default: 01:27:19 INFO - "C:\slave\test"] 01:27:19 INFO - --app=APP Application being installed. [default: firefox] 01:27:19 INFO - Using _rmtree_windows ... 01:27:19 INFO - Using _rmtree_windows ... 01:27:19 INFO - mkdir: C:\slave\test\build\application 01:27:19 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 01:27:19 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 01:27:21 INFO - Reading from file tmpfile_stdout 01:27:21 INFO - Output received: 01:27:21 INFO - C:\slave\test\build\application\firefox\firefox.exe 01:27:21 INFO - Using _rmtree_windows ... 01:27:21 INFO - Using _rmtree_windows ... 01:27:21 INFO - Running post-action listener: _resource_record_post_action 01:27:21 INFO - [mozharness: 2016-11-05 08:27:21.920000Z] Finished install step (success) 01:27:21 INFO - [mozharness: 2016-11-05 08:27:21.920000Z] Running run-tests step. 01:27:21 INFO - Running pre-action listener: _resource_record_pre_action 01:27:21 INFO - Running main action method: run_tests 01:27:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 01:27:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 01:27:21 INFO - Python 2.7.3 01:27:21 INFO - Return code: 0 01:27:21 INFO - grabbing minidump binary from tooltool 01:27:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:21 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01928E90>, '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 0x0132A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019DE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 01:27:21 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 01:27:21 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 01:27:21 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 01:27:22 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 01:27:22 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpy3pkgk 01:27:22 INFO - INFO - File integrity verified, renaming tmpy3pkgk to win32-minidump_stackwalk.exe 01:27:22 INFO - Return code: 0 01:27:22 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 01:27:22 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 01:27:22 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 01:27:22 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 01:27:22 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 01:27:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-146', '--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 01:27:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-146 --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 01:27:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:27:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:27:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:27:22 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 01:27:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:27:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:27:22 INFO - 'DCLOCATION': 'SCL3', 01:27:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:27:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:27:22 INFO - 'HOMEDRIVE': 'C:', 01:27:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:27:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:27:22 INFO - 'KTS_VERSION': '1.19c', 01:27:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:27:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 01:27:22 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:27:22 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 01:27:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:27:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:22 INFO - 'MOZ_NO_REMOTE': '1', 01:27:22 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:27:22 INFO - 'NO_EM_RESTART': '1', 01:27:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:27:22 INFO - 'OS': 'Windows_NT', 01:27:22 INFO - 'OURDRIVE': 'C:', 01:27:22 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;', 01:27:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:27:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:27:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:27:22 INFO - 'PROCESSOR_LEVEL': '6', 01:27:22 INFO - 'PROCESSOR_REVISION': '1e05', 01:27:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:27:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:27:22 INFO - 'PROMPT': '$P$G', 01:27:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:27:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:27:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:27:22 INFO - 'PWD': 'C:\\slave\\test', 01:27:22 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 01:27:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:27:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:27:22 INFO - 'SYSTEMDRIVE': 'C:', 01:27:22 INFO - 'SYSTEMROOT': 'C:\\windows', 01:27:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:22 INFO - 'TEST1': 'testie', 01:27:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:27:22 INFO - 'USERDOMAIN': 'T-W732-IX-146', 01:27:22 INFO - 'USERNAME': 'cltbld', 01:27:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:27:22 INFO - 'WINDIR': 'C:\\windows', 01:27:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:27:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:27:22 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-146', '--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 01:27:24 INFO - mozversion application_buildid: 20161104223009 01:27:24 INFO - mozversion application_changeset: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd 01:27:24 INFO - mozversion application_display_name: Nightly 01:27:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:24 INFO - mozversion application_name: Firefox 01:27:24 INFO - mozversion application_remotingname: firefox 01:27:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:27:24 INFO - mozversion application_vendor: Mozilla 01:27:24 INFO - mozversion application_version: 52.0a1 01:27:24 INFO - mozversion platform_buildid: 20161104223009 01:27:24 INFO - mozversion platform_changeset: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd 01:27:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:27:24 INFO - mozversion platform_version: 52.0a1 01:27:24 INFO - using testdate: 1478334444 01:27:24 INFO - actual date: 1478334444 01:27:24 INFO - starting webserver on 'localhost:49651' 01:27:24 INFO - SUITE-START | Running 2 tests 01:27:24 INFO - TEST-START | dromaeo_css 01:27:24 INFO - operating with platform_type : w7_ 01:27:24 INFO - Initialising browser for dromaeo_css test... 01:27:24 INFO - TEST-INFO | started process 2684 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp4dva4q\profile http://localhost:49651/getInfo.html) 01:27:31 INFO - PROCESS | 2684 | __metrics Screen width/height:1600/1200 01:27:31 INFO - PROCESS | 2684 | colorDepth:24 01:27:31 INFO - PROCESS | 2684 | Browser inner width/height: 1010/674 01:27:31 INFO - PROCESS | 2684 | __metrics 01:27:32 INFO - PROCESS | 2684 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:27:32 INFO - PROCESS | 2684 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:27:33 INFO - TEST-INFO | 2684: exit 0 01:27:33 INFO - Browser initialized. 01:27:33 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:33 INFO - TEST-INFO | started process 3968 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp4dva4q\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:33 INFO - PROCESS | 3968 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 01:27:44 INFO - PROCESS | 3968 | RSS: Main: 122519552 01:27:44 INFO - PROCESS | 3968 | 01:28:35 INFO - PROCESS | 3968 | 1478334515874 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] 01:30:46 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49651/tests/dromaeo/cssquery-ext.html) 01:30:48 INFO - PROCESS | 3968 | RSS: Main: 289222656 01:30:48 INFO - PROCESS | 3968 | 01:33:51 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-ext.html (next: http://localhost:49651/tests/dromaeo/cssquery-jquery.html) 01:33:51 INFO - PROCESS | 3968 | RSS: Main: 199303168 01:33:51 INFO - PROCESS | 3968 | 01:36:53 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49651/tests/dromaeo/cssquery-mootools.html) 01:36:54 INFO - PROCESS | 3968 | RSS: Main: 147091456 01:36:54 INFO - PROCESS | 3968 | 01:39:56 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49651/tests/dromaeo/cssquery-prototype.html) 01:39:57 INFO - PROCESS | 3968 | RSS: Main: 146948096 01:39:57 INFO - PROCESS | 3968 | 01:42:59 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49651/tests/dromaeo/cssquery-yui.html) 01:43:00 INFO - PROCESS | 3968 | RSS: Main: 136355840 01:43:00 INFO - PROCESS | 3968 | 01:46:02 INFO - PROCESS | 3968 | Cycle 1(1): loaded http://localhost:49651/tests/dromaeo/cssquery-yui.html (next: http://localhost:49651/tests/dromaeo/cssquery-dojo.html) 01:46:03 INFO - PROCESS | 3968 | RSS: Main: 228597760 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | __start_tp_report 01:46:03 INFO - PROCESS | 3968 | _x_x_mozilla_page_load 01:46:03 INFO - PROCESS | 3968 | _x_x_mozilla_page_load_details 01:46:03 INFO - PROCESS | 3968 | |i|pagename|runs| 01:46:03 INFO - PROCESS | 3968 | |0;dojo.html;2756.3798145417277;2759.296379423247;2789.274788554975;2796.091270337141;2804.929876753081;4685.742284174369;4693.178693728601;4767.38024056873;4773.8806529836775;4789.377380940477;4415.116976604684;4653.976730116345;4697.185270677665;4700.647451441138;4788.545088216622;3938.7439816411984;3945.9605403946025;4178.56125106864;4191.664666826662;4193.203291374629;3961.5246170459586;4077.1845630873795;4155.023569461174;4185.707000509966;4186.937195942048;4185.560516145806;4187.246295666779;4191.811368488426;4220.704550681454;4238.279492486269;4077.8164982575854;4161.313383291753;4167.083241686839;4245.80893859777;4261.382099595572;4043.7978101095123;4074.9592504074876;4095.85664501744;4179.059711564904;4217.514985776614;4607.124646317223;4636.976815115903;4701.341812146302;4720.551547602972;4756.66703330767;4729.763511824395;4942.456329803718;5033.370828646419;5035.571976382012;5039.143345631215;4896.828610998598;4969.900601987976;5018.272350509193;5107.846764597068;5119.948800512022;4579.977100114478;4599.747013914233;4607.331936869169;4682.531746825358;4693.906121877611;4803.471618122004;5054.317667114892;5147.40804807452;5153.227015947563;5154.896902061938;4732.929006064912;4829.589484893823;4846.466888642247;4941.703497790144;4946.579540739078;4771.570558649669;4893.535114164149;4922.384701912261;4922.8769280768265;4995.800167993312;4710.9764451177525;4872.9512704873205;4940.431850337257;4960.603151747851;4963.230699241623;4877.12211801879;4931.235658472942;4939.678920870132;4951;4954.702717836942;3283.9014829555153;3303.5044743288217;3306.140403495109;3333.116724068124;3352.6479719629574;2873.54023356262;2904.8838046478327;3090.42827076987;3118.7972781769113;3125.2186953261685;4669;4678.251479763221;4714.363560919232;4744.620430365562;4757.714537127784;4948.777305021283;4951.430585482643;5001.599872010231;5019.52314530119;5037.82367617139;4538.682292239578;4622.705642420127;4640.907181856344;4642.814287428465;4704.341392205094;3850.4224366345275;4016.979915100406;4165.291900376943;4167.791340511279;4191.622753952159;5000.374953130859;5081.8729531762;5085.135526960352;5137.8715532112;5242.1088414969545;4778.928316075192;4882.780274887567;4898.681585696918;4940.382452193476;5030.144875371268;4893.877653058702;4929.605631549539;4957.900841983068;5090.2364645303505;5101.132807422673;4736.336912832137;4767.094252092092;4843.685160464559;4848.539388758062;4856.757162141949;4897.706137631753;4929;4932.556069953722;4936.03747269279;4957.4051113865125;4828.227483602606;4829.058333624909;4836.056968891033;4838.298446725275;4848.10310092634;4805.303231031551;4827.589654879374;4835.734034628129;4839.467658557626;4849.927251091307;4582.1293954148605;4721.268203428474;4723.811047558059;4729.314249433744;4738.881527961828;4834.444038935427;4837.806487740591;4842.201036828884;4854.417469903634;4858.44127925279;4442.689011769275;4750.691205071797;4772.3557319761385;4810.759462026815;4815.831445899376 01:46:03 INFO - PROCESS | 3968 | |1;ext.html;534.8181618249795;542.1244689825932;572.7308165162373;576.7145263094772;577.3504807092022;27349.863250683742;35659.643403565955;36289.45565816511;36473.270534589356;37847.24305513888;35872.82063589685;36766.81616591913;37091.81454092733;37113.628863711354;37161.25677486449;6612.371824676648;6740.5618634788825;7458.254174582526;7461.552306861592;7626.656800443993;9114.58984345706;9316.301277404187;9322.300827437935;9343.159115679588;9525.761855953622;9422.199113075398;9482.33623646345;9486.525673716287;9489;9516.71449856502;6562.179727534059;6695.430888374494;9271.258299336105;9470.952645236765;9534.761630959247;9398.436093834323;9417.76455588609;9439.339246252764;9451.33840631156;9456.8581471278;32971.340573188405;34242.82878585616;34363;34543.65456345429;34560.30879382423;2717.6467059282168;2722.7005029446746;2903.2596687844675;3170.1440611035123;3224.435723748358;27368.178954631392;27634.309142271402;27720;28058.298542536395;28080.719192808014;3292.917677058069;3384.9661503384896;3396.9490457643155;3436.432988556885;3458.9308213835584;278610;279876;280424;280675.5966220176;280967;45454.31818522725;45663.77168114205;45809.083818323445;45812.5418745815;45908.311375329395;45887.31169032467;47244.29133562999;47738.522614774105;48022.279665804344;48106.75946620244;11122.721931951766;11207.887921120684;11293.378864162385;11579.710507237387;11614.477348519336;8515.829683406297;8572.957135214408;8622.655093796304;8743.868841967376;8770.4737715736;13581.592552223448;13742.106763060568;13816.309184540733;13914.443422263199;14033.1580105194;5517.144842549411;5551.861203469945;5579.7489112990015;5623.831285061373;5625.915611265835;738.4055835052834;808.5310519898445;809.8258874342044;876.0538618292229;881.8104107616765;25022.749772502408;25623.615645765327;26043.353930449754;26064.34839129037;26074.478510430064;836.2849763452282;909.2044461096541;946.0019679238469;970.1317320997667;971.1647982734843;13029.348532573524;13145.297703789363;13577.11748736329;13753.243571603458;13781.793273100911;398303;401248;401484;401642;402214;43145.56854431478;43261;44262.778686106365;44433.555664443586;44434.33348499775;942.5569982108398;959.9136077753036;1010.2978429991233;1012.0688966151158;1050.795094956476;25328.240152795446;26497;26745.7325426751;26813.731862681125;26911;38379.232415350976;38566.42150367691;38850;38959.220815582965;38996.415053774785;332577;333961;338278;557398;578916;35396.646033539335;36393;36487.08782280358;36763.26473470569;36984.63015369812;40154.799226003684;40334.143195657394;40379.798101009306;40480;41263.587364125975;32309.51535726919;34150;35095.29267578666;35877.46183807297;36145.27709445849;33517;33721.49417758785;35056.82471587626;35204;35385;31977.68022319747;32396.35207295888;32654.673453265164;33229.66770332266;33302.66697332996;29054;31045.68954310428;31235.531467028468;31405.528917065807;31417.214569635027;23714;23723.881380592986;23947.401314967265;24062.39844003914;24294 01:46:03 INFO - PROCESS | 3968 | |2;jquery.html;292.23726074944403;309.76612657443627;314.4952351475881;323.4388336236629;323.5018072168859;12742.490300387984;15880.920595397021;16812.495625131236;16850.915745421287;17070.061146636963;16155.91922040388;16218.432354867582;16242.106684132368;16297.348106075773;16317.918410407932;4962.10682077225;5239.21411788231;5241.161414173733;5263.4210236874105;5265.157574788015;3936.3898595717565;5147.81982630608;5479.068558345091;6015.9398406016035;6067.939320606803;6052.394760523956;6068.696565171724;6070.21087258658;6072.2106126203535;6072.362401947798;5813.912791308134;5828.47543721065;5965.403459654065;5970.402959704038;5978.432048955342;4832.855014349575;5038.118329292359;5123.436421993578;5145.099607568661;5148;19394.418167455;19989.300374486822;20242.291519796738;20495.48761280965;20727.481812954797;1827.5522496988324;1832.2945665918585;1960.1571324330532;2006.5685877536255;2493;13314.068015238938;13337.266450345227;13408.932955335258;13522.985776066835;13814.723705525834;1641.983580164195;1658.4444211189266;1674.6064674801535;1679.5968967447848;1733.6359364533464;77239;78092;79277.60361198215;79417.20582794157;79554.6022269885;8628.611712472828;8731.956340218385;8881.555922203865;8915.955420222857;9073.368723070367;10401;10963.122950163968;11400.714982125348;11916.570011598664;11969.46137423818;6702.932970670328;6829.556078854958;6853.451723862079;6934.341237582422;6986.790396288119;6422.42198202164;6681.799546013627;6705.396514313637;6868.141482314711;6904.100277883301;5476.452354764571;5673.3475650299915;5675;5676.375598684142;5862.449834322084;1561.242797243336;1573.2527049651324;1573.8516924398114;1592.8805339599576;1612.0569466862016;785.862474067036;786.2609147401424;809.5628360685164;859.5745106172485;903.0202230579763;17955.281788728567;18078.548036298936;18402.447926562225;18439;18810.81189188118;756.7691853984478;800.4476910931438;808.1877712898616;855.4012191465998;863.3567991845989;9771.511424428892;10034.598616055422;10153.39079655223;10208.897911020795;10290.485475726184;336224;345378;352414;354408;355298;87401.5629921859;88485;89899;90642;91776.541117294;846.3694547561983;871.5772850167664;872.2106493623281;873.7116751471999;874.1389731114847;15794.36822527086;16128.193590320672;16371.754423683893;16631.334746610482;16932.32270709158;30261.697383026767;41879.581204188784;42210.36684449674;42454.78772606117;42726.78636606797;52432;60315.39684603098;60539;60724.39275607187;61176;7322.890156647548;7409.555426674417;7445.329920307198;7450.776476705708;7556.395488360808;11783.057355411374;11926.821097683369;12168.2699038058;12389.75220495603;12403;10329.845052324372;10416.583336666448;10475.952404759462;10752.193585481276;10790.244682872437;10070;10320.535575899252;10556.788864222519;10650.201234907257;10722.73193170177;10148.391096534231;10273;10628.627998020032;10665.146788256765;10853.511591978222;7261.128664560287;7370.6683199255085;7376.225496322809;7480.92519074824;7635.46551741395;7703.191164927826;10187.134093602126;10270.486475676336;10361.63734269297;10723.356598604109 01:46:03 INFO - PROCESS | 3968 | |3;mootools.html;1085.641738226385;1085.799127161475;1157.0569985461848;1288.7809072457687;1367.1796921846887;6539.476841852652;6844.076049733285;6872.347127022925;6876.965615171924;6911.067005954201;5838.2994040715175;6055.818325450233;6667.499937504683;6860.622665753382;6946.826329341757;1970.8029197080284;1989.910454029565;1996.510854840566;1996.9301074462396;2007.2974458939327;1982.7719812221635;2005.1277694202995;2009.8291645210174;2010.577778666482;2014.446027342478;1606.8152162501287;1660.709375859226;1892.1485331600766;1946.2604210400027;1950.7854136045023;1799.2263326769485;1874.3533480949052;1885.0103695559803;1887.584731359099;1905.2569497895831;1912.4645099372199;1951.990240048805;2019;2021.1006102284489;2026.1591439552567;6777.390034896883;6866.450683945272;6880.139982502187;6898.655067246667;6903.447724182053;4138.172365526877;4192.748435093904;4233.830646774125;4234.957650423518;4239.957600424018;2718.836869787819;2785.1644506648126;2893.9276518087004;2900.3039270575123;2909.86905589249;865.0830120072743;865.1910463716446;875.7898104454948;900.9909900900973;916.9358144929857;8644.135586441369;8973.641054357819;9011.864822027675;9088;9091.590878410487;3507.3862074136923;3509.9298014039578;3523.4714792780774;3629.1471504196493;3629.328574213779;7064;7083.89374159388;7092.326229008236;7117.395021423134;7120.608366539786;4122.381642753551;4122.773247471418;4163.666906647461;4186.518550366747;4199.475065616798;3707.0917625181464;3848.2111167210655;3900.5124359455067;3961.960380396217;3985;2884.855757212173;2939.6031535742827;2949.513330300519;3001.309698769295;3023.2290765855005;886.3839631456076;895.3508706188092;911.8085202107499;912.8995810460844;913.3971578757988;663.7378235596913;688.8277930517371;692.3076923076923;697.0798545919338;716.5091912040269;4113.218488487238;4130.855420060284;4215.557366476537;4227.450431443954;4231.767252801125;354.3922173472507;356.782362758717;356.800191892541;357.524492425074;363.3151509404791;2821.2523681224493;2849.6153019342537;2988.073697153889;3005.383896301253;3153.684631536828;8342.457740246864;8461.492310461392;8472.322214222848;8492.830143397097;8501.61742721579;7928.127905930459;8341.624626891804;8455.069942306342;8481.872771908425;8499.830003399897;6193.349698281706;6379.138816259746;6526.118973938457;6535.3137920517665;6569.146011018537;4224.176285624274;4405.207062728739;4410.536893626251;4484.237679594412;4529.048899731093;7162.570245785269;7561.508501947355;7657.46397752152;7799.571023593529;7807.219278072147;7544.773656790305;7791.259830316111;7798;7806.531608103532;7813.726519571787;5542.722863856871;5930.733117009831;6009.489193418433;6056.545759068;6064.241969753781;6295.779647712308;6557.377049180321;6571.211454625475;6676.265610782712;6699.665016749241;5922.111683247547;6097.756089756537;6133.233345831771;6133.662648554372;6169.167162433014;5641.407652196624;5815.41845815415;5833.416658334133;5985.1022346646605;6009.969950150221;5347.866303342447;5434.592405569519;5453.700046497481;5458.918116228339;5464.043792336404;3684.8894533162975;3714.257148570243;3877.5928527504207;3883.223355328889;3934.8229329680807;3845.403962385835;3904.4338570907626;3919.666828319625;3981.0644498543397;4109.116539943926 01:46:03 INFO - PROCESS | 3968 | |4;prototype.html;234.70427261650315;252.8937846104636;258.4275829038679;261.79318338512576;263.8113748669702;4868.318435419044;5103.923441148386;5179.223116532513;5196.818111366103;5208.687478751278;5078.060558796626;5106.514881086291;5106.821261255856;5107.157319042363;5114.207297868837;3552.786832790028;3643.6174201708704;3684.6868016218655;3719.0144611677792;3747.7376583639157;3661.9084522887006;3691.5570131584245;3720.5721342045604;3736.215394767102;3745.288395204916;3574.303010912873;3695.796731179784;3696.7597106188014;3697.926041479169;3707.1473561080966;3687.225682606656;3693.242885208526;3699.352613292663;3703.574088979761;3710.7588006779606;3029;3173.7461003119697;3182.6817318268218;3192.6488086310487;3194.2493514024186;4964.67729597575;4977.601791856643;4999.125153098194;5003.924941125886;5008.84973450797;4112.259793237216;4302.849400270976;4313.698041137121;4337.306031035051;4346.4349634547625;5259.921101183485;5260.395054568697;5266.473352664703;5272.393674727378;5276.81531146408;3360.6807353301397;3544.1848374873925;3547.733919956014;3549.0062782420705;3555.928881422383;5519.558435325204;5524.392316845184;5525.530329921961;5554.72226388683;5571.8607034824045;3478.930421391558;3521.260535287567;3535.5227044348685;3536.717062634983;3539.6460353964912;3536.8328451610905;3568.518250036264;3571.3035957987945;3578.5526809148855;3586.4261718125495;4061;4205.789710514462;4209.116085622054;4209.873703788891;4233.5656997226715;5069.670471419346;5263.763015691309;5271.71005594696;5305.65513241638;5316.76074576645;1206.867244603093;1212.7574485103014;1214.7670114833452;1214.8967337776212;1215.9574414895437;755.3390783064818;783.9412044096601;784.6155383861867;788.440207452715;793.1117148793273;4815.855524334275;4894.877628059256;4919.680220785637;4924.9507504925205;4936.7284799336385;5244.475552444801;5345.438728933484;5360;5378.515933565998;5382.273393091882;4886.511348865085;4902.950970490321;4904.730239836772;4913.975430122827;5012.498750125032;2766.5573508238585;2839.0205379144163;2843.1470558832266;2843.8435886026055;2851.0327873767033;5550.389457159709;5560.527355174774;5568.9721551392795;5572.192032155314;5597.132444471114;5276.364327058624;5383.71192243295;5481.205225242317;5481.793151006626;5484.972575137099;5640.294963129609;5657.094864821609;5665.716714164275;5668.149777533403;5692.459216374438;5439.646422982493;5478.096114141122;5500.339959204921;5508.697021663686;5517.36550296721;5423.186522021729;5434.782608695607;5437.211604317431;5442.945570544244;5443.237946687388;5393.298871146882;5697.145428185805;5705.0871860503785;5708.343540492897;5713.7428815704225;3527.365074286653;3545.6986156177013;3560.768550044239;3561.6082230954057;3567.714582833419;5496.945030549803;5524.392316845224;5537.197106419627;5547.55619550443;5558.833235002957;5193.714345711022;5358.437364076716;5365.570754339721;5380;5391.02961466924;5318.867028324323;5331.960782354021;5335.5860696916125;5341.385740639721;5350.464953504774;5170.819021334235;5216.713080780593;5235.397929238186;5238.68567885928;5242.68543887368;5317.867053323543;5358.142697168434;5362.973185134049;5389.083855744454;5405.756740946746;5192.896142077213;5272;5292;5293.867653308698;5301.6818990860675 01:46:03 INFO - PROCESS | 3968 | |5;yui.html;569.9373068962414;571.9170720245562;580.6429046136626;612.4090252905945;653.7646447278979;12461.252324860503;13528.93235533821;13529.458821647135;13615.591532254035;13624.727505449886;11591.362475063868;11834.28994260345;11835.70410739732;12282.263064216131;12416.130870839044;469.55626932548586;565.6097292867908;569.3054473542293;574.0786038408357;576.2221001647783;478.909007288614;561.5816216918381;584.4710536964054;584.5411352088611;584.7544031506756;597.438407896578;603.6800495737266;604.3865476541314;605.884881872445;606.8452544601142;488.8680056384756;501.0605115408609;593.6616128806583;597.7907732293684;601.371566712784;428.98498552550825;436.8165370544379;440.2317955168252;441.68640265411597;442.53975865100256;11007.724806879813;11331.716707082307;11785.116116291312;11865.644030679094;11967.820482692767;11816.29102253859;11888.643340699706;12123.21199122063;12224.388780560936;12321.260724356478;11734.413279335999;11985.101117416229;12073.758524829454;12135.211211271238;12150.939245303716;3962.4254483100076;3992.161646054332;4001.1797581495725;4011.3782363733667;4042.110735638155;60370.69814650943;62159.6892015537;62243.68878155626;62812.37187628111;63290.683546581975;9481.24150067993;9514.04859514043;9532.237421006303;9539.47532885698;9692;16698.749518757228;16938.745918811226;16996.320147193972;17017.40439084651;17053.7441938371;6728.42808361285;6783.050372947791;6853.828654283583;6908.1019467468905;6909.930900690929;3424.760266781351;3644.653757892996;3763.849446022183;3773.584905660375;3775.7734535927934;2557.987210063975;2617.7644012038627;2623.357277467033;2630.776384007381;2632.236651371081;479.12798706354795;483.70010593432295;500.087340603393;502.108756956398;504.6064070025386;520.2092818915228;566.4901588570237;596.922554788602;598.6348327520209;618.6597371445687;7515.473916825879;8068.193180682002;8334.416590838584;8405.117462666332;8826.86759698605;579.0503574138417;583.6002338398126;595.8391234366696;604.7218279591349;608.285264813842;3968.722189446711;4001.279769641434;4136.544980052192;4171.0823618804425;4188.874333769992;71946.28053719396;76252.61873690596;77152;77785.22214777893;78253.60873195597;44250.778746106706;57223.42776572264;58415;59005.704971474865;69610.65194673995;48400.7579962105;58290.12564811531;58413.4158658408;59563.40436595579;59867.70066149641;8703.608337624695;9107.134822191882;9269.86095208559;9350.111739384747;9480.383775054599;41587.376189356575;41771.7911410441;42444.36333454939;42484.78757606192;42608.360874587524;55342.44657553534;55452.72273638606;56577.43422565722;56985.43014569801;56992.430075698714;10731.517081731185;10864.239503234698;10865.130789536975;10999.120070394509;11090.112790976864;13483.865161348262;13731.794023089862;13804.93097534506;13897.861021389657;13982.091164074303;12725.61823145307;12775.680607984874;12860.228386296852;12927.225065534889;12968.157069790434;9761.707148785548;10158.339707918962;10235.948820255851;10702.785944281226;12590.307533085768;9784.315097943361;10727.678169654922;12780.233186008869;12905.935470322589;13013.391745905237;10365.689029329133;10570.8414373786;10692.358458492516;10811.729706757395;10965;11068.391238481961;11072.667819965414;11158.43781870555;11370.431478426211;11371.204015719151 01:46:03 INFO - PROCESS | 3968 | __end_tp_report 01:46:03 INFO - PROCESS | 3968 | __start_cc_report 01:46:03 INFO - PROCESS | 3968 | _x_x_mozilla_cycle_collect,2370 01:46:03 INFO - PROCESS | 3968 | __end_cc_report 01:46:03 INFO - PROCESS | 3968 | __startTimestamp1478335563098__endTimestamp 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | ------- Summary: start ------- 01:46:03 INFO - PROCESS | 3968 | Number of tests: 6 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#0] dojo.html Cycles:165 Average:4553.32 Median:4767.09 stddev:574.42 (12.0%) stddev-sans-first:558.62 01:46:03 INFO - PROCESS | 3968 | Values: 2756.4 2759.3 2789.3 2796.1 2804.9 4685.7 4693.2 4767.4 4773.9 4789.4 4415.1 4654.0 4697.2 4700.6 4788.5 3938.7 3946.0 4178.6 4191.7 4193.2 3961.5 4077.2 4155.0 4185.7 4186.9 4185.6 4187.2 4191.8 4220.7 4238.3 4077.8 4161.3 4167.1 4245.8 4261.4 4043.8 4075.0 4095.9 4179.1 4217.5 4607.1 4637.0 4701.3 4720.6 4756.7 4729.8 4942.5 5033.4 5035.6 5039.1 4896.8 4969.9 5018.3 5107.8 5119.9 4580.0 4599.7 4607.3 4682.5 4693.9 4803.5 5054.3 5147.4 5153.2 5154.9 4732.9 4829.6 4846.5 4941.7 4946.6 4771.6 4893.5 4922.4 4922.9 4995.8 4711.0 4873.0 4940.4 4960.6 4963.2 4877.1 4931.2 4939.7 4951.0 4954.7 3283.9 3303.5 3306.1 3333.1 3352.6 2873.5 2904.9 3090.4 3118.8 3125.2 4669.0 4678.3 4714.4 4744.6 4757.7 4948.8 4951.4 5001.6 5019.5 5037.8 4538.7 4622.7 4640.9 4642.8 4704.3 3850.4 4017.0 4165.3 4167.8 4191.6 5000.4 5081.9 5085.1 5137.9 5242.1 4778.9 4882.8 4898.7 4940.4 5030.1 4893.9 4929.6 4957.9 5090.2 5101.1 4736.3 4767.1 4843.7 4848.5 4856.8 4897.7 4929.0 4932.6 4936.0 4957.4 4828.2 4829.1 4836.1 4838.3 4848.1 4805.3 4827.6 4835.7 4839.5 4849.9 4582.1 4721.3 4723.8 4729.3 4738.9 4834.4 4837.8 4842.2 4854.4 4858.4 4442.7 4750.7 4772.4 4810.8 4815.8 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#1] ext.html Cycles:180 Average:50297.39 Median:36875.72 stddev:101288.47 (274.7%) stddev-sans-first:101503.70 01:46:03 INFO - PROCESS | 3968 | Values: 534.8 542.1 572.7 576.7 577.4 27349.9 35659.6 36289.5 36473.3 37847.2 35872.8 36766.8 37091.8 37113.6 37161.3 6612.4 6740.6 7458.3 7461.6 7626.7 9114.6 9316.3 9322.3 9343.2 9525.8 9422.2 9482.3 9486.5 9489.0 9516.7 6562.2 6695.4 9271.3 9471.0 9534.8 9398.4 9417.8 9439.3 9451.3 9456.9 32971.3 34242.8 34363.0 34543.7 34560.3 2717.6 2722.7 2903.3 3170.1 3224.4 27368.2 27634.3 27720.0 28058.3 28080.7 3292.9 3385.0 3396.9 3436.4 3458.9 278610.0 279876.0 280424.0 280675.6 280967.0 45454.3 45663.8 45809.1 45812.5 45908.3 45887.3 47244.3 47738.5 48022.3 48106.8 11122.7 11207.9 11293.4 11579.7 11614.5 8515.8 8573.0 8622.7 8743.9 8770.5 13581.6 13742.1 13816.3 13914.4 14033.2 5517.1 5551.9 5579.7 5623.8 5625.9 738.4 808.5 809.8 876.1 881.8 25022.7 25623.6 26043.4 26064.3 26074.5 836.3 909.2 946.0 970.1 971.2 13029.3 13145.3 13577.1 13753.2 13781.8 398303.0 401248.0 401484.0 401642.0 402214.0 43145.6 43261.0 44262.8 44433.6 44434.3 942.6 959.9 1010.3 1012.1 1050.8 25328.2 26497.0 26745.7 26813.7 26911.0 38379.2 38566.4 38850.0 38959.2 38996.4 332577.0 333961.0 338278.0 557398.0 578916.0 35396.6 36393.0 36487.1 36763.3 36984.6 40154.8 40334.1 40379.8 40480.0 41263.6 32309.5 34150.0 35095.3 35877.5 36145.3 33517.0 33721.5 35056.8 35204.0 35385.0 31977.7 32396.4 32654.7 33229.7 33302.7 29054.0 31045.7 31235.5 31405.5 31417.2 23714.0 23723.9 23947.4 24062.4 24294.0 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#2] jquery.html Cycles:180 Average:24169.05 Median:42044.97 stddev:58612.83 (139.4%) stddev-sans-first:58749.84 01:46:03 INFO - PROCESS | 3968 | Values: 292.2 309.8 314.5 323.4 323.5 12742.5 15880.9 16812.5 16850.9 17070.1 16155.9 16218.4 16242.1 16297.3 16317.9 4962.1 5239.2 5241.2 5263.4 5265.2 3936.4 5147.8 5479.1 6015.9 6067.9 6052.4 6068.7 6070.2 6072.2 6072.4 5813.9 5828.5 5965.4 5970.4 5978.4 4832.9 5038.1 5123.4 5145.1 5148.0 19394.4 19989.3 20242.3 20495.5 20727.5 1827.6 1832.3 1960.2 2006.6 2493.0 13314.1 13337.3 13408.9 13523.0 13814.7 1642.0 1658.4 1674.6 1679.6 1733.6 77239.0 78092.0 79277.6 79417.2 79554.6 8628.6 8732.0 8881.6 8916.0 9073.4 10401.0 10963.1 11400.7 11916.6 11969.5 6702.9 6829.6 6853.5 6934.3 6986.8 6422.4 6681.8 6705.4 6868.1 6904.1 5476.5 5673.3 5675.0 5676.4 5862.4 1561.2 1573.3 1573.9 1592.9 1612.1 785.9 786.3 809.6 859.6 903.0 17955.3 18078.5 18402.4 18439.0 18810.8 756.8 800.4 808.2 855.4 863.4 9771.5 10034.6 10153.4 10208.9 10290.5 336224.0 345378.0 352414.0 354408.0 355298.0 87401.6 88485.0 89899.0 90642.0 91776.5 846.4 871.6 872.2 873.7 874.1 15794.4 16128.2 16371.8 16631.3 16932.3 30261.7 41879.6 42210.4 42454.8 42726.8 52432.0 60315.4 60539.0 60724.4 61176.0 7322.9 7409.6 7445.3 7450.8 7556.4 11783.1 11926.8 12168.3 12389.8 12403.0 10329.8 10416.6 10476.0 10752.2 10790.2 10070.0 10320.5 10556.8 10650.2 10722.7 10148.4 10273.0 10628.6 10665.1 10853.5 7261.1 7370.7 7376.2 7480.9 7635.5 7703.2 10187.1 10270.5 10361.6 10723.4 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#3] mootools.html Cycles:180 Average:4424.67 Median:5391.23 stddev:2473.54 (45.9%) stddev-sans-first:2467.75 01:46:03 INFO - PROCESS | 3968 | Values: 1085.6 1085.8 1157.1 1288.8 1367.2 6539.5 6844.1 6872.3 6877.0 6911.1 5838.3 6055.8 6667.5 6860.6 6946.8 1970.8 1989.9 1996.5 1996.9 2007.3 1982.8 2005.1 2009.8 2010.6 2014.4 1606.8 1660.7 1892.1 1946.3 1950.8 1799.2 1874.4 1885.0 1887.6 1905.3 1912.5 1952.0 2019.0 2021.1 2026.2 6777.4 6866.5 6880.1 6898.7 6903.4 4138.2 4192.7 4233.8 4235.0 4240.0 2718.8 2785.2 2893.9 2900.3 2909.9 865.1 865.2 875.8 901.0 916.9 8644.1 8973.6 9011.9 9088.0 9091.6 3507.4 3509.9 3523.5 3629.1 3629.3 7064.0 7083.9 7092.3 7117.4 7120.6 4122.4 4122.8 4163.7 4186.5 4199.5 3707.1 3848.2 3900.5 3962.0 3985.0 2884.9 2939.6 2949.5 3001.3 3023.2 886.4 895.4 911.8 912.9 913.4 663.7 688.8 692.3 697.1 716.5 4113.2 4130.9 4215.6 4227.5 4231.8 354.4 356.8 356.8 357.5 363.3 2821.3 2849.6 2988.1 3005.4 3153.7 8342.5 8461.5 8472.3 8492.8 8501.6 7928.1 8341.6 8455.1 8481.9 8499.8 6193.3 6379.1 6526.1 6535.3 6569.1 4224.2 4405.2 4410.5 4484.2 4529.0 7162.6 7561.5 7657.5 7799.6 7807.2 7544.8 7791.3 7798.0 7806.5 7813.7 5542.7 5930.7 6009.5 6056.5 6064.2 6295.8 6557.4 6571.2 6676.3 6699.7 5922.1 6097.8 6133.2 6133.7 6169.2 5641.4 5815.4 5833.4 5985.1 6010.0 5347.9 5434.6 5453.7 5458.9 5464.0 3684.9 3714.3 3877.6 3883.2 3934.8 3845.4 3904.4 3919.7 3981.1 4109.1 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#4] prototype.html Cycles:180 Average:4369.93 Median:5106.99 stddev:1381.50 (27.1%) stddev-sans-first:1350.06 01:46:03 INFO - PROCESS | 3968 | Values: 234.7 252.9 258.4 261.8 263.8 4868.3 5103.9 5179.2 5196.8 5208.7 5078.1 5106.5 5106.8 5107.2 5114.2 3552.8 3643.6 3684.7 3719.0 3747.7 3661.9 3691.6 3720.6 3736.2 3745.3 3574.3 3695.8 3696.8 3697.9 3707.1 3687.2 3693.2 3699.4 3703.6 3710.8 3029.0 3173.7 3182.7 3192.6 3194.2 4964.7 4977.6 4999.1 5003.9 5008.8 4112.3 4302.8 4313.7 4337.3 4346.4 5259.9 5260.4 5266.5 5272.4 5276.8 3360.7 3544.2 3547.7 3549.0 3555.9 5519.6 5524.4 5525.5 5554.7 5571.9 3478.9 3521.3 3535.5 3536.7 3539.6 3536.8 3568.5 3571.3 3578.6 3586.4 4061.0 4205.8 4209.1 4209.9 4233.6 5069.7 5263.8 5271.7 5305.7 5316.8 1206.9 1212.8 1214.8 1214.9 1216.0 755.3 783.9 784.6 788.4 793.1 4815.9 4894.9 4919.7 4925.0 4936.7 5244.5 5345.4 5360.0 5378.5 5382.3 4886.5 4903.0 4904.7 4914.0 5012.5 2766.6 2839.0 2843.1 2843.8 2851.0 5550.4 5560.5 5569.0 5572.2 5597.1 5276.4 5383.7 5481.2 5481.8 5485.0 5640.3 5657.1 5665.7 5668.1 5692.5 5439.6 5478.1 5500.3 5508.7 5517.4 5423.2 5434.8 5437.2 5442.9 5443.2 5393.3 5697.1 5705.1 5708.3 5713.7 3527.4 3545.7 3560.8 3561.6 3567.7 5496.9 5524.4 5537.2 5547.6 5558.8 5193.7 5358.4 5365.6 5380.0 5391.0 5318.9 5332.0 5335.6 5341.4 5350.5 5170.8 5216.7 5235.4 5238.7 5242.7 5317.9 5358.1 5363.0 5389.1 5405.8 5192.9 5272.0 5292.0 5293.9 5301.7 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - PROCESS | 3968 | [#5] yui.html Cycles:180 Average:15683.34 Median:440.96 stddev:20321.72 (4608.5%) stddev-sans-first:20347.04 01:46:03 INFO - PROCESS | 3968 | Values: 569.9 571.9 580.6 612.4 653.8 12461.3 13528.9 13529.5 13615.6 13624.7 11591.4 11834.3 11835.7 12282.3 12416.1 469.6 565.6 569.3 574.1 576.2 478.9 561.6 584.5 584.5 584.8 597.4 603.7 604.4 605.9 606.8 488.9 501.1 593.7 597.8 601.4 429.0 436.8 440.2 441.7 442.5 11007.7 11331.7 11785.1 11865.6 11967.8 11816.3 11888.6 12123.2 12224.4 12321.3 11734.4 11985.1 12073.8 12135.2 12150.9 3962.4 3992.2 4001.2 4011.4 4042.1 60370.7 62159.7 62243.7 62812.4 63290.7 9481.2 9514.0 9532.2 9539.5 9692.0 16698.7 16938.7 16996.3 17017.4 17053.7 6728.4 6783.1 6853.8 6908.1 6909.9 3424.8 3644.7 3763.8 3773.6 3775.8 2558.0 2617.8 2623.4 2630.8 2632.2 479.1 483.7 500.1 502.1 504.6 520.2 566.5 596.9 598.6 618.7 7515.5 8068.2 8334.4 8405.1 8826.9 579.1 583.6 595.8 604.7 608.3 3968.7 4001.3 4136.5 4171.1 4188.9 71946.3 76252.6 77152.0 77785.2 78253.6 44250.8 57223.4 58415.0 59005.7 69610.7 48400.8 58290.1 58413.4 59563.4 59867.7 8703.6 9107.1 9269.9 9350.1 9480.4 41587.4 41771.8 42444.4 42484.8 42608.4 55342.4 55452.7 56577.4 56985.4 56992.4 10731.5 10864.2 10865.1 10999.1 11090.1 13483.9 13731.8 13804.9 13897.9 13982.1 12725.6 12775.7 12860.2 12927.2 12968.2 9761.7 10158.3 10235.9 10702.8 12590.3 9784.3 10727.7 12780.2 12905.9 13013.4 10365.7 10570.8 10692.4 10811.7 10965.0 11068.4 11072.7 11158.4 11370.4 11371.2 01:46:03 INFO - PROCESS | 3968 | -------- Summary: end -------- 01:46:03 INFO - PROCESS | 3968 | 01:46:03 INFO - TEST-INFO | 3968: exit 0 01:46:04 INFO - TEST-OK | dromaeo_css | took 1120459ms 01:46:04 INFO - TEST-START | kraken 01:46:04 INFO - operating with platform_type : w7_ 01:46:04 INFO - Initialising browser for kraken test... 01:46:04 INFO - TEST-INFO | started process 1824 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvcoyaf\profile http://localhost:49651/getInfo.html) 01:46:08 INFO - PROCESS | 1824 | __metrics Screen width/height:1600/1200 01:46:08 INFO - PROCESS | 1824 | colorDepth:24 01:46:08 INFO - PROCESS | 1824 | Browser inner width/height: 1010/674 01:46:08 INFO - PROCESS | 1824 | __metrics 01:46:09 INFO - PROCESS | 1824 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:46:09 INFO - PROCESS | 1824 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:46:09 INFO - PROCESS | 1824 | console.error: 01:46:09 INFO - PROCESS | 1824 | Message: Error: Connection closed before committing the transaction. 01:46:09 INFO - PROCESS | 1824 | Stack: 01:46:09 INFO - PROCESS | 1824 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 01:46:09 INFO - PROCESS | 1824 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 01:46:09 INFO - PROCESS | 1824 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 01:46:09 INFO - PROCESS | 1824 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 01:46:09 INFO - PROCESS | 1824 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 01:46:09 INFO - PROCESS | 1824 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 01:46:09 INFO - PROCESS | 1824 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 01:46:09 INFO - PROCESS | 1824 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 01:46:09 INFO - PROCESS | 1824 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 01:46:09 INFO - PROCESS | 1824 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 01:46:09 INFO - PROCESS | 1824 | Barrier/this.client.addBlocker/promise13.2% 01:47:07 INFO - TinderboxPrint: I/O read bytes / time
95,613,440 / 1,438,520 01:47:07 INFO - TinderboxPrint: I/O write bytes / time
488,580,096 / 3,759,430 01:47:07 INFO - TinderboxPrint: CPU idle
8,251.7 (86.8%) 01:47:07 INFO - TinderboxPrint: CPU user
1,214.7 (12.8%) 01:47:07 INFO - install - Wall time: 2s; CPU: 12.0%; Read bytes: 12611584; Write bytes: 34569216; Read time: 218770; Write time: 32850 01:47:07 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 83001856; Write bytes: 416447488; Read time: 1219750; Write time: 3283330 01:47:08 INFO - Running post-run listener: _upload_blobber_files 01:47:08 INFO - Blob upload gear active. 01:47:08 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 01:47:08 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 01:47:08 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', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 01:47:08 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 -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 01:47:08 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 01:47:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:10 INFO - (blobuploader) - INFO - Done attempting. 01:47:10 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 01:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:11 INFO - (blobuploader) - INFO - Done attempting. 01:47:11 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 01:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:47:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:18 INFO - (blobuploader) - INFO - Done attempting. 01:47:18 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:18 INFO - Return code: 0 01:47:18 INFO - rmtree: C:\slave\test\build\uploaded_files.json 01:47:18 INFO - Using _rmtree_windows ... 01:47:18 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 01:47:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1973e5b62b6185d07c2d119b7c24e8967af4aec1a844f27e45d38c68e0205fb0e187b1989ca0041811b4a02e814098675d31dac3c98914508771f88f2996d19c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/527237ba273c8a8fd6a761af2f70e55c9ef84cbaf8478afce94df7b64dcf6da9c119bdfdb3440e441e15eb3f613d57e81d99929fb02d71ef30daf2831909b6dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fd92a7d486fd1c3a6b69575fed5994d240c42ac537ce7b5840e68bab1d8f2dc656f9efd9bc4db9117f01ac0594a08c53bd0e7617b1014bcce1e98d57573f680"} 01:47:18 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 01:47:18 INFO - Writing to file C:\slave\test\properties\blobber_files 01:47:18 INFO - Contents: 01:47:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1973e5b62b6185d07c2d119b7c24e8967af4aec1a844f27e45d38c68e0205fb0e187b1989ca0041811b4a02e814098675d31dac3c98914508771f88f2996d19c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/527237ba273c8a8fd6a761af2f70e55c9ef84cbaf8478afce94df7b64dcf6da9c119bdfdb3440e441e15eb3f613d57e81d99929fb02d71ef30daf2831909b6dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fd92a7d486fd1c3a6b69575fed5994d240c42ac537ce7b5840e68bab1d8f2dc656f9efd9bc4db9117f01ac0594a08c53bd0e7617b1014bcce1e98d57573f680"} 01:47:18 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:18 INFO - Copying logs to upload dir... 01:47:18 INFO - mkdir: C:\slave\test\build\upload\logs 01:47:18 INFO - Copying logs to upload dir... 01:47:18 INFO - Using _rmtree_windows ... 01:47:18 INFO - Using _rmtree_windows ... 01:47:18 INFO - Using _rmtree_windows ... 01:47:18 INFO - Using _rmtree_windows ... 01:47:19 INFO - Using _rmtree_windows ... 01:47:19 INFO - Using _rmtree_windows ... 01:47:19 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1475.319000 ========= master_lag: 0.52 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 35 secs) (at 2016-11-05 01:47:19.658303) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 01:47:19.672210) ========= '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-146 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-146 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-146 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/sha512/1973e5b62b6185d07c2d119b7c24e8967af4aec1a844f27e45d38c68e0205fb0e187b1989ca0041811b4a02e814098675d31dac3c98914508771f88f2996d19c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/527237ba273c8a8fd6a761af2f70e55c9ef84cbaf8478afce94df7b64dcf6da9c119bdfdb3440e441e15eb3f613d57e81d99929fb02d71ef30daf2831909b6dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fd92a7d486fd1c3a6b69575fed5994d240c42ac537ce7b5840e68bab1d8f2dc656f9efd9bc4db9117f01ac0594a08c53bd0e7617b1014bcce1e98d57573f680"} build_url:https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/ElPruJ4SQjWb0IVpXQpBZg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1973e5b62b6185d07c2d119b7c24e8967af4aec1a844f27e45d38c68e0205fb0e187b1989ca0041811b4a02e814098675d31dac3c98914508771f88f2996d19c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/527237ba273c8a8fd6a761af2f70e55c9ef84cbaf8478afce94df7b64dcf6da9c119bdfdb3440e441e15eb3f613d57e81d99929fb02d71ef30daf2831909b6dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fd92a7d486fd1c3a6b69575fed5994d240c42ac537ce7b5840e68bab1d8f2dc656f9efd9bc4db9117f01ac0594a08c53bd0e7617b1014bcce1e98d57573f680"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 01:47:19.846381) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 01:47:19.846684) ========= '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-146 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-146 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-146 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 01:47:19.959888) ========= ========= Started reboot slave lost (results: 0, elapsed: 5 secs) (at 2016-11-05 01:47:19.960169) ========= ========= Finished reboot slave lost (results: 0, elapsed: 5 secs) (at 2016-11-05 01:47:25.303316) ========= ========= Total master_lag: 0.69 =========