builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-093 starttime: 1480078226.76 results: success (0) buildid: 20161125030214 builduid: 9f4746ed4f204cb699934983bdc943b7 revision: bad312aefb42982f492ad2cf36f4c6c3d698f4f7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:26.757648) ========= master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:26.758104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:26.758425) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:26.902882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-25 04:50:26.903265) ========= '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-093 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-093 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-093 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 --04:50:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 04:50:28 (2.27 GB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.218000 ========= master_lag: 2.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-25 04:50:30.309335) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-11-25 04:50:30.309636) ========= '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-093 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-093 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-093 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=14.001000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-11-25 04:50:44.329564) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-25 04:50:44.330045) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev bad312aefb42982f492ad2cf36f4c6c3d698f4f7 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bad312aefb42982f492ad2cf36f4c6c3d698f4f7 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-093 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-093 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-093 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-25 04:50:45,023 truncating revision to first 12 chars 2016-11-25 04:50:45,023 Setting DEBUG logging. 2016-11-25 04:50:45,023 attempt 1/10 2016-11-25 04:50:45,023 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bad312aefb42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 04:50:45,540 unpacking tar archive at: mozilla-central-bad312aefb42/testing/mozharness/ 2016-11-25 04:50:45,720 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.429000 ========= master_lag: 3.40 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-11-25 04:50:49.156458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:49.156902) ========= script_repo_revision: bad312aefb42982f492ad2cf36f4c6c3d698f4f7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:49.157330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:49.157600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 04:50:49.168248) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2016-11-25 04:50:49.168736) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-093 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-093 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-093 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 04:50:49 INFO - MultiFileLogger online at 20161125 04:50:49 in C:\slave\test 04:50:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:50:49 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 04:50:49 INFO - {'append_to_log': False, 04:50:49 INFO - 'base_work_dir': 'C:\\slave\\test', 04:50:49 INFO - 'blob_upload_branch': 'Firefox', 04:50:49 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 04:50:49 INFO - 'branch': 'Firefox', 04:50:49 INFO - 'buildbot_json_path': 'buildprops.json', 04:50:49 INFO - 'config_files': ('talos/windows_config.py',), 04:50:49 INFO - 'default_actions': ('clobber', 04:50:49 INFO - 'read-buildbot-config', 04:50:49 INFO - 'download-and-extract', 04:50:49 INFO - 'populate-webroot', 04:50:49 INFO - 'create-virtualenv', 04:50:49 INFO - 'install', 04:50:49 INFO - 'run-tests'), 04:50:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:50:49 INFO - 'download_minidump_stackwalk': True, 04:50:49 INFO - 'download_symbols': 'ondemand', 04:50:49 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 04:50:49 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 04:50:49 INFO - 'hg': 'c:/mozilla-build/hg/hg', 04:50:49 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 04:50:49 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 04:50:49 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 04:50:49 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 04:50:49 INFO - 'C:/mozilla-build/tooltool.py'), 04:50:49 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 04:50:49 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 04:50:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:50:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:50:49 INFO - 'installer_path': 'installer.exe', 04:50:49 INFO - 'log_level': 'info', 04:50:49 INFO - 'log_name': 'talos', 04:50:49 INFO - 'log_to_console': True, 04:50:49 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 04:50:49 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 04:50:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 04:50:49 INFO - 'opt_config_files': (), 04:50:49 INFO - 'pip_index': False, 04:50:49 INFO - 'sps_profile': False, 04:50:49 INFO - 'sps_profile_interval': 0, 04:50:49 INFO - 'suite': 'dromaeojs', 04:50:49 INFO - 'system_bits': '32', 04:50:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:50:49 INFO - 'title': 'T-W732-IX-093', 04:50:49 INFO - 'use_talos_json': True, 04:50:49 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 04:50:49 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 04:50:49 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 04:50:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:50:49 INFO - 'work_dir': 'build'} 04:50:49 INFO - [mozharness: 2016-11-25 12:50:49.949000Z] Running clobber step. 04:50:49 INFO - Running pre-action listener: _resource_record_pre_action 04:50:49 INFO - Running main action method: clobber 04:50:49 INFO - rmtree: C:\slave\test\build 04:50:49 INFO - Using _rmtree_windows ... 04:50:49 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 04:52:23 INFO - Running post-action listener: _resource_record_post_action 04:52:23 INFO - [mozharness: 2016-11-25 12:52:23.502000Z] Finished clobber step (success) 04:52:23 INFO - [mozharness: 2016-11-25 12:52:23.502000Z] Running read-buildbot-config step. 04:52:23 INFO - Running pre-action listener: _resource_record_pre_action 04:52:23 INFO - Running main action method: read_buildbot_config 04:52:23 INFO - Using buildbot properties: 04:52:23 INFO - { 04:52:23 INFO - "project": "", 04:52:23 INFO - "product": "firefox", 04:52:23 INFO - "script_repo_revision": "production", 04:52:23 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 04:52:23 INFO - "repository": "", 04:52:23 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 04:52:23 INFO - "buildid": "20161125030214", 04:52:23 INFO - "stage_platform": "win32-pgo", 04:52:23 INFO - "basedir": "C:\\slave\\test", 04:52:23 INFO - "nightly_build": "True", 04:52:23 INFO - "buildnumber": 13, 04:52:23 INFO - "slavename": "t-w732-ix-093", 04:52:23 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/", 04:52:23 INFO - "platform": "win7_ix", 04:52:23 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 04:52:23 INFO - "branch": "mozilla-central", 04:52:23 INFO - "repo_path": "mozilla-central", 04:52:23 INFO - "revision": "bad312aefb42982f492ad2cf36f4c6c3d698f4f7", 04:52:23 INFO - "pgo_build": "True", 04:52:23 INFO - "builduid": "9f4746ed4f204cb699934983bdc943b7", 04:52:23 INFO - "slavebuilddir": "test" 04:52:23 INFO - } 04:52:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 04:52:23 INFO - Running post-action listener: _resource_record_post_action 04:52:23 INFO - [mozharness: 2016-11-25 12:52:23.506000Z] Finished read-buildbot-config step (success) 04:52:23 INFO - [mozharness: 2016-11-25 12:52:23.506000Z] Running download-and-extract step. 04:52:23 INFO - Running pre-action listener: _resource_record_pre_action 04:52:23 INFO - Running main action method: download_and_extract 04:52:23 INFO - mkdir: C:\slave\test\build\tests 04:52: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')]} 04:52:23 INFO - trying https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 04:52:23 INFO - Downloading https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 04:52:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 04:52:24 INFO - Downloaded 1218 bytes. 04:52:24 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 04:52:24 INFO - Using the following test package requirements: 04:52:24 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 04:52:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 04:52:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 04:52:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'jsshell-win32.zip'], 04:52:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 04:52:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 04:52:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 04:52:24 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 04:52:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 04:52:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 04:52:24 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 04:52:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 04:52:24 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 04:52:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 04:52:24 INFO - Fetch https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 04:52:25 INFO - Http code: 200 04:52:25 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 04:52:25 INFO - x-amz-cf-id: vmVkbuJuBe8dIdgr-j4eD-p0RP0_uH-20RMopCEApg86nEJ7fxxVJA== 04:52:25 INFO - x-amz-version-id: ER7MuxaAK6oBXBbHrDmw8d2oTF9nTAxj 04:52:25 INFO - Expected file size: 16884503 04:52:25 INFO - Obtained file size: 16884503 04:52:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 04:52:45 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 04:52:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 04:52:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 04:52:46 INFO - Http code: 200 04:52:46 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 04:52:46 INFO - x-amz-cf-id: nHAUPByeVncV_5oV1N1vnOgHs-PQFnKYSk2FGzTXHHss46wx8DhKMg== 04:52:46 INFO - x-amz-version-id: ER7MuxaAK6oBXBbHrDmw8d2oTF9nTAxj 04:52:47 INFO - Expected file size: 16884503 04:52:47 INFO - Obtained file size: 16884503 04:52:57 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 04:52:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 04:52:57 INFO - Fetch https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 04:52:57 INFO - Http code: 200 04:52:57 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 04:52:57 INFO - x-amz-cf-id: FG5S0cUORzVuPPdwC_q5nRuP60nWjUJRBOz1sxwY8fBR3mdVWz7vWQ== 04:52:57 INFO - x-amz-version-id: sVTGwfMwWgqinpFinDTa1N1VrVmnD.ro 04:52:57 INFO - Expected file size: 14409749 04:52:57 INFO - Obtained file size: 14409749 04:52:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:58 INFO - trying https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 04:52:58 INFO - Downloading https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 04:52:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 04:53:01 INFO - Downloaded 54679664 bytes. 04:53:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 04:53:01 INFO - mkdir: C:\slave\test\properties 04:53:01 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 04:53:01 INFO - Writing to file C:\slave\test\properties\build_url 04:53:01 INFO - Contents: 04:53:01 INFO - build_url:https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 04:53:02 INFO - Running post-action listener: _resource_record_post_action 04:53:02 INFO - Running post-action listener: set_extra_try_arguments 04:53:02 INFO - [mozharness: 2016-11-25 12:53:02.320000Z] Finished download-and-extract step (success) 04:53:02 INFO - [mozharness: 2016-11-25 12:53:02.320000Z] Running populate-webroot step. 04:53:02 INFO - Running pre-action listener: _resource_record_pre_action 04:53:02 INFO - Running main action method: populate_webroot 04:53:02 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 04:53:02 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 04:53:02 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 04:53:02 INFO - u'--tppagecycles', 04:53:02 INFO - u'7'], 04:53:02 INFO - u'tests': [u'tsvgm']}}, 04:53:02 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 04:53:02 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 04:53:02 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 04:53:02 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 04:53:02 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 04:53:02 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 04:53:02 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'damp', u'tps']}, 04:53:02 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'tests': [u'damp', u'tps']}, 04:53:02 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'dromaeo_dom']}, 04:53:02 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 04:53:02 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'basic_compositor_video']}, 04:53:02 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 04:53:02 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'other-e10s': {u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'a11yr', 04:53:02 INFO - u'ts_paint', 04:53:02 INFO - u'tpaint', 04:53:02 INFO - u'sessionrestore', 04:53:02 INFO - u'sessionrestore_no_auto_restore', 04:53:02 INFO - u'tabpaint']}, 04:53:02 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'tsvgx', 04:53:02 INFO - u'tsvgr_opacity', 04:53:02 INFO - u'tart', 04:53:02 INFO - u'tscrollx', 04:53:02 INFO - u'cart', 04:53:02 INFO - u'tsvg_static']}, 04:53:02 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 04:53:02 INFO - u'tsvgr_opacity', 04:53:02 INFO - u'tart', 04:53:02 INFO - u'tscrollx', 04:53:02 INFO - u'cart', 04:53:02 INFO - u'tsvg_static']}, 04:53:02 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'talos_options': [u'--disable-e10s'], 04:53:02 INFO - u'tests': [u'tp5o']}, 04:53:02 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'tests': [u'tp5o']}, 04:53:02 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'talos_options': [u'--disable-e10s', 04:53:02 INFO - u'--xperf_path', 04:53:02 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:02 INFO - u'tests': [u'tp5n']}, 04:53:02 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 04:53:02 INFO - u'talos_options': [u'--xperf_path', 04:53:02 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:02 INFO - u'tests': [u'tp5n']}}, 04:53:02 INFO - u'talos.zip': {u'path': u'', 04:53:02 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:53:02 INFO - Running post-action listener: _resource_record_post_action 04:53:02 INFO - [mozharness: 2016-11-25 12:53:02.398000Z] Finished populate-webroot step (success) 04:53:02 INFO - [mozharness: 2016-11-25 12:53:02.398000Z] Running create-virtualenv step. 04:53:02 INFO - Running pre-action listener: _resource_record_pre_action 04:53:02 INFO - Running main action method: create_virtualenv 04:53:02 INFO - Creating virtualenv C:\slave\test\build/venv 04:53:02 INFO - mkdir: C:\slave\test\build\venv\Scripts 04:53:02 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 04:53:02 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'! 04:53:02 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 04:53:02 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 04:53:09 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 04:53:16 INFO - Installing distribute......................................................................................................................................................................................done. 04:53:18 INFO - Return code: 0 04:53:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 04:53:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 04:53:18 INFO - Reading from file tmpfile_stdout 04:53:18 INFO - Output received: 04:53:18 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 04:53:18 INFO - Using _rmtree_windows ... 04:53:18 INFO - Using _rmtree_windows ... 04:53:18 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 04:53:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:53:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:18 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:18 INFO - 'DCLOCATION': 'SCL3', 04:53:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:18 INFO - 'HOMEDRIVE': 'C:', 04:53:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:18 INFO - 'KTS_VERSION': '1.19c', 04:53:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:18 INFO - 'MOZ_NO_REMOTE': '1', 04:53:18 INFO - 'NO_EM_RESTART': '1', 04:53:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:18 INFO - 'OS': 'Windows_NT', 04:53:18 INFO - 'OURDRIVE': 'C:', 04:53:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:18 INFO - 'PROCESSOR_LEVEL': '6', 04:53:18 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:18 INFO - 'PROMPT': '$P$G', 04:53:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:18 INFO - 'PWD': 'C:\\slave\\test', 04:53:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:18 INFO - 'SYSTEMDRIVE': 'C:', 04:53:18 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:18 INFO - 'TEST1': 'testie', 04:53:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:18 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:18 INFO - 'USERNAME': 'cltbld', 04:53:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:18 INFO - 'WINDIR': 'C:\\windows', 04:53:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:19 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:53:19 INFO - Downloading/unpacking pip>=1.5 04:53:23 INFO - Running setup.py egg_info for package pip 04:53:23 INFO - warning: no files found matching 'pip\cacert.pem' 04:53:23 INFO - warning: no files found matching '*.html' under directory 'docs' 04:53:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 04:53:23 INFO - no previously-included directories found matching 'docs\_build\_sources' 04:53:23 INFO - Installing collected packages: pip 04:53:23 INFO - Found existing installation: pip 0.8.2 04:53:23 INFO - Uninstalling pip: 04:53:23 INFO - Successfully uninstalled pip 04:53:23 INFO - Running setup.py install for pip 04:53:23 INFO - warning: no files found matching 'pip\cacert.pem' 04:53:23 INFO - warning: no files found matching '*.html' under directory 'docs' 04:53:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 04:53:23 INFO - no previously-included directories found matching 'docs\_build\_sources' 04:53:23 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 04:53:23 INFO - Successfully installed pip 04:53:23 INFO - Cleaning up... 04:53:23 INFO - Return code: 0 04:53:23 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 04:53: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')]} 04:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53: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')]} 04:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 04:53:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:53:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:23 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:23 INFO - 'DCLOCATION': 'SCL3', 04:53:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:23 INFO - 'HOMEDRIVE': 'C:', 04:53:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:23 INFO - 'KTS_VERSION': '1.19c', 04:53:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:23 INFO - 'MOZ_NO_REMOTE': '1', 04:53:23 INFO - 'NO_EM_RESTART': '1', 04:53:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:23 INFO - 'OS': 'Windows_NT', 04:53:23 INFO - 'OURDRIVE': 'C:', 04:53:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:23 INFO - 'PROCESSOR_LEVEL': '6', 04:53:23 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:23 INFO - 'PROMPT': '$P$G', 04:53:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:23 INFO - 'PWD': 'C:\\slave\\test', 04:53:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:23 INFO - 'SYSTEMDRIVE': 'C:', 04:53:23 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:23 INFO - 'TEST1': 'testie', 04:53:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:23 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:23 INFO - 'USERNAME': 'cltbld', 04:53:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:23 INFO - 'WINDIR': 'C:\\windows', 04:53:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:25 INFO - Downloading/unpacking psutil>=3.1.1 04:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:27 INFO - Installing collected packages: psutil 04:53:27 INFO - Successfully installed psutil 04:53:27 INFO - Cleaning up... 04:53:27 INFO - Return code: 0 04:53:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 04:53: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')]} 04:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53: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')]} 04:53:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:27 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:27 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 04:53:27 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 04:53:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:27 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:27 INFO - 'DCLOCATION': 'SCL3', 04:53:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:27 INFO - 'HOMEDRIVE': 'C:', 04:53:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:27 INFO - 'KTS_VERSION': '1.19c', 04:53:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:27 INFO - 'MOZ_NO_REMOTE': '1', 04:53:27 INFO - 'NO_EM_RESTART': '1', 04:53:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:27 INFO - 'OS': 'Windows_NT', 04:53:27 INFO - 'OURDRIVE': 'C:', 04:53:27 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;', 04:53:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:27 INFO - 'PROCESSOR_LEVEL': '6', 04:53:27 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:27 INFO - 'PROMPT': '$P$G', 04:53:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:27 INFO - 'PWD': 'C:\\slave\\test', 04:53:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:27 INFO - 'SYSTEMDRIVE': 'C:', 04:53:27 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:27 INFO - 'TEST1': 'testie', 04:53:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:27 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:27 INFO - 'USERNAME': 'cltbld', 04:53:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:27 INFO - 'WINDIR': 'C:\\windows', 04:53:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:53:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:53:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 04:53:29 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) 04:53:29 INFO - Installing collected packages: mozsystemmonitor 04:53:29 INFO - Running setup.py install for mozsystemmonitor 04:53:29 INFO - Successfully installed mozsystemmonitor 04:53:29 INFO - Cleaning up... 04:53:29 INFO - Return code: 0 04:53:29 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 04:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 04:53:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:53:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:29 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:29 INFO - 'DCLOCATION': 'SCL3', 04:53:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:29 INFO - 'HOMEDRIVE': 'C:', 04:53:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:29 INFO - 'KTS_VERSION': '1.19c', 04:53:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:29 INFO - 'MOZ_NO_REMOTE': '1', 04:53:29 INFO - 'NO_EM_RESTART': '1', 04:53:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:29 INFO - 'OS': 'Windows_NT', 04:53:29 INFO - 'OURDRIVE': 'C:', 04:53:29 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:29 INFO - 'PROCESSOR_LEVEL': '6', 04:53:29 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:29 INFO - 'PROMPT': '$P$G', 04:53:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:29 INFO - 'PWD': 'C:\\slave\\test', 04:53:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:29 INFO - 'SYSTEMDRIVE': 'C:', 04:53:29 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:29 INFO - 'TEST1': 'testie', 04:53:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:29 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:29 INFO - 'USERNAME': 'cltbld', 04:53:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:29 INFO - 'WINDIR': 'C:\\windows', 04:53:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:29 INFO - Downloading/unpacking jsonschema==2.5.1 04:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:31 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 04:53:31 INFO - warning: no files found matching '*.txt' 04:53:31 INFO - no previously-included directories found matching 'build' 04:53:31 INFO - no previously-included directories found matching 'dist' 04:53:31 INFO - no previously-included directories found matching '.git*' 04:53:31 INFO - Installing collected packages: jsonschema, functools32 04:53:31 INFO - Running setup.py install for functools32 04:53:32 INFO - warning: no files found matching '*.txt' 04:53:32 INFO - no previously-included directories found matching 'build' 04:53:32 INFO - no previously-included directories found matching 'dist' 04:53:32 INFO - no previously-included directories found matching '.git*' 04:53:32 INFO - Successfully installed jsonschema functools32 04:53:32 INFO - Cleaning up... 04:53:32 INFO - Return code: 0 04:53:32 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 04:53:32 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')]} 04:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:32 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')]} 04:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:32 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:32 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 04:53:32 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 04:53:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:32 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:32 INFO - 'DCLOCATION': 'SCL3', 04:53:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:32 INFO - 'HOMEDRIVE': 'C:', 04:53:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:32 INFO - 'KTS_VERSION': '1.19c', 04:53:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:32 INFO - 'MOZ_NO_REMOTE': '1', 04:53:32 INFO - 'NO_EM_RESTART': '1', 04:53:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:32 INFO - 'OS': 'Windows_NT', 04:53:32 INFO - 'OURDRIVE': 'C:', 04:53:32 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;', 04:53:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:32 INFO - 'PROCESSOR_LEVEL': '6', 04:53:32 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:32 INFO - 'PROMPT': '$P$G', 04:53:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:32 INFO - 'PWD': 'C:\\slave\\test', 04:53:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:32 INFO - 'SYSTEMDRIVE': 'C:', 04:53:32 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:32 INFO - 'TEST1': 'testie', 04:53:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:32 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:32 INFO - 'USERNAME': 'cltbld', 04:53:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:32 INFO - 'WINDIR': 'C:\\windows', 04:53:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 04:53:32 INFO - Cleaning up... 04:53:32 INFO - Return code: 0 04:53:32 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 04:53:32 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')]} 04:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:32 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')]} 04:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:32 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:32 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 04:53:32 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 04:53:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:32 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:32 INFO - 'DCLOCATION': 'SCL3', 04:53:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:32 INFO - 'HOMEDRIVE': 'C:', 04:53:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:32 INFO - 'KTS_VERSION': '1.19c', 04:53:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:32 INFO - 'MOZ_NO_REMOTE': '1', 04:53:32 INFO - 'NO_EM_RESTART': '1', 04:53:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:32 INFO - 'OS': 'Windows_NT', 04:53:32 INFO - 'OURDRIVE': 'C:', 04:53:32 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;', 04:53:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:32 INFO - 'PROCESSOR_LEVEL': '6', 04:53:32 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:32 INFO - 'PROMPT': '$P$G', 04:53:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:32 INFO - 'PWD': 'C:\\slave\\test', 04:53:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:32 INFO - 'SYSTEMDRIVE': 'C:', 04:53:32 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:32 INFO - 'TEST1': 'testie', 04:53:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:32 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:32 INFO - 'USERNAME': 'cltbld', 04:53:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:32 INFO - 'WINDIR': 'C:\\windows', 04:53:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:33 INFO - Downloading/unpacking blobuploader==1.2.4 04:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:34 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 04:53:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:34 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 04:53:34 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 04:53:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 04:53:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:35 INFO - Downloading docopt-0.6.1.tar.gz 04:53:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 04:53:35 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:35 INFO - Running setup.py install for blobuploader 04:53:35 INFO - Running setup.py install for requests 04:53:36 INFO - Running setup.py install for docopt 04:53:36 INFO - Successfully installed blobuploader requests docopt 04:53:36 INFO - Cleaning up... 04:53:37 INFO - Return code: 0 04:53:37 INFO - Installing None into virtualenv C:\slave\test\build/venv 04:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:37 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:37 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 04:53:37 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 04:53:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:37 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:37 INFO - 'DCLOCATION': 'SCL3', 04:53:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:37 INFO - 'HOMEDRIVE': 'C:', 04:53:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:37 INFO - 'KTS_VERSION': '1.19c', 04:53:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:37 INFO - 'MOZ_NO_REMOTE': '1', 04:53:37 INFO - 'NO_EM_RESTART': '1', 04:53:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:37 INFO - 'OS': 'Windows_NT', 04:53:37 INFO - 'OURDRIVE': 'C:', 04:53:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:37 INFO - 'PROCESSOR_LEVEL': '6', 04:53:37 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:37 INFO - 'PROMPT': '$P$G', 04:53:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:37 INFO - 'PWD': 'C:\\slave\\test', 04:53:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:37 INFO - 'SYSTEMDRIVE': 'C:', 04:53:37 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:37 INFO - 'TEST1': 'testie', 04:53:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:37 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:37 INFO - 'USERNAME': 'cltbld', 04:53:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:37 INFO - 'WINDIR': 'C:\\windows', 04:53:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 04:53:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0gssw8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 04:53:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 04:53:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z51gdm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 04:53:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 04:53:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bktvoh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 04:53:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 04:53:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vwc_r3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 04:53:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 04:53:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ripqq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nmbwm6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wtrxe6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5dfaou-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wcqjx0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pzvmdo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-shgrbx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 04:53:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 04:53:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dwpvfm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 04:53:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 04:53:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7knfzu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 04:53:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 04:53:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y7wkrt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 04:53:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 04:53:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jufvup-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 04:53:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 04:53:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ubtaz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 04:53:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 04:53:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cy2ryb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 04:53:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:40 INFO - Running setup.py install for manifestparser 04:53:40 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 04:53:40 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 04:53:41 INFO - Running setup.py install for mozcrash 04:53:41 INFO - Running setup.py install for mozdebug 04:53:42 INFO - Running setup.py install for mozdevice 04:53:42 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 04:53:42 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 04:53:42 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 04:53:42 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 04:53:42 INFO - Running setup.py install for mozfile 04:53:43 INFO - Running setup.py install for mozhttpd 04:53:43 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Running setup.py install for mozinfo 04:53:43 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Running setup.py install for mozInstall 04:53:43 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 04:53:43 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 04:53:44 INFO - Running setup.py install for mozleak 04:53:44 INFO - Running setup.py install for mozlog 04:53:44 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 04:53:44 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 04:53:45 INFO - Running setup.py install for moznetwork 04:53:45 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 04:53:45 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 04:53:45 INFO - Running setup.py install for mozprocess 04:53:46 INFO - Running setup.py install for mozprofile 04:53:46 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 04:53:46 INFO - Running setup.py install for mozrunner 04:53:47 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 04:53:47 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 04:53:47 INFO - Running setup.py install for mozscreenshot 04:53:47 INFO - Running setup.py install for moztest 04:53:48 INFO - Running setup.py install for mozversion 04:53:48 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 04:53:48 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 04:53:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:48 INFO - Cleaning up... 04:53:48 INFO - Return code: 0 04:53:48 INFO - Installing None into virtualenv C:\slave\test\build/venv 04:53:48 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')]} 04:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:48 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')]} 04:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:48 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:48 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 04:53:48 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 04:53:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:48 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:48 INFO - 'DCLOCATION': 'SCL3', 04:53:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:48 INFO - 'HOMEDRIVE': 'C:', 04:53:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:48 INFO - 'KTS_VERSION': '1.19c', 04:53:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:48 INFO - 'MOZ_NO_REMOTE': '1', 04:53:48 INFO - 'NO_EM_RESTART': '1', 04:53:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:48 INFO - 'OS': 'Windows_NT', 04:53:48 INFO - 'OURDRIVE': 'C:', 04:53:48 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;', 04:53:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:48 INFO - 'PROCESSOR_LEVEL': '6', 04:53:48 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:48 INFO - 'PROMPT': '$P$G', 04:53:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:48 INFO - 'PWD': 'C:\\slave\\test', 04:53:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:48 INFO - 'SYSTEMDRIVE': 'C:', 04:53:48 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:48 INFO - 'TEST1': 'testie', 04:53:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:48 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:48 INFO - 'USERNAME': 'cltbld', 04:53:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:48 INFO - 'WINDIR': 'C:\\windows', 04:53:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mpb_1n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 04:53:49 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)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wfd3lx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 04:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t7rv_p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 04:53:49 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)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-exhazj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 04:53:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dedxil-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 04:53:49 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)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gdsiqi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 04:53:49 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)) 04:53:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 04:53:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lu1ah_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o8rqll-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bhmnld-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iemvys-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ykpkpd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z4ev8s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yuzw7p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 04:53:50 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)) 04:53:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 04:53:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uzmxcb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 04:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 04:53:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 04:53:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mdymqh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 04:53:51 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)) 04:53:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 04:53:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gbbjcm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 04:53:51 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)) 04:53:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 04:53:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eoxalr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 04:53:51 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)) 04:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 04:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 04:53:51 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)) 04:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 04:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 04:53:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 04:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:52 INFO - Downloading blessings-1.6.tar.gz 04:53:52 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 04:53:53 INFO - Installing collected packages: blessings 04:53:53 INFO - Running setup.py install for blessings 04:53:53 INFO - Successfully installed blessings 04:53:53 INFO - Cleaning up... 04:53:56 INFO - Return code: 0 04:53:56 INFO - Done creating virtualenv C:\slave\test\build/venv. 04:53:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 04:53:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 04:53:56 INFO - Reading from file tmpfile_stdout 04:53:56 INFO - Using _rmtree_windows ... 04:53:56 INFO - Using _rmtree_windows ... 04:53:56 INFO - Current package versions: 04:53:56 INFO - blessings == 1.6 04:53:56 INFO - blobuploader == 1.2.4 04:53:56 INFO - docopt == 0.6.1 04:53:56 INFO - functools32 == 3.2.3-2 04:53:56 INFO - jsonschema == 2.5.1 04:53:56 INFO - manifestparser == 1.1 04:53:56 INFO - mozInstall == 1.12 04:53:56 INFO - mozcrash == 1.0 04:53:56 INFO - mozdebug == 0.1 04:53:56 INFO - mozdevice == 0.49 04:53:56 INFO - mozfile == 1.2 04:53:56 INFO - mozhttpd == 0.7 04:53:56 INFO - mozinfo == 0.9 04:53:56 INFO - mozleak == 0.1 04:53:56 INFO - mozlog == 3.4 04:53:56 INFO - moznetwork == 0.27 04:53:56 INFO - mozprocess == 0.23 04:53:56 INFO - mozprofile == 0.28 04:53:56 INFO - mozrunner == 6.13 04:53:56 INFO - mozscreenshot == 0.1 04:53:56 INFO - mozsystemmonitor == 0.3 04:53:56 INFO - moztest == 0.7 04:53:56 INFO - mozversion == 1.4 04:53:56 INFO - psutil == 3.1.1 04:53:56 INFO - requests == 1.2.3 04:53:56 INFO - Installing None into virtualenv C:\slave\test\build/venv 04:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:56 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:56 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 04:53:56 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 04:53:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:56 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:56 INFO - 'DCLOCATION': 'SCL3', 04:53:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:56 INFO - 'HOMEDRIVE': 'C:', 04:53:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:56 INFO - 'KTS_VERSION': '1.19c', 04:53:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:56 INFO - 'MOZ_NO_REMOTE': '1', 04:53:56 INFO - 'NO_EM_RESTART': '1', 04:53:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:56 INFO - 'OS': 'Windows_NT', 04:53:56 INFO - 'OURDRIVE': 'C:', 04:53:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:56 INFO - 'PROCESSOR_LEVEL': '6', 04:53:56 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:56 INFO - 'PROMPT': '$P$G', 04:53:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:56 INFO - 'PWD': 'C:\\slave\\test', 04:53:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:56 INFO - 'SYSTEMDRIVE': 'C:', 04:53:56 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:56 INFO - 'TEST1': 'testie', 04:53:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:56 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:56 INFO - 'USERNAME': 'cltbld', 04:53:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:56 INFO - 'WINDIR': 'C:\\windows', 04:53:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 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)) 04:53:56 INFO - Cleaning up... 04:53:56 INFO - Return code: 0 04:53:56 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 04:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:56 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 0x017B1640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E59020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0175B7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-093', 'USERDOMAIN': 'T-W732-IX-093', '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-093', '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 04:53:56 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 04:53:56 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 04:53:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:53:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:53:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:53:56 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:53:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:53:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:53:56 INFO - 'DCLOCATION': 'SCL3', 04:53:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:53:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:53:56 INFO - 'HOMEDRIVE': 'C:', 04:53:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:53:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:53:56 INFO - 'KTS_VERSION': '1.19c', 04:53:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:53:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:53:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:53:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:56 INFO - 'MOZ_NO_REMOTE': '1', 04:53:56 INFO - 'NO_EM_RESTART': '1', 04:53:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:53:56 INFO - 'OS': 'Windows_NT', 04:53:56 INFO - 'OURDRIVE': 'C:', 04:53:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 04:53:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:53:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:53:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:53:56 INFO - 'PROCESSOR_LEVEL': '6', 04:53:56 INFO - 'PROCESSOR_REVISION': '1e05', 04:53:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:53:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:53:56 INFO - 'PROMPT': '$P$G', 04:53:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:53:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:53:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:53:56 INFO - 'PWD': 'C:\\slave\\test', 04:53:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:53:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:53:56 INFO - 'SYSTEMDRIVE': 'C:', 04:53:56 INFO - 'SYSTEMROOT': 'C:\\windows', 04:53:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:56 INFO - 'TEST1': 'testie', 04:53:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:53:56 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:53:56 INFO - 'USERNAME': 'cltbld', 04:53:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:53:56 INFO - 'WINDIR': 'C:\\windows', 04:53:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:53:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 04:53:57 INFO - Cleaning up... 04:53:57 INFO - Return code: 0 04:53:57 INFO - Running post-action listener: _resource_record_post_action 04:53:57 INFO - Running post-action listener: _start_resource_monitoring 04:53:57 INFO - Starting resource monitoring. 04:53:57 INFO - [mozharness: 2016-11-25 12:53:57.309000Z] Finished create-virtualenv step (success) 04:53:57 INFO - [mozharness: 2016-11-25 12:53:57.310000Z] Running install step. 04:53:57 INFO - Running pre-action listener: _resource_record_pre_action 04:53:57 INFO - Running main action method: install 04:53:57 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 04:53:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 04:53:57 INFO - Reading from file tmpfile_stdout 04:53:57 INFO - Using _rmtree_windows ... 04:53:57 INFO - Using _rmtree_windows ... 04:53:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:57 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 04:53:57 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 04:53:57 INFO - Reading from file tmpfile_stdout 04:53:57 INFO - Output received: 04:53:57 INFO - Usage: mozinstall-script.py [options] installer 04:53:57 INFO - Options: 04:53:57 INFO - -h, --help show this help message and exit 04:53:57 INFO - -d DEST, --destination=DEST 04:53:57 INFO - Directory to install application into. [default: 04:53:57 INFO - "C:\slave\test"] 04:53:57 INFO - --app=APP Application being installed. [default: firefox] 04:53:57 INFO - Using _rmtree_windows ... 04:53:57 INFO - Using _rmtree_windows ... 04:53:57 INFO - mkdir: C:\slave\test\build\application 04:53:57 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 04:53:57 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 04:54:00 INFO - Reading from file tmpfile_stdout 04:54:00 INFO - Output received: 04:54:00 INFO - C:\slave\test\build\application\firefox\firefox.exe 04:54:00 INFO - Using _rmtree_windows ... 04:54:00 INFO - Using _rmtree_windows ... 04:54:00 INFO - Running post-action listener: _resource_record_post_action 04:54:00 INFO - [mozharness: 2016-11-25 12:54:00.163000Z] Finished install step (success) 04:54:00 INFO - [mozharness: 2016-11-25 12:54:00.163000Z] Running run-tests step. 04:54:00 INFO - Running pre-action listener: _resource_record_pre_action 04:54:00 INFO - Running main action method: run_tests 04:54:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 04:54:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 04:54:00 INFO - Python 2.7.3 04:54:00 INFO - Return code: 0 04:54:00 INFO - grabbing minidump binary from tooltool 04:54: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')]} 04:54:00 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 0x01824E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019255C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 04:54:00 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 04:54:00 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 04:54:00 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 04:54:00 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 04:54:00 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpgkzewt 04:54:00 INFO - INFO - File integrity verified, renaming tmpgkzewt to win32-minidump_stackwalk.exe 04:54:00 INFO - Return code: 0 04:54:00 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 04:54:00 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 04:54:00 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 04:54:00 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 04:54:00 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 04:54:00 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-093', '--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 04:54:00 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-093 --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 04:54:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 04:54:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 04:54:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 04:54:00 INFO - 'COMPUTERNAME': 'T-W732-IX-093', 04:54:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 04:54:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 04:54:00 INFO - 'DCLOCATION': 'SCL3', 04:54:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 04:54:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 04:54:00 INFO - 'HOMEDRIVE': 'C:', 04:54:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 04:54:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 04:54:00 INFO - 'KTS_VERSION': '1.19c', 04:54:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 04:54:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-093', 04:54:00 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 04:54:00 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 04:54:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 04:54:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 04:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:00 INFO - 'MOZ_NO_REMOTE': '1', 04:54:00 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 04:54:00 INFO - 'NO_EM_RESTART': '1', 04:54:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 04:54:00 INFO - 'OS': 'Windows_NT', 04:54:00 INFO - 'OURDRIVE': 'C:', 04:54: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;', 04:54:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 04:54:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 04:54:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 04:54:00 INFO - 'PROCESSOR_LEVEL': '6', 04:54:00 INFO - 'PROCESSOR_REVISION': '1e05', 04:54:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 04:54:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 04:54:00 INFO - 'PROMPT': '$P$G', 04:54:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 04:54:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 04:54:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 04:54:00 INFO - 'PWD': 'C:\\slave\\test', 04:54:00 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 04:54:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 04:54:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 04:54:00 INFO - 'SYSTEMDRIVE': 'C:', 04:54:00 INFO - 'SYSTEMROOT': 'C:\\windows', 04:54:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:54:00 INFO - 'TEST1': 'testie', 04:54:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 04:54:00 INFO - 'USERDOMAIN': 'T-W732-IX-093', 04:54:00 INFO - 'USERNAME': 'cltbld', 04:54:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 04:54:00 INFO - 'WINDIR': 'C:\\windows', 04:54:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 04:54:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 04:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 04:54:00 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-093', '--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 04:54:01 INFO - mozversion application_buildid: 20161125030214 04:54:01 INFO - mozversion application_changeset: bad312aefb42982f492ad2cf36f4c6c3d698f4f7 04:54:01 INFO - mozversion application_display_name: Nightly 04:54:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:01 INFO - mozversion application_name: Firefox 04:54:01 INFO - mozversion application_remotingname: firefox 04:54:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:54:01 INFO - mozversion application_vendor: Mozilla 04:54:01 INFO - mozversion application_version: 53.0a1 04:54:01 INFO - mozversion platform_buildid: 20161125030214 04:54:01 INFO - mozversion platform_changeset: bad312aefb42982f492ad2cf36f4c6c3d698f4f7 04:54:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:54:01 INFO - mozversion platform_version: 53.0a1 04:54:01 INFO - using testdate: 1480078441 04:54:01 INFO - actual date: 1480078441 04:54:01 INFO - starting webserver on 'localhost:49564' 04:54:01 INFO - SUITE-START | Running 2 tests 04:54:01 INFO - TEST-START | dromaeo_css 04:54:01 INFO - operating with platform_type : w7_ 04:54:01 INFO - Initialising browser for dromaeo_css test... 04:54:02 INFO - TEST-INFO | started process 2764 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmph4z1e3\profile http://localhost:49564/getInfo.html) 04:54:09 INFO - PROCESS | 2764 | __metrics Screen width/height:1600/1200 04:54:09 INFO - PROCESS | 2764 | colorDepth:24 04:54:09 INFO - PROCESS | 2764 | Browser inner width/height: 1010/674 04:54:09 INFO - PROCESS | 2764 | __metrics 04:54:10 INFO - PROCESS | 2764 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:54:10 INFO - PROCESS | 2764 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:54:11 INFO - TEST-INFO | 2764: exit 0 04:54:11 INFO - Browser initialized. 04:54:11 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:11 INFO - TEST-INFO | started process 2684 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmph4z1e3\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:12 INFO - PROCESS | 2684 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 04:54:23 INFO - PROCESS | 2684 | RSS: Main: 123375616 04:54:23 INFO - PROCESS | 2684 | 04:55:15 INFO - PROCESS | 2684 | 1480078515046 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] 04:57:25 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49564/tests/dromaeo/cssquery-ext.html) 04:57:27 INFO - PROCESS | 2684 | RSS: Main: 287027200 04:57:27 INFO - PROCESS | 2684 | 05:00:30 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-ext.html (next: http://localhost:49564/tests/dromaeo/cssquery-jquery.html) 05:00:30 INFO - PROCESS | 2684 | RSS: Main: 200306688 05:00:30 INFO - PROCESS | 2684 | 05:03:32 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49564/tests/dromaeo/cssquery-mootools.html) 05:03:33 INFO - PROCESS | 2684 | RSS: Main: 162332672 05:03:33 INFO - PROCESS | 2684 | 05:06:35 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49564/tests/dromaeo/cssquery-prototype.html) 05:06:36 INFO - PROCESS | 2684 | RSS: Main: 152014848 05:06:36 INFO - PROCESS | 2684 | 05:09:39 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49564/tests/dromaeo/cssquery-yui.html) 05:09:39 INFO - PROCESS | 2684 | RSS: Main: 130994176 05:09:39 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | Cycle 1(1): loaded http://localhost:49564/tests/dromaeo/cssquery-yui.html (next: http://localhost:49564/tests/dromaeo/cssquery-dojo.html) 05:12:42 INFO - PROCESS | 2684 | RSS: Main: 183967744 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | __start_tp_report 05:12:42 INFO - PROCESS | 2684 | _x_x_mozilla_page_load 05:12:42 INFO - PROCESS | 2684 | _x_x_mozilla_page_load_details 05:12:42 INFO - PROCESS | 2684 | |i|pagename|runs| 05:12:42 INFO - PROCESS | 2684 | |0;dojo.html;2728.522508561002;2765.8478783666897;2773.972260277397;2774.791890608205;2786.7631251343646;4691.577758001779;4733.242681171013;4796.3764710587575;4798.784054717537;4799.376081109455;4656.627469802416;4719.740414277213;4751.572358487735;4766.022965292115;4855.1018061658615;4020.9798951005205;4160.854370097048;4168.458100446953;4191.7275377100395;4230.090530535934;4206.284931561627;4225.830966761341;4258.957410425903;4275.657947364219;4299.720518166325;4148.792560371984;4281.250781113293;4282;4283.957160428403;4284.164587905344;4131.855385061508;4190.120074784269;4230.51349094855;4253.425787518676;4282.122164956192;4132.876013719593;4156.189543039109;4226.154769046172;4235.788210589458;4241.406203131564;4665.976670116662;4713.245880659112;4785.21044027735;4797.664163508556;4802.351682522884;4853.660243782937;4895.192293271606;4898.779554920037;4915.5821755150855;5067.974660126676;4870.22076467767;4883.780229889628;4921.384826896638;4927.901441971177;4932.827351042696;4516.570925762047;4522.412086428743;4524.615407690383;4591.311303304497;4600.838970636045;4956.677815941952;4960.131976904027;4994.700317980933;5078.7206703631655;5086.313347698014;4729.69256998294;4820;4849.636277279218;4876.21980483121;4954.133026720309;5058.797648094035;5071.46749591295;5072.949270507248;5075.695458272442;5078.7460626968505;4909.68087074339;4923.163062279421;5038.143515602356;5040.420351659606;5044.445111037783;4847.127517046965;4900.485449027872;4904.4605093439695;5052.9242061369105;5054.19132938728;3199.7120259176786;3238.417084924736;3264.4450443424676;3291.753118516121;3292.2098696312714;2994.835284059354;3014.4121896230463;3017.9245518862203;3018.9849050754606;3028.757699384044;4640.559146881041;4693.859184224479;4705.505921878222;4721.952780472151;4734;4904.896398310351;4994.450610432849;4997.425296090923;5003.724795136302;5008.173651347487;4618.584327410549;4708.317293992352;4743.383360163157;4764.213904705754;4765.642576806753;3953.2093581283857;3988.481497405319;4032.153247817991;4067.491563554556;4122.072533679958;5043.268726034705;5206.479352064839;5215.6609820361555;5302.761375738101;5330.093884039723;4805.159097158054;4871.707697538159;4977.377827771528;4985.053717826008;5097.719625420637;5097.566706829961;5149;5172.844814655567;5207.244949482228;5251.186066159751;4829.372181616367;4830.589399901048;4849.418069831643;4854.83008094715;4862.270659400977;4728.763561821826;4930;4979.402471703419;4987.10232158215;4990.975045124751;4574.588287054181;4666.323383109362;4736.2185239436485;4763.7141771493825;4828.806847726192;4747.216709243075;4787.3537072496165;4822.083804077214;4833.540813622701;4841.080194762983;4626.768661566841;4699.929501057555;4712.9293060604805;4713.622910167247;4719.952800471951;4839.782209800638;4877.662365953385;4894.314795928644;4930.4822993586595;4977.552020318189;4776.5701086902345;4852.781624826962;4864.027194561031;4870.269459581091;4880.1215781159735 05:12:42 INFO - PROCESS | 2684 | |1;ext.html;510.85185296264086;539.1723704114185;549.870780366614;563.2508763344755;572.2932178759229;27536;28531.71468285319;37664.811675941615;38803.80598097012;40314.395284070764;38499.80750096246;38831.41752873702;38844.61155388445;38894.61105388945;38929.610703892955;7611.124720657126;7680.961595192044;7706.7687969361;7739.458237923348;7742.070951485802;6431.228252609693;6534.411902928736;9272.211861991738;9488.38325508843;9490.572924218392;9679.225661947028;9721.70834874955;9723.611055557769;9744.853827192597;9753;6573.57271777333;9531.475768832712;9560.378575392646;9565.18695910852;9591.712248632482;6698.83252918681;8965.551722413853;9166.81666366669;9169.679061232891;9547.379420337656;36130;36417.81791091028;36491.817540912125;36504.26991460156;36516.45225321622;2834.518131917579;2846.3595690969573;2937.3978334441604;3316.038348878822;3386.593608766964;26610;26625.866870665715;26737;27046.72953270462;27202;3418.5214070030215;3430.365382404263;3445.9483107753404;3473.426884564044;3512.6311737267474;333800;335438;336374;337078.99999999756;338034;48654.026919461416;48809;48872.02255954861;48958.5104148954;49077;50216.99566008733;50616.49383506191;50763.746181268856;50826.237606435934;50886;13322.800157997637;13831.824294934842;13839.861601384058;13842.446302148002;13842.93078534621;9623.663171789094;9875.506224688737;10739;10764.3541387517;10773.515191816387;14085.507007254697;14517.241724072392;14627.414903403745;14770.852291477162;14798.927613941147;5678.517326027251;5728.054870946247;5750.1949727038245;5764.654120752684;5783.913241301384;722.2091809468624;747.5514691185354;909.7498187998222;919.9126083022102;946.9005754395827;24980.125695600567;26360.340991474983;26623.86688066586;26733.732662673123;26803.597946030826;875.4659657608853;885.6501681835639;887.6405055952221;939.8026414453042;1002.3484734922213;13527.797083043763;13877.734492058655;14467.49363772263;14860.256987150598;15141.621459463602;477465;483084;484009;487178;488444;46200.537994620296;46264;46448;46708.29937550939;46712;986.1124987511154;992.0922356044283;1046.1317106801337;1073.5705717712976;1084.251866212311;25802.61296080598;27018.864905675346;27159.532296877904;27295;27552.173434796987;39333;39541;40024;40060.59939400569;40101.799491002355;389378;398749;400846;402334;402954;20671.276505322243;21647.675284870424;21703.131874724833;21728.45678857978;21781.346559603237;37789;38828.41757373585;39628;40333.59666403416;40833.1833363337;34549.13627159341;34982.30035399227;35413.29173416569;35575.46636800398;36188.09529761777;32957.835210823796;33708.157296067795;34201.31597367989;34300.31399372048;36088.81955590205;32195.35609287848;32967.50548741819;33479;33684.49473257854;33730.831345843115;30101;31949.367341735782;31990.52014219835;32106.678933211304;32449;23779.286621401385;24112.156074537306;24429.51140977206;24524.50950981006;24553.263402097964 05:12:42 INFO - PROCESS | 2684 | |2;jquery.html;278.5584848015895;303.7403020417543;306.9969300306998;308.5880349733107;312.9593152890125;12607.306598137098;12614.936925315373;13354.66613334667;13364.799528007088;13422;15665.295061722221;16242.431514896982;16322.918385408086;16379.59051023725;16397.42609008685;4956.7025978441225;5141.717205553693;5148.588112950955;5156.53591176794;5156.942826720513;4034.9394759078637;4968.751562421882;5053.898922021557;5085.694858308495;5089.720065396402;5093.79624815007;5095.4140273868425;5101.234814777776;5104.106781313255;5109.4379618241965;4901.142300097505;4978.228374601942;5065.544101030888;5068.619853510983;5070.746462676888;4289.442372491588;4422.690411671184;4441.822327106912;4454.532274111237;4466.039801442673;21213.575728485343;21259.36221913345;21355.145794168213;21441.356759297247;21467.463313417134;1762.2950819672105;1793.2826869252403;1855.0168410742328;1923.413358925527;2007.9498012549657;12625.558105466265;12950.222986620833;12990.740185196242;13015.34923253843;13363.1313964592;1639.5737108351796;1699.5383969785973;1699.9490015299561;1756.2535922233;1759.9472015839544;79802.60098699527;79941.60029199875;81328;82251.58874205651;83567;8416.915830841735;8559.358048146414;8646.956765216259;8811.427257228259;8967;11425.31448113116;11446.885531144582;11496.252743571642;11507.482163302671;11545.365004924643;6263.6241825490615;6726.529142959946;6834.829129271709;7273.3453989141235;7291.08861392326;7123;7219.819504512324;7283.9669453873885;7465.104187497426;7583.431242656712;5625.128105143627;5675.517581005661;5695.658260504383;5714.7428365723645;5790.739416726257;1437.0515459796484;1456.730504856605;1477.837437881832;1482.6071091160852;1504.811898512686;719.6761457344217;780.55898417394;781.8592653322456;781.9921800782033;804.3123129724119;17665.823341766674;18407.355742548945;18615.534611634546;18628.906855465637;18855.151518181716;803.6022169026369;819.647551552826;827.4580150001639;838.3209600223838;871.7471933139318;10075.445850478147;10320.587176513005;10370.896291036994;10406.323588966692;10626.46867656614;404922;408743;413390;416437;416693;82734.58632706798;86263.56868215745;94309.52845235867;96329;96982;881.4755220643707;909.9545022748836;925.1396201532639;950.254050570299;957.5212393803098;16481.83518164803;16482.51696115699;16852.57868553296;17005.149742513073;17307.740383894503;44639.33041004323;45100;45360.54639453563;45368.77315613401;46062.539374605825;63462.365376345646;64306.356936430035;64666.35333646792;65034.349656504724;66589.33410665831;7131.964340178266;7270.636468176464;7313.780586582411;7327.706891724484;7338.559686418832;11510.712232194262;12530.561430349893;12628.684282893002;12795.104342695922;13019.609411717664;10469.476526173814;10518.474076296308;10642.73393165177;10878.564857405616;10886.40124793144;10179.949100254747;10370.792584148125;10553.939357586214;10582.417967011888;10709.179439495148;10615;10709.303895246783;10765.138788897026;10800.459977001276;10978.396188209406;7238.131424229126;7270.309320614533;7288.3440490354005;7350.411967042731;7351.301626345488;9374.859377109213;10054.245931555017;10400.583976641152;10436.165106791286;10495.632652857419 05:12:42 INFO - PROCESS | 2684 | |3;mootools.html;1048.1719441641103;1115.587232723892;1122.2088427658502;1173.3781096019109;1372.368710393219;5745.253117094777;6290.21372328459;6471.0940468334475;6503.187101612299;6539.64031978241;6503.414692677658;6519.706613202406;6546.607203567777;6554.836129096775;6567.376099270562;1542.799436073309;1829.44202018384;1864.3008871673123;1865.1886429403185;1868.7850897146866;1575.7006168827886;1752.4742577226782;1800.5948661551176;1810.6921823290006;1814.4919422561704;1846.22458567402;1849.6948003579394;1849.704047352424;1854.2026928420773;1854.7866995295578;1791.2655811117509;1801.3245033112582;1824.771903512061;1825.1330617956496;1832.0564909071838;1710.4099085815374;1722.250820892903;1730.134932533733;1738.1483073293996;1740.26908698347;6282.057691346335;6357.809265722036;6402.967985160044;6411.807645770635;6415.390537898892;3760.360738674432;3871.9225615487812;3977.5624681285035;3979.920401591981;3986.1629057897876;2617.345663584104;2715.253305341047;2759.599858020576;2766.61267422561;2769.1692492252446;815.3314282288526;833.6415341403194;852.6845067325129;865.398548009134;868.3574155125225;8301.169883011711;8340.37447191463;8358.540280284582;8397.6221070052;8423.652215645467;3426.588809342895;3429.0741499794917;3485.9651403486146;3530.029241958482;3541.079319376929;7182.174049984319;7311.963440182872;7312;7321.194668586451;7360.153582337993;3912.726109172331;3940.0346915006007;4013.6387725104883;4032.354823228269;4033.798310084484;3321;3416.453367461244;3619.113317237294;3683.631636836295;3737.159139193714;2909.418116376733;2967.985160074186;2968.1837494689134;2976.538636511344;3012.7137921897825;951.8619800128943;961.3991255465335;964.6912987843963;971.5288085278635;973.6300205921705;675.368530424055;702.7189124350301;704.8519810839679;713.8072720365473;723.4863247094622;4054.6958978076764;4256.57434256578;4298.3552467130185;4333.674974376934;4348.630366418826;697.6755808549049;706.8833642448939;715.5241764226835;717.8951036766008;724.2106104346287;2909.5490199019187;2970.301979034925;3050.008747157183;3062.8774849006236;3084.306031142975;7762;7799.259070388418;7869.685212591432;7887.171846956102;7908.802279942933;7556.65995030225;7802.804929876685;7805.219478052263;7814.570198639016;7824.334931530883;5716.828495145152;6103.8474038148515;6138.693065346715;6141;6164.075388691643;4023.465216913326;4234.174336004449;4236.173946080485;4245.448091748053;4403.955960440355;7082.964585177041;7274.8908766367485;7284.089488813898;7299.8540029200185;7316.670749816165;7301.4158867289425;7308.8903666443985;7360.447966402434;7416.517926334771;7422.70309187648;5431.402545720047;5504.064309067388;5588.329400471806;5611.298587676541;5630.915536266877;6208.503319734501;6227.252729672468;6240.500759939103;6260.561760676709;6279.497640188865;5429.212764149255;5592.86017849557;5635.6618602883955;5638.436156384329;5665.178549110274;5320.0955837506945;5352.571794256528;5446.101393270066;5466.371367292653;5484.204790305464;5148.9742551287;5159.406668233201;5202.2196670499725;5227.843164705065;5235.973820131027;3509.298140371885;3519.5952465465775;3520.5247291615306;3570.071781336819;3637.5089362935664;3532.2052538178705;3536.3811333017134;3676.5220521332058;3776.131489757316;3800.9809950950066 05:12:42 INFO - PROCESS | 2684 | |4;prototype.html;237.31297892600932;240.84104491035913;247.44942502930982;248.0808604121728;248.59975439542322;5009.69941803492;5043.697378157313;5051.722155281458;5065.391323468412;5104.8979020419565;4770.761461926907;4806.134895718778;4866.586340161081;4918.754062296889;4933.260010998352;3651.4522821576843;3662.157703728144;3666.413373860183;3667.266546690659;3671.5594128704593;3628.274345130971;3652.141746689526;3654.269146170763;3662.377395842713;3668.1746607013474;3465.556782468354;3504.754667173299;3544.220271540257;3633.8001409922445;3641.271745650867;3632.600413954463;3634.436662317345;3639.981800091009;3641.1625326175126;3646.4712616670568;3103.844807759603;3134.529820526939;3146.65386807451;3146.685331466858;3147.7324427423696;4851.175300198975;4860.7326597037145;4873.31773551703;4878.219484882437;4880.463149053601;4108.5275193352845;4223.514295855985;4224.302990006645;4226.387173859804;4240.978795106004;5089.51649593288;5102.6428150029515;5116.155834287338;5132.794688212467;5137.614678899097;3414.778039427429;3452.7065199458075;3458.4639380896;3465.1856813648774;3466.272082862602;5269.815556455505;5348.705821179834;5381.851616804174;5391.865203369908;5407.864803379907;3477.4609935460044;3539.752217344813;3547.1486843157454;3553.5202747629255;3565.876748824112;3300.405926933127;3433.3476639438427;3503.2292895562937;3589.5692516897616;3614.1868079681863;4132.91734165321;4133.565975572582;4139.971414007115;4150.5641907599875;4161.209370219649;5202.375714914235;5222.8955420892125;5224.425313215544;5233.528982391603;5249.532719621771;1167.9196743012678;1179.3926128043893;1180.1326025371345;1180.7697498987786;1184.5676328140166;713.8072720365473;724.3589423360364;748.2255865179513;759.1990450075224;761.4936067515151;4856.247281671346;4878.804847806048;4892.8532144035735;4912.729799861042;4932.926006109911;5160.612954028463;5207.947920520822;5208.322918020633;5210.218467229945;5229.790808367623;4890.315355850184;4897.730624815598;4902.558769710743;4914.90170196594;4916.950830491649;2633.710291867893;2761.282066662642;2768.0311890838047;2773.833569985807;2787.247443190368;5498.890022199534;5506.119020956708;5513.173024046345;5519.586031047687;5527.723613819293;5281.735913204325;5331.920021199685;5352.464753524617;5359.3032905722;5388.407275199725;5381.30043094403;5483.643563168461;5509.531689806331;5515.58633102527;5518.4824173352645;5206.8958620828125;5236.293100431393;5267.683938963674;5281.551068159096;5283.709395983258;5135.483613029514;5179.4561571036;5184.662996905189;5220.190870415092;5226.425093239664;5485.4788795064405;5501.284832971688;5554.888902222014;5565.276514053147;5566.137248726453;3245.0102718671033;3356.1441832333576;3357.4124528207954;3362.3107263010534;3373.2241584436206;5253.658512196695;5270.262163297218;5271.630985830955;5278.841634750964;5281.788728450819;5050.368703912011;5078.771455284595;5080.196530231715;5084.339035925306;5092.8472145835685;5051.696898186121;5073.619478539051;5073.858381864051;5113;5113.718745469035;4868.902621947612;4965.577925876197;5115.7953681852305;5126;5138.640295179302;5065.493450654905;5261.500157485033;5265.473452654704;5266.657667251617;5268.367795864521;5032.521910418504;5095.235714642833;5108.540231379194;5110.412302585101;5127.025865085622 05:12:42 INFO - PROCESS | 2684 | |5;yui.html;548.1777333999001;564.6527385657822;566.8809549994501;578.9450002249786;632.475045712059;13001.804972925402;13253.536126235584;13746.175229486238;13750.93124534376;13978.790318145224;13124.409401576928;13633.182009079437;13686.58940231792;13700.314984250772;13718.314084295795;579.3279795437294;580.3326174898858;590.4833270887973;592.8162269696386;599.5802937943436;599.0146209485393;601.3235110500686;601.4977493792688;601.7472661482188;602.5781952633153;625.1747693045182;626.8401557602805;626.9811905642815;627.5262177056316;628.0955424189176;624.1667374055642;624.7157543317779;624.8843963866653;625.2434554189437;628.9150964619763;453.81620443720345;453.8388871950443;456.31097043464274;458.53000674308765;458.6055991847042;12246.265224086583;12501.187422817578;12538.623841284776;12541;12605.369731513387;11943.9847612953;12058.276503409823;12189.512419503299;12608.11743177978;12612.68468288291;10400.427976461291;10919.781604367947;11045.83431248532;11204.15968802335;11330.943345283304;3220.710136087763;3294.96705032949;3319.751018673585;3381.881634142818;3382.5095361172494;58292;61532.69233653848;63600.363996359905;64784.676076619784;64877.351226487604;9616.471094089753;9679.70960871175;9685.079917407835;9778.804423911624;9782.951085244527;15331.463398780988;15485.767713484307;15532.301046452936;15569.922150389402;15572.221388930508;6862.451003919774;6890.827729306808;6933.583984960918;7076.575405475585;7164.391026762783;3745.981270093687;3837.1174629834736;3844.2503711775935;3919.098607320332;3972.2849887020034;2372.406898275431;2665.7200993895362;2669.2259244819174;2676.772474339664;2684.3960108975325;465.3438651501435;470.4730701614214;474.8789058790056;479.05386860949346;483.8862867226199;624.6064979063161;644.78399736088;656.3666062249952;661.5303134774268;668.5554106519207;8526.445781024213;8533.615987280587;8579.442336248125;8660.090690477535;8724.258438032712;603.1525706382452;629.2731894661673;645.2611759535399;655.790147152916;662.7249691378121;4120;4145.813438395279;4186.5604111568455;4234.9364759528635;4255.212785634606;80060;80556.59721701471;81107.59446202731;81470;82240;70398;72595.63702181455;73010.63494682492;73461.26538734652;74226.25773742302;65693;65888.6705566469;66409;66548.66725666437;66755.66622166858;8547.61535730906;8681.479111253346;9033.683821066232;9123.817523649623;9161.450312980976;40967;41458.37812432756;41496.1700765989;42551.574484254765;43400.34899476447;53472.1979170305;55621.1656825156;55773.44226557682;56000.439995599525;56199;10967.396793176453;11434.48544815502;11532.88467115318;11862.525498979945;11918.880811191777;13355.332233388486;13441.126326788728;13453.865461345262;13543.932280338535;14060.656950358798;13065.020123490965;13103.279319637511;13110.234254033418;13255;13746.312684365541;12344.62966111018;12598.496060157491;13024.088313817942;13105.344732763515;13151.802722958972;12546.309952952675;12921.095523313277;13028.934855326042;13036.022298327474;13068.673283167616;10495.73760655959;10500.579976800842;10549.261551691308;10675.092617127631;10888.727781805519;10172.949135254275;10830.730110800598;10892.727681808019;10893.836592451278;10929.234953553176 05:12:42 INFO - PROCESS | 2684 | __end_tp_report 05:12:42 INFO - PROCESS | 2684 | __start_cc_report 05:12:42 INFO - PROCESS | 2684 | _x_x_mozilla_cycle_collect,2283 05:12:42 INFO - PROCESS | 2684 | __end_cc_report 05:12:42 INFO - PROCESS | 2684 | __startTimestamp1480079562595__endTimestamp 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | ------- Summary: start ------- 05:12:42 INFO - PROCESS | 2684 | Number of tests: 6 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#0] dojo.html Cycles:165 Average:4580.53 Median:4787.35 stddev:585.32 (12.2%) stddev-sans-first:568.80 05:12:42 INFO - PROCESS | 2684 | Values: 2728.5 2765.8 2774.0 2774.8 2786.8 4691.6 4733.2 4796.4 4798.8 4799.4 4656.6 4719.7 4751.6 4766.0 4855.1 4021.0 4160.9 4168.5 4191.7 4230.1 4206.3 4225.8 4259.0 4275.7 4299.7 4148.8 4281.3 4282.0 4284.0 4284.2 4131.9 4190.1 4230.5 4253.4 4282.1 4132.9 4156.2 4226.2 4235.8 4241.4 4666.0 4713.2 4785.2 4797.7 4802.4 4853.7 4895.2 4898.8 4915.6 5068.0 4870.2 4883.8 4921.4 4927.9 4932.8 4516.6 4522.4 4524.6 4591.3 4600.8 4956.7 4960.1 4994.7 5078.7 5086.3 4729.7 4820.0 4849.6 4876.2 4954.1 5058.8 5071.5 5072.9 5075.7 5078.7 4909.7 4923.2 5038.1 5040.4 5044.4 4847.1 4900.5 4904.5 5052.9 5054.2 3199.7 3238.4 3264.4 3291.8 3292.2 2994.8 3014.4 3017.9 3019.0 3028.8 4640.6 4693.9 4705.5 4722.0 4734.0 4904.9 4994.5 4997.4 5003.7 5008.2 4618.6 4708.3 4743.4 4764.2 4765.6 3953.2 3988.5 4032.2 4067.5 4122.1 5043.3 5206.5 5215.7 5302.8 5330.1 4805.2 4871.7 4977.4 4985.1 5097.7 5097.6 5149.0 5172.8 5207.2 5251.2 4829.4 4830.6 4849.4 4854.8 4862.3 4728.8 4930.0 4979.4 4987.1 4991.0 4574.6 4666.3 4736.2 4763.7 4828.8 4747.2 4787.4 4822.1 4833.5 4841.1 4626.8 4699.9 4712.9 4713.6 4720.0 4839.8 4877.7 4894.3 4930.5 4977.6 4776.6 4852.8 4864.0 4870.3 4880.1 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#1] ext.html Cycles:180 Average:53514.14 Median:38144.40 stddev:109149.84 (286.1%) stddev-sans-first:109383.49 05:12:42 INFO - PROCESS | 2684 | Values: 510.9 539.2 549.9 563.3 572.3 27536.0 28531.7 37664.8 38803.8 40314.4 38499.8 38831.4 38844.6 38894.6 38929.6 7611.1 7681.0 7706.8 7739.5 7742.1 6431.2 6534.4 9272.2 9488.4 9490.6 9679.2 9721.7 9723.6 9744.9 9753.0 6573.6 9531.5 9560.4 9565.2 9591.7 6698.8 8965.6 9166.8 9169.7 9547.4 36130.0 36417.8 36491.8 36504.3 36516.5 2834.5 2846.4 2937.4 3316.0 3386.6 26610.0 26625.9 26737.0 27046.7 27202.0 3418.5 3430.4 3445.9 3473.4 3512.6 333800.0 335438.0 336374.0 337079.0 338034.0 48654.0 48809.0 48872.0 48958.5 49077.0 50217.0 50616.5 50763.7 50826.2 50886.0 13322.8 13831.8 13839.9 13842.4 13842.9 9623.7 9875.5 10739.0 10764.4 10773.5 14085.5 14517.2 14627.4 14770.9 14798.9 5678.5 5728.1 5750.2 5764.7 5783.9 722.2 747.6 909.7 919.9 946.9 24980.1 26360.3 26623.9 26733.7 26803.6 875.5 885.7 887.6 939.8 1002.3 13527.8 13877.7 14467.5 14860.3 15141.6 477465.0 483084.0 484009.0 487178.0 488444.0 46200.5 46264.0 46448.0 46708.3 46712.0 986.1 992.1 1046.1 1073.6 1084.3 25802.6 27018.9 27159.5 27295.0 27552.2 39333.0 39541.0 40024.0 40060.6 40101.8 389378.0 398749.0 400846.0 402334.0 402954.0 20671.3 21647.7 21703.1 21728.5 21781.3 37789.0 38828.4 39628.0 40333.6 40833.2 34549.1 34982.3 35413.3 35575.5 36188.1 32957.8 33708.2 34201.3 34300.3 36088.8 32195.4 32967.5 33479.0 33684.5 33730.8 30101.0 31949.4 31990.5 32106.7 32449.0 23779.3 24112.2 24429.5 24524.5 24553.3 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#2] jquery.html Cycles:180 Average:26269.41 Median:4356.07 stddev:68741.97 (1578.1%) stddev-sans-first:68907.11 05:12:42 INFO - PROCESS | 2684 | Values: 278.6 303.7 307.0 308.6 313.0 12607.3 12614.9 13354.7 13364.8 13422.0 15665.3 16242.4 16322.9 16379.6 16397.4 4956.7 5141.7 5148.6 5156.5 5156.9 4034.9 4968.8 5053.9 5085.7 5089.7 5093.8 5095.4 5101.2 5104.1 5109.4 4901.1 4978.2 5065.5 5068.6 5070.7 4289.4 4422.7 4441.8 4454.5 4466.0 21213.6 21259.4 21355.1 21441.4 21467.5 1762.3 1793.3 1855.0 1923.4 2007.9 12625.6 12950.2 12990.7 13015.3 13363.1 1639.6 1699.5 1699.9 1756.3 1759.9 79802.6 79941.6 81328.0 82251.6 83567.0 8416.9 8559.4 8647.0 8811.4 8967.0 11425.3 11446.9 11496.3 11507.5 11545.4 6263.6 6726.5 6834.8 7273.3 7291.1 7123.0 7219.8 7284.0 7465.1 7583.4 5625.1 5675.5 5695.7 5714.7 5790.7 1437.1 1456.7 1477.8 1482.6 1504.8 719.7 780.6 781.9 782.0 804.3 17665.8 18407.4 18615.5 18628.9 18855.2 803.6 819.6 827.5 838.3 871.7 10075.4 10320.6 10370.9 10406.3 10626.5 404922.0 408743.0 413390.0 416437.0 416693.0 82734.6 86263.6 94309.5 96329.0 96982.0 881.5 910.0 925.1 950.3 957.5 16481.8 16482.5 16852.6 17005.1 17307.7 44639.3 45100.0 45360.5 45368.8 46062.5 63462.4 64306.4 64666.4 65034.3 66589.3 7132.0 7270.6 7313.8 7327.7 7338.6 11510.7 12530.6 12628.7 12795.1 13019.6 10469.5 10518.5 10642.7 10878.6 10886.4 10179.9 10370.8 10553.9 10582.4 10709.2 10615.0 10709.3 10765.1 10800.5 10978.4 7238.1 7270.3 7288.3 7350.4 7351.3 9374.9 10054.2 10400.6 10436.2 10495.6 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#3] mootools.html Cycles:180 Average:4217.73 Median:5336.33 stddev:2317.35 (43.4%) stddev-sans-first:2311.60 05:12:42 INFO - PROCESS | 2684 | Values: 1048.2 1115.6 1122.2 1173.4 1372.4 5745.3 6290.2 6471.1 6503.2 6539.6 6503.4 6519.7 6546.6 6554.8 6567.4 1542.8 1829.4 1864.3 1865.2 1868.8 1575.7 1752.5 1800.6 1810.7 1814.5 1846.2 1849.7 1849.7 1854.2 1854.8 1791.3 1801.3 1824.8 1825.1 1832.1 1710.4 1722.3 1730.1 1738.1 1740.3 6282.1 6357.8 6403.0 6411.8 6415.4 3760.4 3871.9 3977.6 3979.9 3986.2 2617.3 2715.3 2759.6 2766.6 2769.2 815.3 833.6 852.7 865.4 868.4 8301.2 8340.4 8358.5 8397.6 8423.7 3426.6 3429.1 3486.0 3530.0 3541.1 7182.2 7312.0 7312.0 7321.2 7360.2 3912.7 3940.0 4013.6 4032.4 4033.8 3321.0 3416.5 3619.1 3683.6 3737.2 2909.4 2968.0 2968.2 2976.5 3012.7 951.9 961.4 964.7 971.5 973.6 675.4 702.7 704.9 713.8 723.5 4054.7 4256.6 4298.4 4333.7 4348.6 697.7 706.9 715.5 717.9 724.2 2909.5 2970.3 3050.0 3062.9 3084.3 7762.0 7799.3 7869.7 7887.2 7908.8 7556.7 7802.8 7805.2 7814.6 7824.3 5716.8 6103.8 6138.7 6141.0 6164.1 4023.5 4234.2 4236.2 4245.4 4404.0 7083.0 7274.9 7284.1 7299.9 7316.7 7301.4 7308.9 7360.4 7416.5 7422.7 5431.4 5504.1 5588.3 5611.3 5630.9 6208.5 6227.3 6240.5 6260.6 6279.5 5429.2 5592.9 5635.7 5638.4 5665.2 5320.1 5352.6 5446.1 5466.4 5484.2 5149.0 5159.4 5202.2 5227.8 5236.0 3509.3 3519.6 3520.5 3570.1 3637.5 3532.2 3536.4 3676.5 3776.1 3801.0 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#4] prototype.html Cycles:180 Average:4259.00 Median:4949.42 stddev:1338.49 (27.0%) stddev-sans-first:1307.77 05:12:42 INFO - PROCESS | 2684 | Values: 237.3 240.8 247.4 248.1 248.6 5009.7 5043.7 5051.7 5065.4 5104.9 4770.8 4806.1 4866.6 4918.8 4933.3 3651.5 3662.2 3666.4 3667.3 3671.6 3628.3 3652.1 3654.3 3662.4 3668.2 3465.6 3504.8 3544.2 3633.8 3641.3 3632.6 3634.4 3640.0 3641.2 3646.5 3103.8 3134.5 3146.7 3146.7 3147.7 4851.2 4860.7 4873.3 4878.2 4880.5 4108.5 4223.5 4224.3 4226.4 4241.0 5089.5 5102.6 5116.2 5132.8 5137.6 3414.8 3452.7 3458.5 3465.2 3466.3 5269.8 5348.7 5381.9 5391.9 5407.9 3477.5 3539.8 3547.1 3553.5 3565.9 3300.4 3433.3 3503.2 3589.6 3614.2 4132.9 4133.6 4140.0 4150.6 4161.2 5202.4 5222.9 5224.4 5233.5 5249.5 1167.9 1179.4 1180.1 1180.8 1184.6 713.8 724.4 748.2 759.2 761.5 4856.2 4878.8 4892.9 4912.7 4932.9 5160.6 5207.9 5208.3 5210.2 5229.8 4890.3 4897.7 4902.6 4914.9 4917.0 2633.7 2761.3 2768.0 2773.8 2787.2 5498.9 5506.1 5513.2 5519.6 5527.7 5281.7 5331.9 5352.5 5359.3 5388.4 5381.3 5483.6 5509.5 5515.6 5518.5 5206.9 5236.3 5267.7 5281.6 5283.7 5135.5 5179.5 5184.7 5220.2 5226.4 5485.5 5501.3 5554.9 5565.3 5566.1 3245.0 3356.1 3357.4 3362.3 3373.2 5253.7 5270.3 5271.6 5278.8 5281.8 5050.4 5078.8 5080.2 5084.3 5092.8 5051.7 5073.6 5073.9 5113.0 5113.7 4868.9 4965.6 5115.8 5126.0 5138.6 5065.5 5261.5 5265.5 5266.7 5268.4 5032.5 5095.2 5108.5 5110.4 5127.0 05:12:42 INFO - PROCESS | 2684 | 05:12:42 INFO - PROCESS | 2684 | [#5] yui.html Cycles:180 Average:16594.26 Median:453.83 stddev:22138.51 (4878.2%) stddev-sans-first:22167.83 05:12:42 INFO - PROCESS | 2684 | Values: 548.2 564.7 566.9 578.9 632.5 13001.8 13253.5 13746.2 13750.9 13978.8 13124.4 13633.2 13686.6 13700.3 13718.3 579.3 580.3 590.5 592.8 599.6 599.0 601.3 601.5 601.7 602.6 625.2 626.8 627.0 627.5 628.1 624.2 624.7 624.9 625.2 628.9 453.8 453.8 456.3 458.5 458.6 12246.3 12501.2 12538.6 12541.0 12605.4 11944.0 12058.3 12189.5 12608.1 12612.7 10400.4 10919.8 11045.8 11204.2 11330.9 3220.7 3295.0 3319.8 3381.9 3382.5 58292.0 61532.7 63600.4 64784.7 64877.4 9616.5 9679.7 9685.1 9778.8 9783.0 15331.5 15485.8 15532.3 15569.9 15572.2 6862.5 6890.8 6933.6 7076.6 7164.4 3746.0 3837.1 3844.3 3919.1 3972.3 2372.4 2665.7 2669.2 2676.8 2684.4 465.3 470.5 474.9 479.1 483.9 624.6 644.8 656.4 661.5 668.6 8526.4 8533.6 8579.4 8660.1 8724.3 603.2 629.3 645.3 655.8 662.7 4120.0 4145.8 4186.6 4234.9 4255.2 80060.0 80556.6 81107.6 81470.0 82240.0 70398.0 72595.6 73010.6 73461.3 74226.3 65693.0 65888.7 66409.0 66548.7 66755.7 8547.6 8681.5 9033.7 9123.8 9161.5 40967.0 41458.4 41496.2 42551.6 43400.3 53472.2 55621.2 55773.4 56000.4 56199.0 10967.4 11434.5 11532.9 11862.5 11918.9 13355.3 13441.1 13453.9 13543.9 14060.7 13065.0 13103.3 13110.2 13255.0 13746.3 12344.6 12598.5 13024.1 13105.3 13151.8 12546.3 12921.1 13028.9 13036.0 13068.7 10495.7 10500.6 10549.3 10675.1 10888.7 10172.9 10830.7 10892.7 10893.8 10929.2 05:12:42 INFO - PROCESS | 2684 | -------- Summary: end -------- 05:12:42 INFO - PROCESS | 2684 | 05:12:43 INFO - TEST-INFO | 2684: exit 0 05:12:43 INFO - TEST-OK | dromaeo_css | took 1122027ms 05:12:43 INFO - TEST-START | kraken 05:12:43 INFO - operating with platform_type : w7_ 05:12:43 INFO - Initialising browser for kraken test... 05:12:43 INFO - TEST-INFO | started process 2656 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpczm41n\profile http://localhost:49564/getInfo.html) 05:12:48 INFO - PROCESS | 2656 | __metrics Screen width/height:1600/1200 05:12:48 INFO - PROCESS | 2656 | colorDepth:24 05:12:48 INFO - PROCESS | 2656 | Browser inner width/height: 1010/674 05:12:48 INFO - PROCESS | 2656 | __metrics 05:12:50 INFO - PROCESS | 2656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:12:50 INFO - PROCESS | 2656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:50 INFO - PROCESS | 2656 | console.error: 05:12:50 INFO - PROCESS | 2656 | Message: Error: Connection closed before committing the transaction. 05:12:50 INFO - PROCESS | 2656 | Stack: 05:12:50 INFO - PROCESS | 2656 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 05:12:50 INFO - PROCESS | 2656 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:12:50 INFO - PROCESS | 2656 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:12:50 INFO - PROCESS | 2656 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:12:50 INFO - PROCESS | 2656 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:12:50 INFO - PROCESS | 2656 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 05:12:50 INFO - PROCESS | 2656 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 05:12:50 INFO - PROCESS | 2656 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 05:12:50 INFO - PROCESS | 2656 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 05:12:50 INFO - PROCESS | 2656 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 05:12:50 INFO - PROCESS | 2656 | Barrier/this.client.addBlocker/promise13.2% 05:13:48 INFO - TinderboxPrint: I/O read bytes / time
197,692,928 / 2,872,350 05:13:48 INFO - TinderboxPrint: I/O write bytes / time
490,900,992 / 3,827,750 05:13:48 INFO - TinderboxPrint: CPU idle
8,271.5 (86.8%) 05:13:48 INFO - TinderboxPrint: CPU user
1,221.0 (12.8%) 05:13:48 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 35931136; Read time: 0; Write time: 290180 05:13:48 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 197692928; Write bytes: 418413056; Read time: 2872350; Write time: 3514290 05:13:49 INFO - Running post-run listener: _upload_blobber_files 05:13:49 INFO - Blob upload gear active. 05:13:49 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 05:13:49 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 05:13:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 05:13:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 05:13:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:49 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 05:13:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:13:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:51 INFO - (blobuploader) - INFO - Done attempting. 05:13:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 05:13:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:13:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:52 INFO - (blobuploader) - INFO - Done attempting. 05:13:52 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 05:13:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:14:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:00 INFO - (blobuploader) - INFO - Done attempting. 05:14:00 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:00 INFO - Return code: 0 05:14:00 INFO - rmtree: C:\slave\test\build\uploaded_files.json 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 05:14:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a3e3340039c96f7c43f5c5fea6f0853c82e065e5ee6fa665844ff749380dc6aef68ad3b7ae3d67aabfadbb9c2ebd0f0bcf108173242f3f25a8b16e14105f6a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81fb78a452dd50bbc983c5ea208ceb8c079f21f67cd49fbaeba8302c8cccd4f9a9db561a8690f093e8344356c464725259c4b90188d541a1df0cc51097e33317", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc68f1970185b9ebee11f253a970998a113e314f73f3adf8ed640250e950b425cf31f85ddf2322297aa95a5b0de7de797682459cc3d5745b7c2a4093a6bb64e9"} 05:14:00 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 05:14:00 INFO - Writing to file C:\slave\test\properties\blobber_files 05:14:00 INFO - Contents: 05:14:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a3e3340039c96f7c43f5c5fea6f0853c82e065e5ee6fa665844ff749380dc6aef68ad3b7ae3d67aabfadbb9c2ebd0f0bcf108173242f3f25a8b16e14105f6a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81fb78a452dd50bbc983c5ea208ceb8c079f21f67cd49fbaeba8302c8cccd4f9a9db561a8690f093e8344356c464725259c4b90188d541a1df0cc51097e33317", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc68f1970185b9ebee11f253a970998a113e314f73f3adf8ed640250e950b425cf31f85ddf2322297aa95a5b0de7de797682459cc3d5745b7c2a4093a6bb64e9"} 05:14:00 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:00 INFO - Copying logs to upload dir... 05:14:00 INFO - mkdir: C:\slave\test\build\upload\logs 05:14:00 INFO - Copying logs to upload dir... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... 05:14:00 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1390.660000 ========= master_lag: 0.26 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2016-11-25 05:14:00.083953) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.084753) ========= '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-093 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-093 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-093 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a3e3340039c96f7c43f5c5fea6f0853c82e065e5ee6fa665844ff749380dc6aef68ad3b7ae3d67aabfadbb9c2ebd0f0bcf108173242f3f25a8b16e14105f6a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81fb78a452dd50bbc983c5ea208ceb8c079f21f67cd49fbaeba8302c8cccd4f9a9db561a8690f093e8344356c464725259c4b90188d541a1df0cc51097e33317", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc68f1970185b9ebee11f253a970998a113e314f73f3adf8ed640250e950b425cf31f85ddf2322297aa95a5b0de7de797682459cc3d5745b7c2a4093a6bb64e9"} build_url:https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/NfHoilTXTKKc3pOJZVrKVw/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a3e3340039c96f7c43f5c5fea6f0853c82e065e5ee6fa665844ff749380dc6aef68ad3b7ae3d67aabfadbb9c2ebd0f0bcf108173242f3f25a8b16e14105f6a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81fb78a452dd50bbc983c5ea208ceb8c079f21f67cd49fbaeba8302c8cccd4f9a9db561a8690f093e8344356c464725259c4b90188d541a1df0cc51097e33317", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc68f1970185b9ebee11f253a970998a113e314f73f3adf8ed640250e950b425cf31f85ddf2322297aa95a5b0de7de797682459cc3d5745b7c2a4093a6bb64e9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.198622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.198973) ========= '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-093 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-093 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-093 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.102000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.310944) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.311285) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 05:14:00.760592) ========= ========= Total master_lag: 5.88 =========