builder: autoland_win7_ix_test-dromaeojs slave: t-w732-ix-081 starttime: 1475122540.76 results: success (0) buildid: 20160928193620 builduid: 40a66fdc19eb418293502d7efbbb6efd revision: be5121024c7c3f363b94de428eb795007697e016 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:40.758034) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:40.758837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:40.759119) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:40.847748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:40.848028) ========= '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-081 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-081 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-081 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 --21:15:41-- 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,141 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 21:15:41 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.842000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:41.761369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-28 21:15:41.761712) ========= '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-081 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-081 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-081 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=8.801000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-28 21:15:50.573932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-28 21:15:50.574360) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev be5121024c7c3f363b94de428eb795007697e016 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev be5121024c7c3f363b94de428eb795007697e016 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-081 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-081 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-081 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-09-28 21:15:51,115 truncating revision to first 12 chars 2016-09-28 21:15:51,115 Setting DEBUG logging. 2016-09-28 21:15:51,115 attempt 1/10 2016-09-28 21:15:51,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/be5121024c7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 21:15:52,519 unpacking tar archive at: autoland-be5121024c7c/testing/mozharness/ 2016-09-28 21:15:52,664 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.295000 ========= master_lag: 0.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-28 21:15:53.089773) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:53.090088) ========= script_repo_revision: be5121024c7c3f363b94de428eb795007697e016 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:53.090482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:53.090749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 21:15:53.100887) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 38 secs) (at 2016-09-28 21:15:53.101154) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-081 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-081 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-081 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 21:15:53 INFO - MultiFileLogger online at 20160928 21:15:53 in C:\slave\test 21:15:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:15:53 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 21:15:53 INFO - {'append_to_log': False, 21:15:53 INFO - 'base_work_dir': 'C:\\slave\\test', 21:15:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:15:53 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 21:15:53 INFO - 'branch': 'Autoland-Non-PGO', 21:15:53 INFO - 'buildbot_json_path': 'buildprops.json', 21:15:53 INFO - 'config_files': ('talos/windows_config.py',), 21:15:53 INFO - 'default_actions': ('clobber', 21:15:53 INFO - 'read-buildbot-config', 21:15:53 INFO - 'download-and-extract', 21:15:53 INFO - 'populate-webroot', 21:15:53 INFO - 'create-virtualenv', 21:15:53 INFO - 'install', 21:15:53 INFO - 'run-tests'), 21:15:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:15:53 INFO - 'download_minidump_stackwalk': True, 21:15:53 INFO - 'download_symbols': 'ondemand', 21:15:53 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 21:15:53 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 21:15:53 INFO - 'hg': 'c:/mozilla-build/hg/hg', 21:15:53 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 21:15:53 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 21:15:53 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 21:15:53 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 21:15:53 INFO - 'C:/mozilla-build/tooltool.py'), 21:15:53 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 21:15:53 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:15:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:15:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:15:53 INFO - 'installer_path': 'installer.exe', 21:15:53 INFO - 'log_level': 'info', 21:15:53 INFO - 'log_name': 'talos', 21:15:53 INFO - 'log_to_console': True, 21:15:53 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 21:15:53 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 21:15:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 21:15:53 INFO - 'opt_config_files': (), 21:15:53 INFO - 'pip_index': False, 21:15:53 INFO - 'sps_profile': False, 21:15:53 INFO - 'sps_profile_interval': 0, 21:15:53 INFO - 'suite': 'dromaeojs', 21:15:53 INFO - 'system_bits': '32', 21:15:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:15:53 INFO - 'title': 'T-W732-IX-081', 21:15:53 INFO - 'use_talos_json': True, 21:15:53 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 21:15:53 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 21:15:53 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 21:15:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:15:53 INFO - 'work_dir': 'build'} 21:15:53 INFO - [mozharness: 2016-09-29 04:15:53.616000Z] Running clobber step. 21:15:53 INFO - Running pre-action listener: _resource_record_pre_action 21:15:53 INFO - Running main action method: clobber 21:15:53 INFO - rmtree: C:\slave\test\build 21:15:53 INFO - Using _rmtree_windows ... 21:15:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 21:17:35 INFO - Running post-action listener: _resource_record_post_action 21:17:35 INFO - [mozharness: 2016-09-29 04:17:35.666000Z] Finished clobber step (success) 21:17:35 INFO - [mozharness: 2016-09-29 04:17:35.666000Z] Running read-buildbot-config step. 21:17:35 INFO - Running pre-action listener: _resource_record_pre_action 21:17:35 INFO - Running main action method: read_buildbot_config 21:17:35 INFO - Using buildbot properties: 21:17:35 INFO - { 21:17:35 INFO - "project": "", 21:17:35 INFO - "product": "firefox", 21:17:35 INFO - "script_repo_revision": "production", 21:17:35 INFO - "builddir": "autoland_win7_ix_test-dromaeojs", 21:17:35 INFO - "repository": "", 21:17:35 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs", 21:17:35 INFO - "buildid": "20160928193620", 21:17:35 INFO - "pgo_build": "False", 21:17:35 INFO - "basedir": "C:\\slave\\test", 21:17:35 INFO - "buildnumber": 203, 21:17:35 INFO - "slavename": "t-w732-ix-081", 21:17:35 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 21:17:35 INFO - "platform": "win7_ix", 21:17:35 INFO - "scheduler": "tests-autoland-win32-talos", 21:17:35 INFO - "branch": "autoland", 21:17:35 INFO - "repo_path": "integration/autoland", 21:17:35 INFO - "revision": "be5121024c7c3f363b94de428eb795007697e016", 21:17:35 INFO - "stage_platform": "win32", 21:17:35 INFO - "builduid": "40a66fdc19eb418293502d7efbbb6efd", 21:17:35 INFO - "slavebuilddir": "test" 21:17:35 INFO - } 21:17:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 21:17:35 INFO - Running post-action listener: _resource_record_post_action 21:17:35 INFO - [mozharness: 2016-09-29 04:17:35.670000Z] Finished read-buildbot-config step (success) 21:17:35 INFO - [mozharness: 2016-09-29 04:17:35.670000Z] Running download-and-extract step. 21:17:35 INFO - Running pre-action listener: _resource_record_pre_action 21:17:35 INFO - Running main action method: download_and_extract 21:17:35 INFO - mkdir: C:\slave\test\build\tests 21:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:17:35 INFO - trying https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 21:17:35 INFO - Downloading https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/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 21:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/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 21:17:36 INFO - Downloaded 1274 bytes. 21:17:36 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 21:17:36 INFO - Using the following test package requirements: 21:17:36 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 21:17:36 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 21:17:36 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 21:17:36 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'jsshell-win32.zip'], 21:17:36 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 21:17:36 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 21:17:36 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 21:17:36 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 21:17:36 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 21:17:36 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:17:36 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 21:17:36 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 21:17:36 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 21:17:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:17:36 INFO - Fetch https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 21:17:37 INFO - Http code: 200 21:17:37 INFO - Content-Encoding: None 21:17:37 INFO - Content-Type: application/zip 21:17:37 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 21:17:37 INFO - x-amz-cf-id: GfyXRxnTO4LuNHntNCg18nqT2-4hzHYcA3cyklPEvxp2VWRwikqFfA== 21:17:37 INFO - x-amz-version-id: 8X2b8pO.1IZdgJBTwXfKUPxwyyVAHqg1 21:17:37 INFO - x-cache: Hit from cloudfront 21:17:38 INFO - Expected file size: 17324867 21:17:38 INFO - Obtained file size: 17324867 21:18:07 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 21:18:07 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 21:18:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:18:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 21:18:08 INFO - Http code: 200 21:18:08 INFO - Content-Encoding: None 21:18:08 INFO - Content-Type: application/zip 21:18:08 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 21:18:08 INFO - x-amz-cf-id: q2wgMFG5UWgkObi1Zg-5UrMHleEsOfHBCrajd31c0pG0Xf7YVdf_Uw== 21:18:08 INFO - x-amz-version-id: 8X2b8pO.1IZdgJBTwXfKUPxwyyVAHqg1 21:18:08 INFO - x-cache: Hit from cloudfront 21:18:08 INFO - Expected file size: 17324867 21:18:08 INFO - Obtained file size: 17324867 21:18:19 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 21:18:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 21:18:19 INFO - Fetch https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 21:18:19 INFO - Http code: 200 21:18:19 INFO - Content-Encoding: None 21:18:19 INFO - Content-Type: application/zip 21:18:19 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 21:18:19 INFO - x-amz-cf-id: jQcT8HqKqttSclvi7LBHYxlCyWGAmX0JAXU16vQoIO5ty_V_mfQqdA== 21:18:19 INFO - x-amz-version-id: B8VBnjghwqLmJUb2t2JT_7ZCTki6aF7_ 21:18:19 INFO - x-cache: Hit from cloudfront 21:18:20 INFO - Expected file size: 14414067 21:18:20 INFO - Obtained file size: 14414067 21:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:23 INFO - trying https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:18:23 INFO - Downloading https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 21:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/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 21:18:26 INFO - Downloaded 53549585 bytes. 21:18:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:18:26 INFO - mkdir: C:\slave\test\properties 21:18:26 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 21:18:26 INFO - Writing to file C:\slave\test\properties\build_url 21:18:26 INFO - Contents: 21:18:26 INFO - build_url:https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:18:26 INFO - Running post-action listener: _resource_record_post_action 21:18:26 INFO - Running post-action listener: set_extra_try_arguments 21:18:26 INFO - [mozharness: 2016-09-29 04:18:26.843000Z] Finished download-and-extract step (success) 21:18:26 INFO - [mozharness: 2016-09-29 04:18:26.843000Z] Running populate-webroot step. 21:18:26 INFO - Running pre-action listener: _resource_record_pre_action 21:18:26 INFO - Running main action method: populate_webroot 21:18:26 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 21:18:26 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 21:18:26 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 21:18:26 INFO - u'--tppagecycles', 21:18:26 INFO - u'7'], 21:18:26 INFO - u'tests': [u'tsvgm']}}, 21:18:26 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 21:18:26 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 21:18:26 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 21:18:26 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 21:18:26 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:18:26 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:18:26 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'damp', u'tps']}, 21:18:26 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'tests': [u'damp', u'tps']}, 21:18:26 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'dromaeo_dom']}, 21:18:26 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 21:18:26 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'basic_compositor_video']}, 21:18:26 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 21:18:26 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'other-e10s': {u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'a11yr', 21:18:26 INFO - u'ts_paint', 21:18:26 INFO - u'tpaint', 21:18:26 INFO - u'sessionrestore', 21:18:26 INFO - u'sessionrestore_no_auto_restore', 21:18:26 INFO - u'tabpaint']}, 21:18:26 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'tsvgx', 21:18:26 INFO - u'tsvgr_opacity', 21:18:26 INFO - u'tart', 21:18:26 INFO - u'tscrollx', 21:18:26 INFO - u'cart']}, 21:18:26 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 21:18:26 INFO - u'tsvgr_opacity', 21:18:26 INFO - u'tart', 21:18:26 INFO - u'tscrollx', 21:18:26 INFO - u'cart']}, 21:18:26 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'talos_options': [u'--disable-e10s'], 21:18:26 INFO - u'tests': [u'tp5o']}, 21:18:26 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'tests': [u'tp5o']}, 21:18:26 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'talos_options': [u'--disable-e10s', 21:18:26 INFO - u'--xperf_path', 21:18:26 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:26 INFO - u'tests': [u'tp5n']}, 21:18:26 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:26 INFO - u'talos_options': [u'--xperf_path', 21:18:26 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:26 INFO - u'tests': [u'tp5n']}}, 21:18:26 INFO - u'talos.zip': {u'path': u'', 21:18:26 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:18:26 INFO - Running post-action listener: _resource_record_post_action 21:18:26 INFO - [mozharness: 2016-09-29 04:18:26.857000Z] Finished populate-webroot step (success) 21:18:26 INFO - [mozharness: 2016-09-29 04:18:26.857000Z] Running create-virtualenv step. 21:18:26 INFO - Running pre-action listener: _resource_record_pre_action 21:18:26 INFO - Running main action method: create_virtualenv 21:18:26 INFO - Creating virtualenv C:\slave\test\build/venv 21:18:26 INFO - mkdir: C:\slave\test\build\venv\Scripts 21:18:27 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 21:18:27 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'! 21:18:27 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 21:18:27 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 21:18:41 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 21:18:45 INFO - Installing distribute......................................................................................................................................................................................done. 21:18:47 INFO - Return code: 0 21:18:47 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 21:18:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 21:18:47 INFO - Reading from file tmpfile_stdout 21:18:47 INFO - Output received: 21:18:47 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 21:18:47 INFO - Using _rmtree_windows ... 21:18:47 INFO - Using _rmtree_windows ... 21:18:47 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 21:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:18:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 21:18:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:18:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:47 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:18:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:47 INFO - 'DCLOCATION': 'SCL3', 21:18:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:47 INFO - 'HOMEDRIVE': 'C:', 21:18:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:47 INFO - 'KTS_VERSION': '1.19c', 21:18:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:18:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:47 INFO - 'MOZ_NO_REMOTE': '1', 21:18:47 INFO - 'NO_EM_RESTART': '1', 21:18:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:47 INFO - 'OS': 'Windows_NT', 21:18:47 INFO - 'OURDRIVE': 'C:', 21:18:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:47 INFO - 'PROCESSOR_LEVEL': '6', 21:18:47 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:47 INFO - 'PROMPT': '$P$G', 21:18:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:18:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:47 INFO - 'PWD': 'C:\\slave\\test', 21:18:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:47 INFO - 'SYSTEMDRIVE': 'C:', 21:18:47 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:47 INFO - 'TEST1': 'testie', 21:18:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:47 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:18:47 INFO - 'USERNAME': 'cltbld', 21:18:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:47 INFO - 'WINDIR': 'C:\\windows', 21:18:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:18:48 INFO - Downloading/unpacking pip>=1.5 21:18:52 INFO - Running setup.py egg_info for package pip 21:18:52 INFO - warning: no files found matching 'pip\cacert.pem' 21:18:52 INFO - warning: no files found matching '*.html' under directory 'docs' 21:18:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:18:52 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:18:52 INFO - Installing collected packages: pip 21:18:52 INFO - Found existing installation: pip 0.8.2 21:18:52 INFO - Uninstalling pip: 21:18:52 INFO - Successfully uninstalled pip 21:18:52 INFO - Running setup.py install for pip 21:18:52 INFO - warning: no files found matching 'pip\cacert.pem' 21:18:52 INFO - warning: no files found matching '*.html' under directory 'docs' 21:18:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:18:52 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:18:52 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 21:18:52 INFO - Successfully installed pip 21:18:52 INFO - Cleaning up... 21:18:52 INFO - Return code: 0 21:18:52 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 21:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:52 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:18:52 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 21:18:52 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:18:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:52 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:18:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:52 INFO - 'DCLOCATION': 'SCL3', 21:18:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:52 INFO - 'HOMEDRIVE': 'C:', 21:18:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:52 INFO - 'KTS_VERSION': '1.19c', 21:18:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:18:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:52 INFO - 'MOZ_NO_REMOTE': '1', 21:18:52 INFO - 'NO_EM_RESTART': '1', 21:18:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:52 INFO - 'OS': 'Windows_NT', 21:18:52 INFO - 'OURDRIVE': 'C:', 21:18:52 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:52 INFO - 'PROCESSOR_LEVEL': '6', 21:18:52 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:52 INFO - 'PROMPT': '$P$G', 21:18:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:18:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:52 INFO - 'PWD': 'C:\\slave\\test', 21:18:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:52 INFO - 'SYSTEMDRIVE': 'C:', 21:18:52 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:52 INFO - 'TEST1': 'testie', 21:18:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:52 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:18:52 INFO - 'USERNAME': 'cltbld', 21:18:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:52 INFO - 'WINDIR': 'C:\\windows', 21:18:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:54 INFO - Downloading/unpacking psutil>=3.1.1 21:18: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 21:18: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 21:18:55 INFO - Installing collected packages: psutil 21:18:55 INFO - Successfully installed psutil 21:18:55 INFO - Cleaning up... 21:18:55 INFO - Return code: 0 21:18:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 21:18:55 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')]} 21:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:55 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')]} 21:18:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:55 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:18:55 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 21:18:55 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 21:18:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:55 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:18:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:55 INFO - 'DCLOCATION': 'SCL3', 21:18:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:55 INFO - 'HOMEDRIVE': 'C:', 21:18:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:55 INFO - 'KTS_VERSION': '1.19c', 21:18:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:18:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:55 INFO - 'MOZ_NO_REMOTE': '1', 21:18:55 INFO - 'NO_EM_RESTART': '1', 21:18:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:55 INFO - 'OS': 'Windows_NT', 21:18:55 INFO - 'OURDRIVE': 'C:', 21:18:55 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;', 21:18:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:55 INFO - 'PROCESSOR_LEVEL': '6', 21:18:55 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:55 INFO - 'PROMPT': '$P$G', 21:18:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:18:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:55 INFO - 'PWD': 'C:\\slave\\test', 21:18:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:55 INFO - 'SYSTEMDRIVE': 'C:', 21:18:55 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:55 INFO - 'TEST1': 'testie', 21:18:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:55 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:18:55 INFO - 'USERNAME': 'cltbld', 21:18:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:55 INFO - 'WINDIR': 'C:\\windows', 21:18:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:18:56 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 21:18:56 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 21:18:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:18:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 21:18:57 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) 21:18:57 INFO - Installing collected packages: mozsystemmonitor 21:18:57 INFO - Running setup.py install for mozsystemmonitor 21:18:57 INFO - Successfully installed mozsystemmonitor 21:18:57 INFO - Cleaning up... 21:18:57 INFO - Return code: 0 21:18:57 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 21:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:57 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:18:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 21:18:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:18:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:18:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:18:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:18:57 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:18:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:18:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:18:57 INFO - 'DCLOCATION': 'SCL3', 21:18:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:18:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:18:57 INFO - 'HOMEDRIVE': 'C:', 21:18:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:18:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:18:57 INFO - 'KTS_VERSION': '1.19c', 21:18:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:18:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:18:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:18:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:57 INFO - 'MOZ_NO_REMOTE': '1', 21:18:57 INFO - 'NO_EM_RESTART': '1', 21:18:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:18:57 INFO - 'OS': 'Windows_NT', 21:18:57 INFO - 'OURDRIVE': 'C:', 21:18:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:18:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:18:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:18:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:18:57 INFO - 'PROCESSOR_LEVEL': '6', 21:18:57 INFO - 'PROCESSOR_REVISION': '1e05', 21:18:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:18:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:18:57 INFO - 'PROMPT': '$P$G', 21:18:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:18:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:18:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:18:57 INFO - 'PWD': 'C:\\slave\\test', 21:18:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:18:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:18:57 INFO - 'SYSTEMDRIVE': 'C:', 21:18:57 INFO - 'SYSTEMROOT': 'C:\\windows', 21:18:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:57 INFO - 'TEST1': 'testie', 21:18:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:18:57 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:18:57 INFO - 'USERNAME': 'cltbld', 21:18:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:18:57 INFO - 'WINDIR': 'C:\\windows', 21:18:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:18:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:58 INFO - Downloading/unpacking jsonschema==2.5.1 21:18:58 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 21:18:58 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 21:18:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:18:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:18:59 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 21:18:59 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 21:18:59 INFO - Downloading functools32-3.2.3-2.tar.gz 21:18:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 21:18:59 INFO - warning: no files found matching '*.txt' 21:18:59 INFO - no previously-included directories found matching 'build' 21:18:59 INFO - no previously-included directories found matching 'dist' 21:18:59 INFO - no previously-included directories found matching '.git*' 21:18:59 INFO - Installing collected packages: jsonschema, functools32 21:18:59 INFO - Running setup.py install for functools32 21:18:59 INFO - warning: no files found matching '*.txt' 21:18:59 INFO - no previously-included directories found matching 'build' 21:18:59 INFO - no previously-included directories found matching 'dist' 21:18:59 INFO - no previously-included directories found matching '.git*' 21:18:59 INFO - Successfully installed jsonschema functools32 21:18:59 INFO - Cleaning up... 21:19:00 INFO - Return code: 0 21:19:00 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 21:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:00 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:00 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 21:19:00 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 21:19:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:00 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:00 INFO - 'DCLOCATION': 'SCL3', 21:19:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:00 INFO - 'HOMEDRIVE': 'C:', 21:19:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:00 INFO - 'KTS_VERSION': '1.19c', 21:19:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:00 INFO - 'MOZ_NO_REMOTE': '1', 21:19:00 INFO - 'NO_EM_RESTART': '1', 21:19:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:00 INFO - 'OS': 'Windows_NT', 21:19:00 INFO - 'OURDRIVE': 'C:', 21:19:00 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;', 21:19:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:00 INFO - 'PROCESSOR_LEVEL': '6', 21:19:00 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:00 INFO - 'PROMPT': '$P$G', 21:19:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:00 INFO - 'PWD': 'C:\\slave\\test', 21:19:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:00 INFO - 'SYSTEMDRIVE': 'C:', 21:19:00 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:00 INFO - 'TEST1': 'testie', 21:19:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:00 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:00 INFO - 'USERNAME': 'cltbld', 21:19:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:00 INFO - 'WINDIR': 'C:\\windows', 21:19:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 21:19:01 INFO - Cleaning up... 21:19:01 INFO - Return code: 0 21:19:01 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 21:19:01 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')]} 21:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:01 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')]} 21:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:01 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:01 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 21:19:01 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 21:19:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:01 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:01 INFO - 'DCLOCATION': 'SCL3', 21:19:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:01 INFO - 'HOMEDRIVE': 'C:', 21:19:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:01 INFO - 'KTS_VERSION': '1.19c', 21:19:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:01 INFO - 'MOZ_NO_REMOTE': '1', 21:19:01 INFO - 'NO_EM_RESTART': '1', 21:19:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:01 INFO - 'OS': 'Windows_NT', 21:19:01 INFO - 'OURDRIVE': 'C:', 21:19:01 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;', 21:19:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:01 INFO - 'PROCESSOR_LEVEL': '6', 21:19:01 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:01 INFO - 'PROMPT': '$P$G', 21:19:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:01 INFO - 'PWD': 'C:\\slave\\test', 21:19:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:01 INFO - 'SYSTEMDRIVE': 'C:', 21:19:01 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:01 INFO - 'TEST1': 'testie', 21:19:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:01 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:01 INFO - 'USERNAME': 'cltbld', 21:19:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:01 INFO - 'WINDIR': 'C:\\windows', 21:19:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:01 INFO - Downloading/unpacking blobuploader==1.2.4 21:19:01 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 21:19:01 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 21:19:02 INFO - Downloading blobuploader-1.2.4.tar.gz 21:19:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 21:19:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:19:02 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 21:19:02 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 21:19:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 21:19:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:19:03 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 21:19:03 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 21:19:03 INFO - Downloading docopt-0.6.1.tar.gz 21:19:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 21:19:03 INFO - Installing collected packages: blobuploader, requests, docopt 21:19:03 INFO - Running setup.py install for blobuploader 21:19:03 INFO - Running setup.py install for requests 21:19:04 INFO - Running setup.py install for docopt 21:19:04 INFO - Successfully installed blobuploader requests docopt 21:19:04 INFO - Cleaning up... 21:19:05 INFO - Return code: 0 21:19:05 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:05 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:05 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 21:19:05 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 21:19:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:05 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:05 INFO - 'DCLOCATION': 'SCL3', 21:19:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:05 INFO - 'HOMEDRIVE': 'C:', 21:19:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:05 INFO - 'KTS_VERSION': '1.19c', 21:19:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:05 INFO - 'MOZ_NO_REMOTE': '1', 21:19:05 INFO - 'NO_EM_RESTART': '1', 21:19:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:05 INFO - 'OS': 'Windows_NT', 21:19:05 INFO - 'OURDRIVE': 'C:', 21:19:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:19:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:05 INFO - 'PROCESSOR_LEVEL': '6', 21:19:05 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:05 INFO - 'PROMPT': '$P$G', 21:19:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:05 INFO - 'PWD': 'C:\\slave\\test', 21:19:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:05 INFO - 'SYSTEMDRIVE': 'C:', 21:19:05 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:05 INFO - 'TEST1': 'testie', 21:19:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:05 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:05 INFO - 'USERNAME': 'cltbld', 21:19:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:05 INFO - 'WINDIR': 'C:\\windows', 21:19:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 21:19:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jp9024-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 21:19:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 21:19:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvhjf2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 21:19:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 21:19:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2_ajk4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 21:19:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 21:19:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ezlmsg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cmdjv3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c0yuu2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rhazzd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tjkbet-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vzmmog-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4apbaq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-arw0bu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 21:19:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 21:19:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p8inam-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 21:19:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 21:19:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-42ktek-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 21:19:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 21:19:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-olhpda-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 21:19:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 21:19:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-joco1w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 21:19:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 21:19:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k0_bkb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 21:19:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 21:19:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-no691e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 21:19:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:19:08 INFO - Running setup.py install for manifestparser 21:19:08 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 21:19:08 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 21:19:09 INFO - Running setup.py install for mozcrash 21:19:09 INFO - Running setup.py install for mozdebug 21:19:10 INFO - Running setup.py install for mozdevice 21:19:10 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 21:19:10 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 21:19:10 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 21:19:10 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 21:19:10 INFO - Running setup.py install for mozfile 21:19:11 INFO - Running setup.py install for mozhttpd 21:19:11 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Running setup.py install for mozinfo 21:19:11 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Running setup.py install for mozInstall 21:19:11 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 21:19:11 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 21:19:12 INFO - Running setup.py install for mozleak 21:19:12 INFO - Running setup.py install for mozlog 21:19:12 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 21:19:12 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 21:19:13 INFO - Running setup.py install for moznetwork 21:19:13 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 21:19:13 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 21:19:13 INFO - Running setup.py install for mozprocess 21:19:14 INFO - Running setup.py install for mozprofile 21:19:14 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 21:19:14 INFO - Running setup.py install for mozrunner 21:19:15 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 21:19:15 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 21:19:15 INFO - Running setup.py install for mozscreenshot 21:19:16 INFO - Running setup.py install for moztest 21:19:16 INFO - Running setup.py install for mozversion 21:19:16 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 21:19:16 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 21:19:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:19:16 INFO - Cleaning up... 21:19:16 INFO - Return code: 0 21:19:16 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:16 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 21:19:16 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 21:19:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:16 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:16 INFO - 'DCLOCATION': 'SCL3', 21:19:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:16 INFO - 'HOMEDRIVE': 'C:', 21:19:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:16 INFO - 'KTS_VERSION': '1.19c', 21:19:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:16 INFO - 'MOZ_NO_REMOTE': '1', 21:19:16 INFO - 'NO_EM_RESTART': '1', 21:19:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:16 INFO - 'OS': 'Windows_NT', 21:19:16 INFO - 'OURDRIVE': 'C:', 21:19:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:19:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:16 INFO - 'PROCESSOR_LEVEL': '6', 21:19:16 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:16 INFO - 'PROMPT': '$P$G', 21:19:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:16 INFO - 'PWD': 'C:\\slave\\test', 21:19:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:16 INFO - 'SYSTEMDRIVE': 'C:', 21:19:16 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:16 INFO - 'TEST1': 'testie', 21:19:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:16 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:16 INFO - 'USERNAME': 'cltbld', 21:19:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:16 INFO - 'WINDIR': 'C:\\windows', 21:19:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hm_p0j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 21:19:17 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)) 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yqhi4d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 21:19:17 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)) 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gel9pk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 21:19:17 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)) 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h19v3d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 21:19:17 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)) 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kxcz4o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 21:19:17 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)) 21:19:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 21:19:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-16qyfj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ovpddy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vuba7t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oputdj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ghlbkt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yndzcr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tutayz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 21:19:18 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)) 21:19:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 21:19:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3f0l9m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 21:19:19 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)) 21:19:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 21:19:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcjmtg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 21:19:19 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)) 21:19:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 21:19:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kcashx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 21:19:19 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)) 21:19:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 21:19:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7tkdc0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 21:19:19 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)) 21:19:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 21:19:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yibbiq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 21:19:19 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)) 21:19:19 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)) 21:19:19 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)) 21:19:19 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)) 21:19:19 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)) 21:19:19 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)) 21:19:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 21:19:19 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 21:19:19 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 21:19:20 INFO - Downloading blessings-1.6.tar.gz 21:19:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 21:19:20 INFO - Installing collected packages: blessings 21:19:20 INFO - Running setup.py install for blessings 21:19:21 INFO - Successfully installed blessings 21:19:21 INFO - Cleaning up... 21:19:23 INFO - Return code: 0 21:19:23 INFO - Done creating virtualenv C:\slave\test\build/venv. 21:19:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:19:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:19:24 INFO - Reading from file tmpfile_stdout 21:19:24 INFO - Using _rmtree_windows ... 21:19:24 INFO - Using _rmtree_windows ... 21:19:24 INFO - Current package versions: 21:19:24 INFO - blessings == 1.6 21:19:24 INFO - blobuploader == 1.2.4 21:19:24 INFO - docopt == 0.6.1 21:19:24 INFO - functools32 == 3.2.3-2 21:19:24 INFO - jsonschema == 2.5.1 21:19:24 INFO - manifestparser == 1.1 21:19:24 INFO - mozInstall == 1.12 21:19:24 INFO - mozcrash == 0.17 21:19:24 INFO - mozdebug == 0.1 21:19:24 INFO - mozdevice == 0.48 21:19:24 INFO - mozfile == 1.2 21:19:24 INFO - mozhttpd == 0.7 21:19:24 INFO - mozinfo == 0.9 21:19:24 INFO - mozleak == 0.1 21:19:24 INFO - mozlog == 3.4 21:19:24 INFO - moznetwork == 0.27 21:19:24 INFO - mozprocess == 0.23 21:19:24 INFO - mozprofile == 0.28 21:19:24 INFO - mozrunner == 6.12 21:19:24 INFO - mozscreenshot == 0.1 21:19:24 INFO - mozsystemmonitor == 0.3 21:19:24 INFO - moztest == 0.7 21:19:24 INFO - mozversion == 1.4 21:19:24 INFO - psutil == 3.1.1 21:19:24 INFO - requests == 1.2.3 21:19:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:24 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 21:19:24 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 21:19:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:24 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:24 INFO - 'DCLOCATION': 'SCL3', 21:19:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:24 INFO - 'HOMEDRIVE': 'C:', 21:19:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:24 INFO - 'KTS_VERSION': '1.19c', 21:19:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:24 INFO - 'MOZ_NO_REMOTE': '1', 21:19:24 INFO - 'NO_EM_RESTART': '1', 21:19:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:24 INFO - 'OS': 'Windows_NT', 21:19:24 INFO - 'OURDRIVE': 'C:', 21:19:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:19:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:24 INFO - 'PROCESSOR_LEVEL': '6', 21:19:24 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:24 INFO - 'PROMPT': '$P$G', 21:19:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:24 INFO - 'PWD': 'C:\\slave\\test', 21:19:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:24 INFO - 'SYSTEMDRIVE': 'C:', 21:19:24 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:24 INFO - 'TEST1': 'testie', 21:19:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:24 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:24 INFO - 'USERNAME': 'cltbld', 21:19:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:24 INFO - 'WINDIR': 'C:\\windows', 21:19:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 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)) 21:19:24 INFO - Cleaning up... 21:19:24 INFO - Return code: 0 21:19:24 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 21:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:19:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018C0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01967E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0186B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-081', 'USERDOMAIN': 'T-W732-IX-081', '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-081', '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 21:19:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 21:19:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:19:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:24 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:24 INFO - 'DCLOCATION': 'SCL3', 21:19:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:24 INFO - 'HOMEDRIVE': 'C:', 21:19:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:24 INFO - 'KTS_VERSION': '1.19c', 21:19:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:24 INFO - 'MOZ_NO_REMOTE': '1', 21:19:24 INFO - 'NO_EM_RESTART': '1', 21:19:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:24 INFO - 'OS': 'Windows_NT', 21:19:24 INFO - 'OURDRIVE': 'C:', 21:19:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:19:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:24 INFO - 'PROCESSOR_LEVEL': '6', 21:19:24 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:24 INFO - 'PROMPT': '$P$G', 21:19:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:24 INFO - 'PWD': 'C:\\slave\\test', 21:19:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:24 INFO - 'SYSTEMDRIVE': 'C:', 21:19:24 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:24 INFO - 'TEST1': 'testie', 21:19:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:24 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:24 INFO - 'USERNAME': 'cltbld', 21:19:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:24 INFO - 'WINDIR': 'C:\\windows', 21:19:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 21:19:25 INFO - Cleaning up... 21:19:25 INFO - Return code: 0 21:19:25 INFO - Running post-action listener: _resource_record_post_action 21:19:25 INFO - Running post-action listener: _start_resource_monitoring 21:19:25 INFO - Starting resource monitoring. 21:19:25 INFO - [mozharness: 2016-09-29 04:19:25.203000Z] Finished create-virtualenv step (success) 21:19:25 INFO - [mozharness: 2016-09-29 04:19:25.204000Z] Running install step. 21:19:25 INFO - Running pre-action listener: _resource_record_pre_action 21:19:25 INFO - Running main action method: install 21:19:25 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:19:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:19:25 INFO - Reading from file tmpfile_stdout 21:19:25 INFO - Using _rmtree_windows ... 21:19:25 INFO - Using _rmtree_windows ... 21:19:25 INFO - Detecting whether we're running mozinstall >=1.0... 21:19:25 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 21:19:25 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 21:19:25 INFO - Reading from file tmpfile_stdout 21:19:25 INFO - Output received: 21:19:25 INFO - Usage: mozinstall-script.py [options] installer 21:19:25 INFO - Options: 21:19:25 INFO - -h, --help show this help message and exit 21:19:25 INFO - -d DEST, --destination=DEST 21:19:25 INFO - Directory to install application into. [default: 21:19:25 INFO - "C:\slave\test"] 21:19:25 INFO - --app=APP Application being installed. [default: firefox] 21:19:25 INFO - Using _rmtree_windows ... 21:19:25 INFO - Using _rmtree_windows ... 21:19:25 INFO - mkdir: C:\slave\test\build\application 21:19:25 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'] 21:19:25 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 21:19:27 INFO - Reading from file tmpfile_stdout 21:19:27 INFO - Output received: 21:19:27 INFO - C:\slave\test\build\application\firefox\firefox.exe 21:19:27 INFO - Using _rmtree_windows ... 21:19:27 INFO - Using _rmtree_windows ... 21:19:27 INFO - Running post-action listener: _resource_record_post_action 21:19:27 INFO - [mozharness: 2016-09-29 04:19:27.693000Z] Finished install step (success) 21:19:27 INFO - [mozharness: 2016-09-29 04:19:27.693000Z] Running run-tests step. 21:19:27 INFO - Running pre-action listener: _resource_record_pre_action 21:19:27 INFO - Running main action method: run_tests 21:19:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 21:19:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 21:19:27 INFO - Python 2.7.3 21:19:27 INFO - Return code: 0 21:19:27 INFO - grabbing minidump binary from tooltool 21:19:27 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')]} 21:19:27 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 0x01974E90>, '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 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EBE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 21:19:27 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 21:19:27 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 21:19:27 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 21:19:27 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 21:19:28 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpvpc2vl 21:19:28 INFO - INFO - File integrity verified, renaming tmpvpc2vl to win32-minidump_stackwalk.exe 21:19:28 INFO - Return code: 0 21:19:28 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 21:19:28 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 21:19:28 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 21:19:28 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 21:19:28 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 21:19:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-081', '--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 21:19:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-081 --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 21:19:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:19:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:19:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:19:28 INFO - 'COMPUTERNAME': 'T-W732-IX-081', 21:19:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:19:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:19:28 INFO - 'DCLOCATION': 'SCL3', 21:19:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:19:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:19:28 INFO - 'HOMEDRIVE': 'C:', 21:19:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:19:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:19:28 INFO - 'KTS_VERSION': '1.19c', 21:19:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:19:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-081', 21:19:28 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:19:28 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 21:19:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:19:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:28 INFO - 'MOZ_NO_REMOTE': '1', 21:19:28 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:19:28 INFO - 'NO_EM_RESTART': '1', 21:19:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:19:28 INFO - 'OS': 'Windows_NT', 21:19:28 INFO - 'OURDRIVE': 'C:', 21:19:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:19:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:19:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:19:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:19:28 INFO - 'PROCESSOR_LEVEL': '6', 21:19:28 INFO - 'PROCESSOR_REVISION': '1e05', 21:19:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:19:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:19:28 INFO - 'PROMPT': '$P$G', 21:19:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:19:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:19:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:19:28 INFO - 'PWD': 'C:\\slave\\test', 21:19:28 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 21:19:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:19:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:19:28 INFO - 'SYSTEMDRIVE': 'C:', 21:19:28 INFO - 'SYSTEMROOT': 'C:\\windows', 21:19:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:28 INFO - 'TEST1': 'testie', 21:19:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:19:28 INFO - 'USERDOMAIN': 'T-W732-IX-081', 21:19:28 INFO - 'USERNAME': 'cltbld', 21:19:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:19:28 INFO - 'WINDIR': 'C:\\windows', 21:19:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:19:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:19:28 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-081', '--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 21:19:30 INFO - mozversion application_buildid: 20160928193620 21:19:30 INFO - mozversion application_changeset: be5121024c7c3f363b94de428eb795007697e016 21:19:30 INFO - mozversion application_display_name: Nightly 21:19:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:19:30 INFO - mozversion application_name: Firefox 21:19:30 INFO - mozversion application_remotingname: firefox 21:19:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:19:30 INFO - mozversion application_vendor: Mozilla 21:19:30 INFO - mozversion application_version: 52.0a1 21:19:30 INFO - mozversion platform_buildid: 20160928193620 21:19:30 INFO - mozversion platform_changeset: be5121024c7c3f363b94de428eb795007697e016 21:19:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:19:30 INFO - mozversion platform_version: 52.0a1 21:19:30 INFO - using testdate: 1475122770 21:19:30 INFO - actual date: 1475122770 21:19:30 INFO - starting webserver on 'localhost:49891' 21:19:30 INFO - SUITE-START | Running 2 tests 21:19:30 INFO - TEST-START | dromaeo_css 21:19:30 INFO - operating with platform_type : w7_ 21:19:30 INFO - Initialising browser for dromaeo_css test... 21:19:30 INFO - TEST-INFO | started process 3312 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmperdhho\profile http://localhost:49891/getInfo.html) 21:19:37 INFO - PROCESS | 3312 | __metrics Screen width/height:1600/1200 21:19:37 INFO - PROCESS | 3312 | colorDepth:24 21:19:37 INFO - PROCESS | 3312 | Browser inner width/height: 1010/674 21:19:37 INFO - PROCESS | 3312 | __metrics 21:19:38 INFO - PROCESS | 3312 | 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" 21:19:38 INFO - PROCESS | 3312 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:19:39 INFO - TEST-INFO | 3312: exit 0 21:19:39 INFO - Browser initialized. 21:19:39 INFO - Running cycle 1/1 for dromaeo_css test... 21:19:39 INFO - TEST-INFO | started process 3988 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmperdhho\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:39 INFO - PROCESS | 3988 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 21:19:50 INFO - PROCESS | 3988 | RSS: Main: 139735040 21:19:50 INFO - PROCESS | 3988 | 21:20:42 INFO - PROCESS | 3988 | 1475122842217 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] 21:22:55 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html) 21:22:56 INFO - PROCESS | 3988 | RSS: Main: 183586816 21:22:56 INFO - PROCESS | 3988 | 21:25:59 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html) 21:25:59 INFO - PROCESS | 3988 | RSS: Main: 202076160 21:25:59 INFO - PROCESS | 3988 | 21:29:02 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html) 21:29:02 INFO - PROCESS | 3988 | RSS: Main: 154746880 21:29:02 INFO - PROCESS | 3988 | 21:32:05 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html) 21:32:05 INFO - PROCESS | 3988 | RSS: Main: 151535616 21:32:05 INFO - PROCESS | 3988 | 21:35:08 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html) 21:35:09 INFO - PROCESS | 3988 | RSS: Main: 137453568 21:35:09 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html) 21:38:12 INFO - PROCESS | 3988 | RSS: Main: 188129280 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | __start_tp_report 21:38:12 INFO - PROCESS | 3988 | _x_x_mozilla_page_load 21:38:12 INFO - PROCESS | 3988 | _x_x_mozilla_page_load_details 21:38:12 INFO - PROCESS | 3988 | |i|pagename|runs| 21:38:12 INFO - PROCESS | 3988 | |0;dojo.html;2316.2703748319277;2347.9882600587;2373.489699714561;2402.0752010475962;2402.1952645863635;4065.00407400187;4101.42580038794;4139.9857035026425;4141.792910354486;4183.748975061499;3977.8209980550873;4027.919441611173;4037.3338399164104;4046.332355161395;4157.646600038993;3573.8927832165077;3602.189507360836;3625.8912232633065;3631.164832088621;3668.5781135169395;3525.9774665347018;3569.910752231202;3596.478510615972;3602.6937710294656;3680.9815950920206;3629.0927268182954;3649.6167902370134;3654.14127679995;3681.668649821529;3687.2441149564274;3531.4526248431534;3560.4659301104784;3603.3153700797025;3620.5474315710535;3669.9633003669887;3537.540119784437;3552.236269202134;3628.7097032237357;3635.000374896898;3640.071781695677;4035.556088830226;4036.9394459083137;4046.4941882264716;4087.8773636790943;4136.172765446892;4203;4235.02594403289;4248.893777655552;4299.548547402509;4371.650267978554;4240.703150779446;4251.319788833803;4415.161119387339;4420.138073075752;4421.6904816662845;3940.1134744682217;4009.959900400988;4017.276890159741;4025.9798701006894;4027.7986100695143;4199.811008504625;4265.232258193555;4357.106793107406;4359.847405340798;4440.4671439427475;4101.569335219759;4130.256553820281;4191.622753952159;4201.327787553977;4291.871243862689;4196.601322874322;4208.410822484854;4269.124829409963;4332.935005974913;4360.171567402183;4261.616454519108;4314.892127696833;4328.480582330078;4333.046729719456;4344.022594916119;4162.313218318944;4171.49942006955;4189.72766770159;4298.247806633889;4314.741115533078;2974.2259940280114;3012.7439167670555;3057.801242919203;3089.1041597936796;3104.7360974316985;2801.8739156737997;2812.0439050722853;2827.34970956677;2843.7440630342953;2861.728135827093;3950.1902110067367;3991.461152744342;4011.6590089842107;4069.7761623111014;4098.036961314089;4236.068065025689;4257.701960862772;4418.690691651618;4421.270490369053;4422.933655995163;3920.5295364556437;4065.654419374327;4066.512018557733;4069;4118.732282401635;3609.458581212839;3642.216923361437;3689.335919534456;3761.2853557824474;3766.4162054881535;4424.203643344229;4516.277395616686;4525.97737011326;4533.320001999727;4548.090381923695;4349.543297953669;4364.6944713871;4430.37974683551;4434.113177364605;4436.667249956202;4355.390245365588;4437.0460351024685;4471.971446567242;4480.507144214199;4546.727196368229;4208.747475151501;4212.178625168062;4253.680973927029;4256.744595324167;4290.95709042918;4029.4761680981283;4251.532331443477;4267.615914567704;4353.847615333448;4372.431583894074;3920;4140.171965606831;4218.240716671029;4228.471441069866;4238.809253583658;4093.6111069448357;4152.231837110145;4155.688323375698;4168.187203495289;4209.91580168401;4048.7570745755347;4064.349704047338;4082.8775136745944;4083.5303940047274;4092.2224777292217;4124.463819703419;4126.5667104953545;4138.79306034691;4197.643200328006;4301.139772045541;4069.3895915612893;4184.2468355696265;4185.016521117474;4213.9367909482;4222.007828160318 21:38:12 INFO - PROCESS | 3988 | |1;ext.html;527.0118527760449;530.9814156504523;532.8747744280096;539.1454544546895;559.5691317685385;25648.487030259384;36189.638103618956;36420.089497762536;37350;37412.81293593535;37126.257474850485;37336.62663373366;37544.24911501769;37805.24389512215;38041.42937855935;7545.962270188641;7578.39372850172;7733.149353571102;7740.9225907740765;7762.573058481781;6433.38882806135;6586.901196482057;6594.802155935329;6752.662366881661;6764.289749576273;9373.906260937405;9583;9602.567884445216;9963.850542241873;9971.451570163637;9641.373310734814;9824.754381140492;9887.653932112342;9902.653407130716;9904.356216845954;6389.744410223632;6577.967110164466;9092.318076144316;9307.3019523536;9552.90447095527;34470.48294275588;34936;35091.649083509095;35329;35421.82289088564;2595.1176599955916;2619.305883940757;2742.040285899939;2754.7520723135012;2769.2384594236746;25727.742722572722;25852.74147258522;25942.610860837103;26024.479510409874;26951.595726064126;2993.1469531183507;2996.9850150749326;3025.5007923692565;3030.8939187128344;3050.084974507639;224862;229488;230148.9999999983;230621;231014;44484.332735009004;44994.325085123746;45433.772831136295;46363.30455043177;46476.302855457194;42515.57484425116;44608.55391446044;45889;47256;48816.755916220194;10160.796784064278;10171.898281017095;10282;10546.789064218827;10569;7483.550986940801;7504.287092726182;7505.4746167767735;7514.849703005909;7523;11930.105242106876;12198.207116537396;12298.139130260975;12493.312867792358;14552.272386380639;5137.871553211125;5203.2195170723935;5211.765470553834;5219.530242278137;5263.421023687391;735.1362149474346;810.3638643664694;815.6778072661384;826.0417915218318;911.3210658059788;24450.755492445205;25375.87312063465;25438.618420723706;25575.61636575453;25834.741652583234;848.5290663681593;854.7051267312767;872.4242000279912;893.097971049245;896.3411892259185;13072.477100916047;13380.996425268144;13596.59210223695;13843.584692459242;13868.237246951312;295475;302690.48654757027;303330;303981;304042;42509;43214.78392608017;43553.128937421076;44182;44346;949.7815502434479;986.8421052631688;1074.6346242277664;1102.2174256277972;1105.3202280597536;23469;23754.574725516526;24183.274501764976;24567.754322456545;24675;38219;38702.419463707505;38733;38740.41889371605;38743.612563874;243988;252210;252361;253239;253267;19020.334288299844;19895.204191832163;19912.103955321756;20862.791372086085;20868.26961056356;37615.62384376121;37675.43486847754;38319.04202394962;38341;38372.42441363438;31217.68782312148;31703;32581.674183257863;32848.1787955303;33300.83349583237;31913;32387.838060809547;32644;32738;33082.66917330796;30771;30950.22624434407;31073.689263107983;31074.68925310718;31130.22174445657;27664;29386.706132938398;29450.558241625964;29537.55693664554;29924;21802.345929622137;23055;23460;23465.76534234704;23562.52874942526 21:38:12 INFO - PROCESS | 3988 | |2;jquery.html;285.13888058064657;306.7254806947781;310.82283098633786;315.9842007899606;321.2578942642495;11449.828252576208;12360.752784944307;14631.92684036581;15416.614584635361;15562.766558501631;14222.644433889132;14307.642308942282;14457.493987710406;14534.854651453483;14552.490662826804;4881.219004959216;5141.897162056775;5147.330846989886;5149.81975630853;5149.845504634867;3594.4608308753764;3769.736118471708;5095.898082038357;5177.430482646906;5180.430152683201;5455.863603409926;5694.715264236792;5715.199872017921;5820.097884827837;5834.737436815353;5624.15637654351;5793.391693872146;5862.853428664275;5968.67172305523;5977.043673012341;4937.629677774145;4949.7772600233075;5054.772535235923;5087.49125087492;5088.618353623493;19807;19976.101075451494;20180.09189586472;20343.69484457734;20347.08438120288;1738.0353903583507;1749.667563162995;1802.044916194419;1850.3431281894868;1908.18901966665;8141.633626486822;8336.666533338659;8600.397972141953;8666.436681615674;8669.869951950726;1646.7282898321882;1671.4651311580178;1696.3808210003383;1708.3422882190323;1738.7044202485608;64915.67542162259;67953.3204667952;68217.65891170512;68897.65551172262;70250.29749702489;8744.125587441333;8932;8942.86585701215;9218.723438296862;9251.953740231256;10588.629397971034;10937.671869843918;11002.174836887265;11604.88395116038;12097;5935.614185077956;5977.282726072899;5988.101784732325;6082.756689732361;6115.388461153938;5566.35986861508;5735.942640573541;5737.627054241544;5764.596478246566;5771.480566748929;3725.9068523287133;3806.6764325032127;3824.29250588642;3877.235857684747;3888.2612303662213;1630.5678995066316;1639.213177674699;1643.9342426303053;1647.0858673436119;1663.9584010399838;762.3596179209491;773.5088218980875;811.2090711556185;818.0306336990685;860.7891066688701;15879.761803572956;16204.513864584082;16404.179791010403;16806.915965420096;16826.41107561254;760.7147319755092;761.5735188294462;783.3811289081561;787.1577412168924;818.6111596991382;9019.233365164035;9145.588448519833;9290.442573445614;9399.718008459757;9517.381370210915;274072;281715;282862;283411;284023;64327;72576.63711681408;72934.63532682303;73314;75038.62480687561;787.1498781316164;800.651716503323;815.9143289954588;816.3306089006958;817.3706246190519;14270.500532481314;14287.487032358265;14714;14778.113313201242;14893.77659335089;36497.27005459823;36604.81697591495;37050.44424333691;37166.44250336193;37458.438123428714;49001.05993640393;49511.752441237564;50135;50148;51075.48924510707;6954.443644508528;6996.230414654486;7046.295370462913;7047.823804404726;7081.362677359062;7936.880946785687;10714.785704286027;10869.347839129361;10975.780484390109;11139.387333696724;8269.751907442786;8371.748847534584;8468.49189048659;8469.449485783405;8716.041235463967;9459;9547.283953703361;9599.760005999906;9835.606575737176;10074.14369778577;9125.771855703395;9173.678921237724;9261;9375.109364610624;9409.576569054272;6839;6863.965680171567;6890.10428644273;6991.755288564876;7056.435485161072;8809;8910.064443233627;8919.866202006846;9068.183863452321;9141 21:38:12 INFO - PROCESS | 3988 | |3;mootools.html;1067.9572817087317;1203.4163430736091;1207.8550573931127;1240.8200810882422;1521.064545304638;5791.449812267833;6342.492600591953;6505.90241146382;6505.934940650592;6540.888049031676;6275.341089185638;6314.557980941336;6499.772507962222;6509.153810004693;6515.348465153483;1834.385480863909;1868.9626207475842;1869.4858913798746;1870.4482177757527;1871.5695390060293;1788.1416919878466;1809.8914065156066;1815.4009176971567;1821.581036361631;1866.2348437140774;1789.53472097255;1824.7171688388255;1837.1181832720301;1837.7335286383466;1839.5309196154963;1682.7812384390074;1703.5144983679716;1707.581642497596;1710.324421853361;1749.9737503937451;1692.957676058096;1710.1021963469154;1714.9314027438888;1740.6605711886186;1761.788585369751;6747.763828265987;6799.286074962107;6802.047713320139;6833.350831670983;6834.077399551046;3712.4988126602834;3857.0550215197177;3857.228554289153;3869.438931354966;3889.86385476507;2543.3895864992455;2561.052410608087;2591.688997320334;2591.8444893306273;2597.4155814941487;724.7572063358781;755.3805879179075;773.3272053313597;774.0672489649959;776.3633820267383;8109.675612975474;8332.125126861714;8332.416730828845;8401.873971890427;8598.355123365773;3266.9382450703615;3270.967290327066;3276.0499455157737;3308.7353011759;3326.9168270793425;6772.93227067723;6873.725050997904;6874.731338658231;6875.209350924708;6899.206591241971;3662.1027848176836;3674.375356189401;3678.9632103679155;3682.410814269704;3688.8155592220282;3074.0777766669908;3172.2386627209303;3480.095175254452;3484.77348972316;3485.4249048907154;2643.867806609662;2710.8102432829514;2729.222171681101;2766.156322321671;2922.0795449433363;845.8223773007737;868.7828042989253;886.6763631274538;886.9755432475501;888.587146437168;703.4723957032226;714.4998501049182;714.5998240985065;716.8064622551882;729.5075823818901;4246.299360605465;4257;4283.464566929134;4310.741355518679;4332.740035597874;754.2306847016013;756.5763172623349;774.1136398823315;789.921007899217;822.1408627983772;2835.0502581635383;2920.2115428834095;2950.8967186148384;3035.2715348316246;3084.0439463766306;7224.313690199423;7964.402669799788;8128.634211460499;8248.092709801916;8271.669133234724;7418.814529636802;7567.810804729926;7727.76355783072;7734.4585878989055;8044.477108987897;5983.461488466059;6160.969195153995;6170.7223174957235;6175.517875709797;6179.6910154493;4305.375720520445;4379.627731642846;4398.450193725785;4407.779611019372;4422.624076953366;7036.542624729581;7119.110111236096;7340.522866013692;7341.889871651822;7350.301721336465;7061.788146355618;7205.567665940061;7263.491555591269;7271.818204544928;7289.5261807982315;5125.384953805567;5317.627766056339;5324.8136315228785;5334.359876814807;5339.172428273624;6129.754809807558;6159.876802464015;6188.4739797117745;6197.225346831646;6244.344343843831;5618.971905140448;5631.63394379364;5638;5710.914336285042;5713.542916566747;5378.112611419072;5465.863353416196;5522.475364840334;5548.472895074961;5571.3592936811165;5181.3782346118705;5257.449052529437;5270.97216042868;5328.62699611039;5368.489993450616;3676.136108014561;3683.447482877483;3693.8707145249787;3702.315071711742;3766.4350347448103;3716.3867961786004;3733.51464309638;3804.6005169456807;3849.364854798927;3881.0685435495843 21:38:12 INFO - PROCESS | 3988 | |4;prototype.html;235.21086753940517;241.1235160192699;243.73676429456182;246.61651132488984;251.2612918021018;4418.33724941259;4481.215787237236;4484.282514797633;4498.662600304978;4508.436445444319;4237;4291.828326866922;4353.978230108853;4424.0930609225115;4439.356293337464;3262;3313.453280208763;3321.983390083058;3322.401967645823;3328.2859327446386;3243.383757086146;3326.6673332666783;3327.8502467389026;3330.9681094532316;3336.883209087682;3115.9688403115906;3173.5398367236735;3214.517822326646;3291.226561757985;3305.256317328594;3213.742900567949;3283.4746440569515;3290.946896992963;3294.2423242654204;3300.339932013607;2837.404145129525;2889.9133026009254;2890.4363649088436;2895.5367141257507;2903.767698584108;3999.8800035998966;4309.892252693677;4338.544452832471;4343.348497725335;4348.934765978513;3840.1551658635335;3855.6697939210926;3872.380419132953;3878.3600705883496;3878.825452854628;4342.956570434319;4597.5517711920975;4612.192866248393;4618.422697162855;4628.583427491509;3111.1133327001694;3133.310671652233;3138;3142.607174103237;3144.0253521408436;4696.577308042292;4771.498992605796;4811.109944660214;4811.604634655946;4831.251156070814;3166.4458719723957;3202.8718851246153;3204.150900011499;3209.502527108302;3210.502372132323;3194.105650417887;3228.790128641631;3240.157559034621;3245.8052516849066;3248.5776849009476;3543.114221444639;3774.0375004997795;3775.2826962876966;3775.5091838060875;3791.4123310886857;4521.638268938477;4564.543545645475;4567.748773817472;4640.6751527393435;4644.326572646947;1107.6898468428692;1116.7598966222301;1120.1990576737671;1120.9159313051555;1122.4892673833385;678.8472593666386;693.9791806245821;726.4805663950301;744.8733715268418;747.6822350501016;4333.978330108329;4379.430674012358;4395.692301538926;4397.472303323621;4401.405810215644;4711.926752229008;4725.031368569435;4727.456342520585;4735.739534325577;4739.146953548326;4308.6983911126545;4320.071184695305;4360.542143074995;4385.473743150842;4386.8903277417685;2414.3481260059684;2457.987710061438;2510.2720211138567;2513.5852584323748;2540.5173017126685;4875.390576177978;4893.070316639827;4915.48387419323;4919.237518184687;4924.556789888928;4852.369192005017;4921.532454416824;4921.9015619688125;4923.458419573844;4932.1122198004705;4892.290617860462;4908.779104940286;4924.9507504924495;4926.8768280793265;4933.975330123399;4611.100835337077;4779.354787103696;4813.4356334192225;4826.8069277228515;4827.927581086216;4656.115338085753;4711.199096153594;4711.740854252912;4713.08094921487;4720.480747117883;4825.565699087099;4924.458309586014;4926.310316555757;4931.556159945622;4947.406311242674;3169.3661267746083;3205.333226722138;3218.1954511372155;3223.4681277588934;3226.6773322667545;4522.072975040174;4723.889885876885;4752.287156926351;4760.714357138583;4776.617870570415;4620.722756634613;4626.537346265482;4628.5371462853445;4631.282151266559;4634.72191668501;4498.987727761228;4589.26571748532;4620.283856002325;4623.260278355447;4630.212863813093;4436.245838207577;4448.1548505785095;4554.27131658933;4555.676604931443;4559.977200113978;4402.383666286786;4591.127685739699;4617.422822147231;4658.743769092733;4695.859124226278;4270.50889147752;4348.7390756554705;4428.31361139024;4486.327050942255;4616.6306695464955 21:38:12 INFO - PROCESS | 3988 | |5;yui.html;551.9503244707976;575.8416435480242;620.4447019917171;639.1786554277751;640.3020707428901;12424.565140220095;13284.535041273557;13315.733685326288;13331.93334033331;13346.399412026458;11892.762144757122;12174.269543827377;12178.878211217885;12231.08266879983;12375.319357435337;494.39189796550266;511.87970826855843;598.1804927249674;636.0872148466965;639.309545690653;499.7226539270703;505.6612069913149;514.5883293365292;629.0909635576594;648.3840351665913;670.574185392277;670.9496787740939;672.4284358295458;679.3546131175378;682.4199430484074;664.8304682305981;665.8768127896354;675.4360109308733;678.3149019490301;683.3200965039817;389.9746516476448;391.8413042717704;492.83982705620457;495.9553640172402;503.506563567702;11453.942730286379;11557.595484158013;11731.413429328499;11754.177207595472;11754.588589399416;11472;11575.768484630344;11627.418629068512;11906.28562286257;11916.582919597859;9687.467189304585;10375.42935138567;10629.362238265729;10663.733406664818;10701.182124646886;3085.444619968405;3198.184462961953;3820.3887378019385;4014.498187726534;4198.097409057037;57169.999050033046;57990.710046449494;58112.41887581155;59626;63921.68039159774;8967.327450441244;9003.234725048313;9016.098390161063;9126.680566180152;9689.370190937567;15439.691206176038;15773.684526309638;15797.289121989537;15841.920790396205;15846.92076539633;6457.3865482779065;6485.0272459130565;6596;6615.834604134839;6670.666466676646;3461.8788342407893;3468.271662950758;3559.982200088983;3609.6931760800103;3677.42999835026;2611.4907593019557;2703.39173685923;2703.9729602704115;2708.851013194254;2712.91861244163;466.65001249063204;467.70534563224953;474.7649913292873;477.77544554059534;479.714569830957;472.2561964905288;497.5397757074691;505.61067977657;535.2426316761738;589.4164776870867;7537.2085930977555;7662.9654996574745;7686;7834.294913457702;7858.939043229091;440.8721470773504;540.8485624025279;549.9797874942005;550.0319437789519;585.2070444547662;3655.5247817784048;3837.0791010157363;3842.9231415371537;3845.576986531479;3851.1334949636107;86996;89531.10468895358;91126;91492.15902899003;91511;79686.20313796788;82078.58960705278;82862.58568707117;84385.15614843895;84996.15003850005;73583;74131.25868741244;74276;74553.62723186349;76613.99999999888;7274.8908766367485;7308.59802710856;7310.415166786538;7430.034095567542;7523.73666921677;43138.13723725571;43813.34279985739;44746.3288050673;45011.32483012823;45311.41410050632;51204;52500;59886.40113598808;61424.69287653533;63524.364756353694;9923.255755818198;9932.216766484862;10003.099721025143;10064.245181611263;10073.093421592092;12000.219985700902;12029.398530073286;12067.215630983957;12194.878051219373;12268.570600028956;11516.974217512825;11553.768924621292;11631;11776.470058847544;11890.583829565923;11393.829092563785;11574.826377604511;11822.704432389259;11859.525618975144;11997.520099195934;11191;11233.38216398106;11250.381229032482;11300.547978080785;11607.941960290145;10158.441285729356;10262.332948358333;10429.322094063862;10808.351498909777;10818;9985;10013.899861001588;10020;10097.242706796873;10114.848277275994 21:38:12 INFO - PROCESS | 3988 | __end_tp_report 21:38:12 INFO - PROCESS | 3988 | __start_cc_report 21:38:12 INFO - PROCESS | 3988 | _x_x_mozilla_cycle_collect,880 21:38:12 INFO - PROCESS | 3988 | __end_cc_report 21:38:12 INFO - PROCESS | 3988 | __startTimestamp1475123892729__endTimestamp 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | ------- Summary: start ------- 21:38:12 INFO - PROCESS | 3988 | Number of tests: 6 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#0] dojo.html Cycles:165 Average:3976.73 Median:4130.26 stddev:476.93 (11.5%) stddev-sans-first:460.26 21:38:12 INFO - PROCESS | 3988 | Values: 2316.3 2348.0 2373.5 2402.1 2402.2 4065.0 4101.4 4140.0 4141.8 4183.7 3977.8 4027.9 4037.3 4046.3 4157.6 3573.9 3602.2 3625.9 3631.2 3668.6 3526.0 3569.9 3596.5 3602.7 3681.0 3629.1 3649.6 3654.1 3681.7 3687.2 3531.5 3560.5 3603.3 3620.5 3670.0 3537.5 3552.2 3628.7 3635.0 3640.1 4035.6 4036.9 4046.5 4087.9 4136.2 4203.0 4235.0 4248.9 4299.5 4371.7 4240.7 4251.3 4415.2 4420.1 4421.7 3940.1 4010.0 4017.3 4026.0 4027.8 4199.8 4265.2 4357.1 4359.8 4440.5 4101.6 4130.3 4191.6 4201.3 4291.9 4196.6 4208.4 4269.1 4332.9 4360.2 4261.6 4314.9 4328.5 4333.0 4344.0 4162.3 4171.5 4189.7 4298.2 4314.7 2974.2 3012.7 3057.8 3089.1 3104.7 2801.9 2812.0 2827.3 2843.7 2861.7 3950.2 3991.5 4011.7 4069.8 4098.0 4236.1 4257.7 4418.7 4421.3 4422.9 3920.5 4065.7 4066.5 4069.0 4118.7 3609.5 3642.2 3689.3 3761.3 3766.4 4424.2 4516.3 4526.0 4533.3 4548.1 4349.5 4364.7 4430.4 4434.1 4436.7 4355.4 4437.0 4472.0 4480.5 4546.7 4208.7 4212.2 4253.7 4256.7 4291.0 4029.5 4251.5 4267.6 4353.8 4372.4 3920.0 4140.2 4218.2 4228.5 4238.8 4093.6 4152.2 4155.7 4168.2 4209.9 4048.8 4064.3 4082.9 4083.5 4092.2 4124.5 4126.6 4138.8 4197.6 4301.1 4069.4 4184.2 4185.0 4213.9 4222.0 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#1] ext.html Cycles:180 Average:40142.24 Median:35013.82 stddev:68762.75 (196.4%) stddev-sans-first:68891.31 21:38:12 INFO - PROCESS | 3988 | Values: 527.0 531.0 532.9 539.1 559.6 25648.5 36189.6 36420.1 37350.0 37412.8 37126.3 37336.6 37544.2 37805.2 38041.4 7546.0 7578.4 7733.1 7740.9 7762.6 6433.4 6586.9 6594.8 6752.7 6764.3 9373.9 9583.0 9602.6 9963.9 9971.5 9641.4 9824.8 9887.7 9902.7 9904.4 6389.7 6578.0 9092.3 9307.3 9552.9 34470.5 34936.0 35091.6 35329.0 35421.8 2595.1 2619.3 2742.0 2754.8 2769.2 25727.7 25852.7 25942.6 26024.5 26951.6 2993.1 2997.0 3025.5 3030.9 3050.1 224862.0 229488.0 230149.0 230621.0 231014.0 44484.3 44994.3 45433.8 46363.3 46476.3 42515.6 44608.6 45889.0 47256.0 48816.8 10160.8 10171.9 10282.0 10546.8 10569.0 7483.6 7504.3 7505.5 7514.8 7523.0 11930.1 12198.2 12298.1 12493.3 14552.3 5137.9 5203.2 5211.8 5219.5 5263.4 735.1 810.4 815.7 826.0 911.3 24450.8 25375.9 25438.6 25575.6 25834.7 848.5 854.7 872.4 893.1 896.3 13072.5 13381.0 13596.6 13843.6 13868.2 295475.0 302690.5 303330.0 303981.0 304042.0 42509.0 43214.8 43553.1 44182.0 44346.0 949.8 986.8 1074.6 1102.2 1105.3 23469.0 23754.6 24183.3 24567.8 24675.0 38219.0 38702.4 38733.0 38740.4 38743.6 243988.0 252210.0 252361.0 253239.0 253267.0 19020.3 19895.2 19912.1 20862.8 20868.3 37615.6 37675.4 38319.0 38341.0 38372.4 31217.7 31703.0 32581.7 32848.2 33300.8 31913.0 32387.8 32644.0 32738.0 33082.7 30771.0 30950.2 31073.7 31074.7 31130.2 27664.0 29386.7 29450.6 29537.6 29924.0 21802.3 23055.0 23460.0 23465.8 23562.5 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#2] jquery.html Cycles:180 Average:20433.83 Median:5511.11 stddev:47312.38 (858.5%) stddev-sans-first:47420.92 21:38:12 INFO - PROCESS | 3988 | Values: 285.1 306.7 310.8 316.0 321.3 11449.8 12360.8 14631.9 15416.6 15562.8 14222.6 14307.6 14457.5 14534.9 14552.5 4881.2 5141.9 5147.3 5149.8 5149.8 3594.5 3769.7 5095.9 5177.4 5180.4 5455.9 5694.7 5715.2 5820.1 5834.7 5624.2 5793.4 5862.9 5968.7 5977.0 4937.6 4949.8 5054.8 5087.5 5088.6 19807.0 19976.1 20180.1 20343.7 20347.1 1738.0 1749.7 1802.0 1850.3 1908.2 8141.6 8336.7 8600.4 8666.4 8669.9 1646.7 1671.5 1696.4 1708.3 1738.7 64915.7 67953.3 68217.7 68897.7 70250.3 8744.1 8932.0 8942.9 9218.7 9252.0 10588.6 10937.7 11002.2 11604.9 12097.0 5935.6 5977.3 5988.1 6082.8 6115.4 5566.4 5735.9 5737.6 5764.6 5771.5 3725.9 3806.7 3824.3 3877.2 3888.3 1630.6 1639.2 1643.9 1647.1 1664.0 762.4 773.5 811.2 818.0 860.8 15879.8 16204.5 16404.2 16806.9 16826.4 760.7 761.6 783.4 787.2 818.6 9019.2 9145.6 9290.4 9399.7 9517.4 274072.0 281715.0 282862.0 283411.0 284023.0 64327.0 72576.6 72934.6 73314.0 75038.6 787.1 800.7 815.9 816.3 817.4 14270.5 14287.5 14714.0 14778.1 14893.8 36497.3 36604.8 37050.4 37166.4 37458.4 49001.1 49511.8 50135.0 50148.0 51075.5 6954.4 6996.2 7046.3 7047.8 7081.4 7936.9 10714.8 10869.3 10975.8 11139.4 8269.8 8371.7 8468.5 8469.4 8716.0 9459.0 9547.3 9599.8 9835.6 10074.1 9125.8 9173.7 9261.0 9375.1 9409.6 6839.0 6864.0 6890.1 6991.8 7056.4 8809.0 8910.1 8919.9 9068.2 9141.0 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#3] mootools.html Cycles:180 Average:4191.15 Median:5326.72 stddev:2321.17 (43.6%) stddev-sans-first:2315.81 21:38:12 INFO - PROCESS | 3988 | Values: 1068.0 1203.4 1207.9 1240.8 1521.1 5791.4 6342.5 6505.9 6505.9 6540.9 6275.3 6314.6 6499.8 6509.2 6515.3 1834.4 1869.0 1869.5 1870.4 1871.6 1788.1 1809.9 1815.4 1821.6 1866.2 1789.5 1824.7 1837.1 1837.7 1839.5 1682.8 1703.5 1707.6 1710.3 1750.0 1693.0 1710.1 1714.9 1740.7 1761.8 6747.8 6799.3 6802.0 6833.4 6834.1 3712.5 3857.1 3857.2 3869.4 3889.9 2543.4 2561.1 2591.7 2591.8 2597.4 724.8 755.4 773.3 774.1 776.4 8109.7 8332.1 8332.4 8401.9 8598.4 3266.9 3271.0 3276.0 3308.7 3326.9 6772.9 6873.7 6874.7 6875.2 6899.2 3662.1 3674.4 3679.0 3682.4 3688.8 3074.1 3172.2 3480.1 3484.8 3485.4 2643.9 2710.8 2729.2 2766.2 2922.1 845.8 868.8 886.7 887.0 888.6 703.5 714.5 714.6 716.8 729.5 4246.3 4257.0 4283.5 4310.7 4332.7 754.2 756.6 774.1 789.9 822.1 2835.1 2920.2 2950.9 3035.3 3084.0 7224.3 7964.4 8128.6 8248.1 8271.7 7418.8 7567.8 7727.8 7734.5 8044.5 5983.5 6161.0 6170.7 6175.5 6179.7 4305.4 4379.6 4398.5 4407.8 4422.6 7036.5 7119.1 7340.5 7341.9 7350.3 7061.8 7205.6 7263.5 7271.8 7289.5 5125.4 5317.6 5324.8 5334.4 5339.2 6129.8 6159.9 6188.5 6197.2 6244.3 5619.0 5631.6 5638.0 5710.9 5713.5 5378.1 5465.9 5522.5 5548.5 5571.4 5181.4 5257.4 5271.0 5328.6 5368.5 3676.1 3683.4 3693.9 3702.3 3766.4 3716.4 3733.5 3804.6 3849.4 3881.1 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#4] prototype.html Cycles:180 Average:3833.59 Median:4401.89 stddev:1176.69 (26.7%) stddev-sans-first:1148.58 21:38:12 INFO - PROCESS | 3988 | Values: 235.2 241.1 243.7 246.6 251.3 4418.3 4481.2 4484.3 4498.7 4508.4 4237.0 4291.8 4354.0 4424.1 4439.4 3262.0 3313.5 3322.0 3322.4 3328.3 3243.4 3326.7 3327.9 3331.0 3336.9 3116.0 3173.5 3214.5 3291.2 3305.3 3213.7 3283.5 3290.9 3294.2 3300.3 2837.4 2889.9 2890.4 2895.5 2903.8 3999.9 4309.9 4338.5 4343.3 4348.9 3840.2 3855.7 3872.4 3878.4 3878.8 4343.0 4597.6 4612.2 4618.4 4628.6 3111.1 3133.3 3138.0 3142.6 3144.0 4696.6 4771.5 4811.1 4811.6 4831.3 3166.4 3202.9 3204.2 3209.5 3210.5 3194.1 3228.8 3240.2 3245.8 3248.6 3543.1 3774.0 3775.3 3775.5 3791.4 4521.6 4564.5 4567.7 4640.7 4644.3 1107.7 1116.8 1120.2 1120.9 1122.5 678.8 694.0 726.5 744.9 747.7 4334.0 4379.4 4395.7 4397.5 4401.4 4711.9 4725.0 4727.5 4735.7 4739.1 4308.7 4320.1 4360.5 4385.5 4386.9 2414.3 2458.0 2510.3 2513.6 2540.5 4875.4 4893.1 4915.5 4919.2 4924.6 4852.4 4921.5 4921.9 4923.5 4932.1 4892.3 4908.8 4925.0 4926.9 4934.0 4611.1 4779.4 4813.4 4826.8 4827.9 4656.1 4711.2 4711.7 4713.1 4720.5 4825.6 4924.5 4926.3 4931.6 4947.4 3169.4 3205.3 3218.2 3223.5 3226.7 4522.1 4723.9 4752.3 4760.7 4776.6 4620.7 4626.5 4628.5 4631.3 4634.7 4499.0 4589.3 4620.3 4623.3 4630.2 4436.2 4448.2 4554.3 4555.7 4560.0 4402.4 4591.1 4617.4 4658.7 4695.9 4270.5 4348.7 4428.3 4486.3 4616.6 21:38:12 INFO - PROCESS | 3988 | 21:38:12 INFO - PROCESS | 3988 | [#5] yui.html Cycles:180 Average:16967.26 Median:469.98 stddev:24307.03 (5171.9%) stddev-sans-first:24343.97 21:38:12 INFO - PROCESS | 3988 | Values: 552.0 575.8 620.4 639.2 640.3 12424.6 13284.5 13315.7 13331.9 13346.4 11892.8 12174.3 12178.9 12231.1 12375.3 494.4 511.9 598.2 636.1 639.3 499.7 505.7 514.6 629.1 648.4 670.6 670.9 672.4 679.4 682.4 664.8 665.9 675.4 678.3 683.3 390.0 391.8 492.8 496.0 503.5 11453.9 11557.6 11731.4 11754.2 11754.6 11472.0 11575.8 11627.4 11906.3 11916.6 9687.5 10375.4 10629.4 10663.7 10701.2 3085.4 3198.2 3820.4 4014.5 4198.1 57170.0 57990.7 58112.4 59626.0 63921.7 8967.3 9003.2 9016.1 9126.7 9689.4 15439.7 15773.7 15797.3 15841.9 15846.9 6457.4 6485.0 6596.0 6615.8 6670.7 3461.9 3468.3 3560.0 3609.7 3677.4 2611.5 2703.4 2704.0 2708.9 2712.9 466.7 467.7 474.8 477.8 479.7 472.3 497.5 505.6 535.2 589.4 7537.2 7663.0 7686.0 7834.3 7858.9 440.9 540.8 550.0 550.0 585.2 3655.5 3837.1 3842.9 3845.6 3851.1 86996.0 89531.1 91126.0 91492.2 91511.0 79686.2 82078.6 82862.6 84385.2 84996.2 73583.0 74131.3 74276.0 74553.6 76614.0 7274.9 7308.6 7310.4 7430.0 7523.7 43138.1 43813.3 44746.3 45011.3 45311.4 51204.0 52500.0 59886.4 61424.7 63524.4 9923.3 9932.2 10003.1 10064.2 10073.1 12000.2 12029.4 12067.2 12194.9 12268.6 11517.0 11553.8 11631.0 11776.5 11890.6 11393.8 11574.8 11822.7 11859.5 11997.5 11191.0 11233.4 11250.4 11300.5 11607.9 10158.4 10262.3 10429.3 10808.4 10818.0 9985.0 10013.9 10020.0 10097.2 10114.8 21:38:12 INFO - PROCESS | 3988 | -------- Summary: end -------- 21:38:12 INFO - PROCESS | 3988 | 21:38:13 INFO - TEST-INFO | 3988: exit 0 21:38:14 INFO - TEST-OK | dromaeo_css | took 1123956ms 21:38:14 INFO - TEST-START | kraken 21:38:14 INFO - operating with platform_type : w7_ 21:38:14 INFO - Initialising browser for kraken test... 21:38:14 INFO - TEST-INFO | started process 1620 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkosl6c\profile http://localhost:49891/getInfo.html) 21:38:18 INFO - PROCESS | 1620 | __metrics Screen width/height:1600/1200 21:38:18 INFO - PROCESS | 1620 | colorDepth:24 21:38:18 INFO - PROCESS | 1620 | Browser inner width/height: 1010/674 21:38:18 INFO - PROCESS | 1620 | __metrics 21:38:19 INFO - PROCESS | 1620 | 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" 21:38:19 INFO - PROCESS | 1620 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:38:19 INFO - PROCESS | 1620 | console.error: 21:38:19 INFO - PROCESS | 1620 | Message: Error: Connection closed before committing the transaction. 21:38:19 INFO - PROCESS | 1620 | Stack: 21:38:19 INFO - PROCESS | 1620 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 21:38:19 INFO - PROCESS | 1620 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 21:38:19 INFO - PROCESS | 1620 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 21:38:19 INFO - PROCESS | 1620 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 21:38:19 INFO - PROCESS | 1620 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 21:38:19 INFO - PROCESS | 1620 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 21:38:19 INFO - PROCESS | 1620 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 21:38:19 INFO - PROCESS | 1620 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 21:38:19 INFO - PROCESS | 1620 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 21:38:19 INFO - PROCESS | 1620 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 21:38:19 INFO - PROCESS | 1620 | Barrier/this.client.addBlocker/promise13.2% 21:39:20 INFO - TinderboxPrint: I/O read bytes / time
70,495,744 / 925,670 21:39:20 INFO - TinderboxPrint: I/O write bytes / time
472,690,176 / 3,321,850 21:39:20 INFO - TinderboxPrint: CPU idle
8,301.4 (86.8%) 21:39:20 INFO - TinderboxPrint: CPU user
1,221.8 (12.8%) 21:39:20 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 0; Write bytes: 38070784; Read time: 0; Write time: 40950 21:39:20 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 70495744; Write bytes: 398147072; Read time: 925670; Write time: 2838240 21:39:21 INFO - Running post-run listener: _upload_blobber_files 21:39:21 INFO - Blob upload gear active. 21:39:21 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 21:39:21 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 21:39:21 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', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 21:39:21 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 Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 21:39:21 INFO - (blobuploader) - INFO - Open directory for files ... 21:39:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 21:39:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:22 INFO - (blobuploader) - INFO - Done attempting. 21:39:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 21:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:23 INFO - (blobuploader) - INFO - Done attempting. 21:39:23 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 21:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:31 INFO - (blobuploader) - INFO - Done attempting. 21:39:31 INFO - (blobuploader) - INFO - Iteration through files over. 21:39:31 INFO - Return code: 0 21:39:31 INFO - rmtree: C:\slave\test\build\uploaded_files.json 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 21:39:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b73017f5c690556b3d108c5d4a589fba3d855151d3a56057f11e3ea8fd512d7c4905a04b100475cc7b6de0dc37771f699522ad6e602254054a63af650374fa7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b3f8b5c2f88865537cc71936813c683b761bd6fe763fba3cbe8fc38665454b6727fed68012ec996a925bff1c859583fc6b6ed080f58fb91b725980ebd951e14d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94b9142ab71ad67595f91e8a38c228b82f1d143b51d997bc3309ed6cc23c0d064322ee75b86bc60aa6e59d61d7a8b4328b8f6b6e7835556029cd3eca5070c"} 21:39:31 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 21:39:31 INFO - Writing to file C:\slave\test\properties\blobber_files 21:39:31 INFO - Contents: 21:39:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b73017f5c690556b3d108c5d4a589fba3d855151d3a56057f11e3ea8fd512d7c4905a04b100475cc7b6de0dc37771f699522ad6e602254054a63af650374fa7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b3f8b5c2f88865537cc71936813c683b761bd6fe763fba3cbe8fc38665454b6727fed68012ec996a925bff1c859583fc6b6ed080f58fb91b725980ebd951e14d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94b9142ab71ad67595f91e8a38c228b82f1d143b51d997bc3309ed6cc23c0d064322ee75b86bc60aa6e59d61d7a8b4328b8f6b6e7835556029cd3eca5070c"} 21:39:31 INFO - Running post-run listener: copy_logs_to_upload_dir 21:39:31 INFO - Copying logs to upload dir... 21:39:31 INFO - mkdir: C:\slave\test\build\upload\logs 21:39:31 INFO - Copying logs to upload dir... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... 21:39:31 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1418.672000 ========= master_lag: 0.09 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 38 secs) (at 2016-09-28 21:39:31.862343) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 21:39:31.869007) ========= '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-081 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-081 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-081 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/Autoland-Non-PGO/sha512/b73017f5c690556b3d108c5d4a589fba3d855151d3a56057f11e3ea8fd512d7c4905a04b100475cc7b6de0dc37771f699522ad6e602254054a63af650374fa7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b3f8b5c2f88865537cc71936813c683b761bd6fe763fba3cbe8fc38665454b6727fed68012ec996a925bff1c859583fc6b6ed080f58fb91b725980ebd951e14d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94b9142ab71ad67595f91e8a38c228b82f1d143b51d997bc3309ed6cc23c0d064322ee75b86bc60aa6e59d61d7a8b4328b8f6b6e7835556029cd3eca5070c"} build_url:https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/CGl7cOU-RruJ8RHHe3izBA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b73017f5c690556b3d108c5d4a589fba3d855151d3a56057f11e3ea8fd512d7c4905a04b100475cc7b6de0dc37771f699522ad6e602254054a63af650374fa7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/b3f8b5c2f88865537cc71936813c683b761bd6fe763fba3cbe8fc38665454b6727fed68012ec996a925bff1c859583fc6b6ed080f58fb91b725980ebd951e14d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/31d94b9142ab71ad67595f91e8a38c228b82f1d143b51d997bc3309ed6cc23c0d064322ee75b86bc60aa6e59d61d7a8b4328b8f6b6e7835556029cd3eca5070c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 21:39:31.982920) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 21:39:31.983271) ========= '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-081 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-081 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-081 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-09-28 21:39:32.095066) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 21:39:32.095373) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 21:39:32.656838) ========= ========= Total master_lag: 0.42 =========