builder: mozilla-beta_win7_ix_test-dromaeojs-e10s-pgo slave: t-w732-ix-116 starttime: 1486666952.55 results: success (0) buildid: 20170209092520 builduid: 497c94cae0b3422d952c6d22b8ce7e60 revision: 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:32.547887) ========= master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:32.548564) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:32.549054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:32.633794) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 11:02:32.634149) ========= '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-116 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-116 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-116 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 --11:02:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|: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% 8.95 MB/s 11:02:33 (8.95 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.059000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 11:02:33.759794) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 11:02:33.760115) ========= '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-116 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-116 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-116 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=7.701000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2017-02-09 11:02:41.470952) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-02-09 11:02:41.471306) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-116 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-116 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-116 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 2017-02-09 11:02:41,622 truncating revision to first 12 chars 2017-02-09 11:02:41,622 Setting DEBUG logging. 2017-02-09 11:02:41,622 attempt 1/10 2017-02-09 11:02:41,622 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/15d8f9d49bfe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 11:02:45,118 unpacking tar archive at: mozilla-beta-15d8f9d49bfe/testing/mozharness/ 2017-02-09 11:02:45,242 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=4.074000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-02-09 11:02:45.560125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:45.560447) ========= script_repo_revision: 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:45.560854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:45.561122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 11:02:45.570443) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 32 secs) (at 2017-02-09 11:02:45.570713) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Beta' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Beta' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-116 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-116 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-116 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 11:02:46 INFO - MultiFileLogger online at 20170209 11:02:46 in C:\slave\test 11:02:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:02:46 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 11:02:46 INFO - {'append_to_log': False, 11:02:46 INFO - 'base_work_dir': 'C:\\slave\\test', 11:02:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:02:46 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 11:02:46 INFO - 'branch': 'Mozilla-Beta', 11:02:46 INFO - 'buildbot_json_path': 'buildprops.json', 11:02:46 INFO - 'config_files': ('talos/windows_config.py',), 11:02:46 INFO - 'default_actions': ('clobber', 11:02:46 INFO - 'read-buildbot-config', 11:02:46 INFO - 'download-and-extract', 11:02:46 INFO - 'populate-webroot', 11:02:46 INFO - 'create-virtualenv', 11:02:46 INFO - 'install', 11:02:46 INFO - 'run-tests'), 11:02:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:02:46 INFO - 'download_minidump_stackwalk': True, 11:02:46 INFO - 'download_symbols': 'ondemand', 11:02:46 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 11:02:46 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 11:02:46 INFO - 'hg': 'c:/mozilla-build/hg/hg', 11:02:46 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 11:02:46 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 11:02:46 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 11:02:46 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 11:02:46 INFO - 'C:/mozilla-build/tooltool.py'), 11:02:46 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 11:02:46 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 11:02:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:02:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:02:46 INFO - 'installer_path': 'installer.exe', 11:02:46 INFO - 'log_level': 'info', 11:02:46 INFO - 'log_name': 'talos', 11:02:46 INFO - 'log_to_console': True, 11:02:46 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 11:02:46 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 11:02:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 11:02:46 INFO - 'opt_config_files': (), 11:02:46 INFO - 'pip_index': False, 11:02:46 INFO - 'sps_profile': False, 11:02:46 INFO - 'sps_profile_interval': 0, 11:02:46 INFO - 'suite': 'dromaeojs-e10s', 11:02:46 INFO - 'system_bits': '32', 11:02:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:02:46 INFO - 'title': 'T-W732-IX-116', 11:02:46 INFO - 'use_talos_json': True, 11:02:46 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 11:02:46 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 11:02:46 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 11:02:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:02:46 INFO - 'work_dir': 'build'} 11:02:46 INFO - [mozharness: 2017-02-09 19:02:46.083000Z] Running clobber step. 11:02:46 INFO - Running pre-action listener: _resource_record_pre_action 11:02:46 INFO - Running main action method: clobber 11:02:46 INFO - rmtree: C:\slave\test\build 11:02:46 INFO - Using _rmtree_windows ... 11:02:46 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 11:05:01 INFO - Running post-action listener: _resource_record_post_action 11:05:01 INFO - [mozharness: 2017-02-09 19:05:01.295000Z] Finished clobber step (success) 11:05:01 INFO - [mozharness: 2017-02-09 19:05:01.295000Z] Running read-buildbot-config step. 11:05:01 INFO - Running pre-action listener: _resource_record_pre_action 11:05:01 INFO - Running main action method: read_buildbot_config 11:05:01 INFO - Using buildbot properties: 11:05:01 INFO - { 11:05:01 INFO - "project": "", 11:05:01 INFO - "product": "firefox", 11:05:01 INFO - "script_repo_revision": "production", 11:05:01 INFO - "builddir": "mozilla-beta_win7_ix_test-dromaeojs-e10s", 11:05:01 INFO - "repository": "", 11:05:01 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos dromaeojs-e10s", 11:05:01 INFO - "buildid": "20170209092520", 11:05:01 INFO - "pgo_build": "True", 11:05:01 INFO - "basedir": "C:\\slave\\test", 11:05:01 INFO - "buildnumber": 28, 11:05:01 INFO - "slavename": "t-w732-ix-116", 11:05:01 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 11:05:01 INFO - "platform": "win7_ix", 11:05:01 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 11:05:01 INFO - "branch": "mozilla-beta", 11:05:01 INFO - "repo_path": "releases/mozilla-beta", 11:05:01 INFO - "revision": "15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91", 11:05:01 INFO - "stage_platform": "win32-pgo", 11:05:01 INFO - "builduid": "497c94cae0b3422d952c6d22b8ce7e60", 11:05:01 INFO - "slavebuilddir": "test" 11:05:01 INFO - } 11:05:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip. 11:05:01 INFO - Running post-action listener: _resource_record_post_action 11:05:01 INFO - [mozharness: 2017-02-09 19:05:01.299000Z] Finished read-buildbot-config step (success) 11:05:01 INFO - [mozharness: 2017-02-09 19:05:01.299000Z] Running download-and-extract step. 11:05:01 INFO - Running pre-action listener: _resource_record_pre_action 11:05:01 INFO - Running main action method: download_and_extract 11:05:01 INFO - mkdir: C:\slave\test\build\tests 11:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:01 INFO - trying https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.test_packages.json 11:05:01 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0.en-US.win32.test_packages.json 11:05:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0.en-US.win32.test_packages.json'}, attempt #1 11:05:02 INFO - Downloaded 1186 bytes. 11:05:02 INFO - Reading from file C:\slave\test\build\firefox-52.0.en-US.win32.test_packages.json 11:05:02 INFO - Using the following test package requirements: 11:05:02 INFO - {u'common': [u'firefox-52.0.en-US.win32.common.tests.zip'], 11:05:02 INFO - u'cppunittest': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.cppunittest.tests.zip'], 11:05:02 INFO - u'gtest': [u'firefox-52.0.en-US.win32.common.tests.zip'], 11:05:02 INFO - u'jittest': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'jsshell-win32.zip'], 11:05:02 INFO - u'mochitest': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.mochitest.tests.zip'], 11:05:02 INFO - u'mozbase': [u'firefox-52.0.en-US.win32.common.tests.zip'], 11:05:02 INFO - u'reftest': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.reftest.tests.zip'], 11:05:02 INFO - u'talos': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.talos.tests.zip'], 11:05:02 INFO - u'web-platform': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.web-platform.tests.zip'], 11:05:02 INFO - u'xpcshell': [u'firefox-52.0.en-US.win32.common.tests.zip', 11:05:02 INFO - u'firefox-52.0.en-US.win32.xpcshell.tests.zip']} 11:05:02 INFO - Downloading packages: [u'firefox-52.0.en-US.win32.common.tests.zip'] for test suite category: common 11:05:02 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip 11:05:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip'}, attempt #1 11:05:02 INFO - Fetch https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip into memory 11:05:03 INFO - Http code: 200 11:05:03 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 11:05:03 INFO - x-amz-cf-id: HL9ZrpatTWGH_mYNy251wC2_IeD8dqSPCEbpN4AR9WkVq9VT81tUHw== 11:05:03 INFO - x-amz-version-id: qKkLndRDJx1tmMYjP0UOFBFKuXJHflLq 11:05:03 INFO - Expected file size: 17043359 11:05:03 INFO - Obtained file size: 17043359 11:05:22 INFO - Downloading packages: [u'firefox-52.0.en-US.win32.common.tests.zip', u'firefox-52.0.en-US.win32.talos.tests.zip'] for test suite category: talos 11:05:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip 11:05:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip'}, attempt #1 11:05:22 INFO - Fetch https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.common.tests.zip into memory 11:05:23 INFO - Http code: 200 11:05:23 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 11:05:23 INFO - x-amz-cf-id: dzlwGVhACRx8qTme1umljJT1aBJHhRPbXyBo8K0ibuFygGqpw2n8nA== 11:05:23 INFO - x-amz-version-id: qKkLndRDJx1tmMYjP0UOFBFKuXJHflLq 11:05:23 INFO - Expected file size: 17043359 11:05:23 INFO - Obtained file size: 17043359 11:05:33 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.talos.tests.zip 11:05:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.talos.tests.zip'}, attempt #1 11:05:33 INFO - Fetch https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.talos.tests.zip into memory 11:05:33 INFO - Http code: 200 11:05:33 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 11:05:33 INFO - x-amz-cf-id: NewdfXGXdSItsHXRM6Um9XqVofPWZQOeV0d-j68dyledegGFuUOysA== 11:05:33 INFO - x-amz-version-id: afC9Z6uSTK4ze6SmBMHW6W.KPwxjaEFX 11:05:34 INFO - Expected file size: 14403005 11:05:34 INFO - Obtained file size: 14403005 11:05:34 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')]} 11:05:34 INFO - trying https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip 11:05:34 INFO - Downloading https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip to C:\slave\test\build\firefox-52.0.en-US.win32.zip 11:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0.en-US.win32.zip'}, attempt #1 11:05:38 INFO - Downloaded 56291485 bytes. 11:05:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip 11:05:38 INFO - mkdir: C:\slave\test\properties 11:05:38 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 11:05:38 INFO - Writing to file C:\slave\test\properties\build_url 11:05:38 INFO - Contents: 11:05:38 INFO - build_url:https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip 11:05:39 INFO - Running post-action listener: _resource_record_post_action 11:05:39 INFO - Running post-action listener: set_extra_try_arguments 11:05:39 INFO - [mozharness: 2017-02-09 19:05:39.411000Z] Finished download-and-extract step (success) 11:05:39 INFO - [mozharness: 2017-02-09 19:05:39.411000Z] Running populate-webroot step. 11:05:39 INFO - Running pre-action listener: _resource_record_pre_action 11:05:39 INFO - Running main action method: populate_webroot 11:05:39 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 11:05:39 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 11:05:39 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 11:05:39 INFO - u'--tppagecycles', 11:05:39 INFO - u'7'], 11:05:39 INFO - u'tests': [u'tsvgm']}}, 11:05:39 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 11:05:39 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 11:05:39 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 11:05:39 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 11:05:39 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 11:05:39 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 11:05:39 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'damp', u'tps']}, 11:05:39 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'tests': [u'damp', u'tps']}, 11:05:39 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'dromaeo_dom']}, 11:05:39 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 11:05:39 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'basic_compositor_video']}, 11:05:39 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 11:05:39 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'other-e10s': {u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'a11yr', 11:05:39 INFO - u'ts_paint', 11:05:39 INFO - u'tpaint', 11:05:39 INFO - u'sessionrestore', 11:05:39 INFO - u'sessionrestore_no_auto_restore', 11:05:39 INFO - u'tabpaint']}, 11:05:39 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'tsvgx', 11:05:39 INFO - u'tsvgr_opacity', 11:05:39 INFO - u'tart', 11:05:39 INFO - u'tscrollx', 11:05:39 INFO - u'cart']}, 11:05:39 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 11:05:39 INFO - u'tsvgr_opacity', 11:05:39 INFO - u'tart', 11:05:39 INFO - u'tscrollx', 11:05:39 INFO - u'cart']}, 11:05:39 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'talos_options': [u'--disable-e10s'], 11:05:39 INFO - u'tests': [u'tp5o']}, 11:05:39 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'tests': [u'tp5o']}, 11:05:39 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'talos_options': [u'--disable-e10s', 11:05:39 INFO - u'--xperf_path', 11:05:39 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:39 INFO - u'tests': [u'tp5n']}, 11:05:39 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:05:39 INFO - u'talos_options': [u'--xperf_path', 11:05:39 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:05:39 INFO - u'tests': [u'tp5n']}}, 11:05:39 INFO - u'talos.zip': {u'path': u'', 11:05:39 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:05:39 INFO - Running post-action listener: _resource_record_post_action 11:05:39 INFO - [mozharness: 2017-02-09 19:05:39.499000Z] Finished populate-webroot step (success) 11:05:39 INFO - [mozharness: 2017-02-09 19:05:39.500000Z] Running create-virtualenv step. 11:05:39 INFO - Running pre-action listener: _resource_record_pre_action 11:05:39 INFO - Running main action method: create_virtualenv 11:05:39 INFO - Creating virtualenv C:\slave\test\build/venv 11:05:39 INFO - mkdir: C:\slave\test\build\venv\Scripts 11:05:39 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 11:05:39 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'! 11:05:39 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 11:05:39 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 11:05:42 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 11:05:46 INFO - Installing distribute......................................................................................................................................................................................done. 11:05:48 INFO - Return code: 0 11:05:48 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 11:05:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 11:05:49 INFO - Reading from file tmpfile_stdout 11:05:49 INFO - Output received: 11:05:49 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 11:05:49 INFO - Using _rmtree_windows ... 11:05:49 INFO - Using _rmtree_windows ... 11:05:49 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 11:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:05:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 11:05:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:05:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:05:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:05:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:05:49 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:05:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:05:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:05:49 INFO - 'DCLOCATION': 'SCL3', 11:05:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:05:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:05:49 INFO - 'HOMEDRIVE': 'C:', 11:05:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:05:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:05:49 INFO - 'KTS_VERSION': '1.19c', 11:05:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:05:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:05:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:05:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:49 INFO - 'MOZ_NO_REMOTE': '1', 11:05:49 INFO - 'NO_EM_RESTART': '1', 11:05:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:05:49 INFO - 'OS': 'Windows_NT', 11:05:49 INFO - 'OURDRIVE': 'C:', 11:05:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:05:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:05:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:05:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:05:49 INFO - 'PROCESSOR_LEVEL': '6', 11:05:49 INFO - 'PROCESSOR_REVISION': '1e05', 11:05:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:05:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:05:49 INFO - 'PROMPT': '$P$G', 11:05:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:05:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:05:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:05:49 INFO - 'PWD': 'C:\\slave\\test', 11:05:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:05:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:05:49 INFO - 'SYSTEMDRIVE': 'C:', 11:05:49 INFO - 'SYSTEMROOT': 'C:\\windows', 11:05:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:49 INFO - 'TEST1': 'testie', 11:05:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:49 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:05:49 INFO - 'USERNAME': 'cltbld', 11:05:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:05:49 INFO - 'WINDIR': 'C:\\windows', 11:05:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:05:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:05:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:05:50 INFO - Downloading/unpacking pip>=1.5 11:05:54 INFO - Running setup.py egg_info for package pip 11:05:54 INFO - warning: no files found matching 'pip\cacert.pem' 11:05:54 INFO - warning: no files found matching '*.html' under directory 'docs' 11:05:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 11:05:54 INFO - no previously-included directories found matching 'docs\_build\_sources' 11:05:54 INFO - Installing collected packages: pip 11:05:54 INFO - Found existing installation: pip 0.8.2 11:05:54 INFO - Uninstalling pip: 11:05:54 INFO - Successfully uninstalled pip 11:05:54 INFO - Running setup.py install for pip 11:05:54 INFO - warning: no files found matching 'pip\cacert.pem' 11:05:54 INFO - warning: no files found matching '*.html' under directory 'docs' 11:05:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 11:05:54 INFO - no previously-included directories found matching 'docs\_build\_sources' 11:05:54 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 11:05:54 INFO - Successfully installed pip 11:05:54 INFO - Cleaning up... 11:05:54 INFO - Return code: 0 11:05:54 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 11:05:54 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')]} 11:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:54 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')]} 11:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:54 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:05:54 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 11:05:54 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 11:05:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:05:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:05:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:05:54 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:05:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:05:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:05:54 INFO - 'DCLOCATION': 'SCL3', 11:05:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:05:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:05:54 INFO - 'HOMEDRIVE': 'C:', 11:05:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:05:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:05:54 INFO - 'KTS_VERSION': '1.19c', 11:05:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:05:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:05:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:05:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:54 INFO - 'MOZ_NO_REMOTE': '1', 11:05:54 INFO - 'NO_EM_RESTART': '1', 11:05:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:05:54 INFO - 'OS': 'Windows_NT', 11:05:54 INFO - 'OURDRIVE': 'C:', 11:05:54 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;', 11:05:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:05:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:05:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:05:54 INFO - 'PROCESSOR_LEVEL': '6', 11:05:54 INFO - 'PROCESSOR_REVISION': '1e05', 11:05:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:05:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:05:54 INFO - 'PROMPT': '$P$G', 11:05:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:05:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:05:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:05:54 INFO - 'PWD': 'C:\\slave\\test', 11:05:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:05:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:05:54 INFO - 'SYSTEMDRIVE': 'C:', 11:05:54 INFO - 'SYSTEMROOT': 'C:\\windows', 11:05:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:54 INFO - 'TEST1': 'testie', 11:05:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:54 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:05:54 INFO - 'USERNAME': 'cltbld', 11:05:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:05:54 INFO - 'WINDIR': 'C:\\windows', 11:05:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:05:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:56 INFO - Downloading/unpacking psutil>=3.1.1 11:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:57 INFO - Installing collected packages: psutil 11:05:57 INFO - Successfully installed psutil 11:05:57 INFO - Cleaning up... 11:05:57 INFO - Return code: 0 11:05:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 11:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:57 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:05:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 11:05:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:05:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:05:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:05:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:05:57 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:05:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:05:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:05:57 INFO - 'DCLOCATION': 'SCL3', 11:05:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:05:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:05:57 INFO - 'HOMEDRIVE': 'C:', 11:05:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:05:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:05:57 INFO - 'KTS_VERSION': '1.19c', 11:05:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:05:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:05:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:05:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:57 INFO - 'MOZ_NO_REMOTE': '1', 11:05:57 INFO - 'NO_EM_RESTART': '1', 11:05:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:05:57 INFO - 'OS': 'Windows_NT', 11:05:57 INFO - 'OURDRIVE': 'C:', 11:05:57 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:05:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:05:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:05:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:05:57 INFO - 'PROCESSOR_LEVEL': '6', 11:05:57 INFO - 'PROCESSOR_REVISION': '1e05', 11:05:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:05:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:05:57 INFO - 'PROMPT': '$P$G', 11:05:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:05:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:05:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:05:57 INFO - 'PWD': 'C:\\slave\\test', 11:05:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:05:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:05:57 INFO - 'SYSTEMDRIVE': 'C:', 11:05:57 INFO - 'SYSTEMROOT': 'C:\\windows', 11:05:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:57 INFO - 'TEST1': 'testie', 11:05:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:57 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:05:57 INFO - 'USERNAME': 'cltbld', 11:05:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:05:57 INFO - 'WINDIR': 'C:\\windows', 11:05:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:05:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:05:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 11:05:59 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) 11:05:59 INFO - Installing collected packages: mozsystemmonitor 11:05:59 INFO - Running setup.py install for mozsystemmonitor 11:05:59 INFO - Successfully installed mozsystemmonitor 11:05:59 INFO - Cleaning up... 11:05:59 INFO - Return code: 0 11:05:59 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 11:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:59 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:05:59 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 11:05:59 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 11:05:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:05:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:05:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:05:59 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:05:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:05:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:05:59 INFO - 'DCLOCATION': 'SCL3', 11:05:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:05:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:05:59 INFO - 'HOMEDRIVE': 'C:', 11:05:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:05:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:05:59 INFO - 'KTS_VERSION': '1.19c', 11:05:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:05:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:05:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:05:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:59 INFO - 'MOZ_NO_REMOTE': '1', 11:05:59 INFO - 'NO_EM_RESTART': '1', 11:05:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:05:59 INFO - 'OS': 'Windows_NT', 11:05:59 INFO - 'OURDRIVE': 'C:', 11:05:59 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 11:05:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:05:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:05:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:05:59 INFO - 'PROCESSOR_LEVEL': '6', 11:05:59 INFO - 'PROCESSOR_REVISION': '1e05', 11:05:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:05:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:05:59 INFO - 'PROMPT': '$P$G', 11:05:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:05:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:05:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:05:59 INFO - 'PWD': 'C:\\slave\\test', 11:05:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:05:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:05:59 INFO - 'SYSTEMDRIVE': 'C:', 11:05:59 INFO - 'SYSTEMROOT': 'C:\\windows', 11:05:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:59 INFO - 'TEST1': 'testie', 11:05:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:05:59 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:05:59 INFO - 'USERNAME': 'cltbld', 11:05:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:05:59 INFO - 'WINDIR': 'C:\\windows', 11:05:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:05:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:00 INFO - Downloading/unpacking jsonschema==2.5.1 11:06:00 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 11:06:00 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 11:06:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:06:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:06:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 11:06:01 INFO - warning: no files found matching '*.txt' 11:06:01 INFO - no previously-included directories found matching 'build' 11:06:01 INFO - no previously-included directories found matching 'dist' 11:06:01 INFO - no previously-included directories found matching '.git*' 11:06:01 INFO - Installing collected packages: jsonschema, functools32 11:06:01 INFO - Running setup.py install for functools32 11:06:01 INFO - warning: no files found matching '*.txt' 11:06:01 INFO - no previously-included directories found matching 'build' 11:06:01 INFO - no previously-included directories found matching 'dist' 11:06:01 INFO - no previously-included directories found matching '.git*' 11:06:01 INFO - Successfully installed jsonschema functools32 11:06:01 INFO - Cleaning up... 11:06:02 INFO - Return code: 0 11:06:02 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 11:06:02 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')]} 11:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:02 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')]} 11:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:02 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:02 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 11:06:02 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 11:06:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:02 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:02 INFO - 'DCLOCATION': 'SCL3', 11:06:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:02 INFO - 'HOMEDRIVE': 'C:', 11:06:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:02 INFO - 'KTS_VERSION': '1.19c', 11:06:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:02 INFO - 'MOZ_NO_REMOTE': '1', 11:06:02 INFO - 'NO_EM_RESTART': '1', 11:06:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:02 INFO - 'OS': 'Windows_NT', 11:06:02 INFO - 'OURDRIVE': 'C:', 11:06:02 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;', 11:06:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:02 INFO - 'PROCESSOR_LEVEL': '6', 11:06:02 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:02 INFO - 'PROMPT': '$P$G', 11:06:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:02 INFO - 'PWD': 'C:\\slave\\test', 11:06:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:02 INFO - 'SYSTEMDRIVE': 'C:', 11:06:02 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:02 INFO - 'TEST1': 'testie', 11:06:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:02 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:02 INFO - 'USERNAME': 'cltbld', 11:06:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:02 INFO - 'WINDIR': 'C:\\windows', 11:06:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 11:06:02 INFO - Cleaning up... 11:06:02 INFO - Return code: 0 11:06:02 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 11:06:02 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')]} 11:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:02 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')]} 11:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:02 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:02 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 11:06:02 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 11:06:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:02 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:02 INFO - 'DCLOCATION': 'SCL3', 11:06:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:02 INFO - 'HOMEDRIVE': 'C:', 11:06:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:02 INFO - 'KTS_VERSION': '1.19c', 11:06:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:02 INFO - 'MOZ_NO_REMOTE': '1', 11:06:02 INFO - 'NO_EM_RESTART': '1', 11:06:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:02 INFO - 'OS': 'Windows_NT', 11:06:02 INFO - 'OURDRIVE': 'C:', 11:06:02 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;', 11:06:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:02 INFO - 'PROCESSOR_LEVEL': '6', 11:06:02 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:02 INFO - 'PROMPT': '$P$G', 11:06:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:02 INFO - 'PWD': 'C:\\slave\\test', 11:06:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:02 INFO - 'SYSTEMDRIVE': 'C:', 11:06:02 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:02 INFO - 'TEST1': 'testie', 11:06:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:02 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:02 INFO - 'USERNAME': 'cltbld', 11:06:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:02 INFO - 'WINDIR': 'C:\\windows', 11:06:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:03 INFO - Downloading/unpacking blobuploader==1.2.4 11:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:04 INFO - Downloading blobuploader-1.2.4.tar.gz 11:06:04 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 11:06:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:06:04 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 11:06:04 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 11:06:04 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 11:06:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:06:04 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 11:06:04 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 11:06:04 INFO - Downloading docopt-0.6.1.tar.gz 11:06:04 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 11:06:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:06:04 INFO - Running setup.py install for blobuploader 11:06:05 INFO - Running setup.py install for requests 11:06:06 INFO - Running setup.py install for docopt 11:06:06 INFO - Successfully installed blobuploader requests docopt 11:06:06 INFO - Cleaning up... 11:06:07 INFO - Return code: 0 11:06:07 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:06:07 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')]} 11:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:07 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')]} 11:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:07 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:07 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 11:06:07 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 11:06:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:07 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:07 INFO - 'DCLOCATION': 'SCL3', 11:06:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:07 INFO - 'HOMEDRIVE': 'C:', 11:06:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:07 INFO - 'KTS_VERSION': '1.19c', 11:06:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:07 INFO - 'MOZ_NO_REMOTE': '1', 11:06:07 INFO - 'NO_EM_RESTART': '1', 11:06:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:07 INFO - 'OS': 'Windows_NT', 11:06:07 INFO - 'OURDRIVE': 'C:', 11:06:07 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;', 11:06:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:07 INFO - 'PROCESSOR_LEVEL': '6', 11:06:07 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:07 INFO - 'PROMPT': '$P$G', 11:06:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:07 INFO - 'PWD': 'C:\\slave\\test', 11:06:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:07 INFO - 'SYSTEMDRIVE': 'C:', 11:06:07 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:07 INFO - 'TEST1': 'testie', 11:06:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:07 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:07 INFO - 'USERNAME': 'cltbld', 11:06:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:07 INFO - 'WINDIR': 'C:\\windows', 11:06:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hehxgn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k_6nnf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvcijv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a70nln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k1l_3v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vfssqu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 11:06:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 11:06:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vjjaih-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rlkggo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cjvxcm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fjrwre-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-epsdtl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wynyam-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jvktdb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 11:06:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 11:06:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqck6n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 11:06:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 11:06:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ht9xd6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 11:06:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 11:06:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l43ghi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 11:06:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 11:06:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ly4jhb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 11:06:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:06:10 INFO - Running setup.py install for manifestparser 11:06:10 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 11:06:10 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 11:06:11 INFO - Running setup.py install for mozcrash 11:06:11 INFO - Running setup.py install for mozdebug 11:06:12 INFO - Running setup.py install for mozdevice 11:06:12 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 11:06:12 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 11:06:12 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 11:06:12 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 11:06:12 INFO - Running setup.py install for mozfile 11:06:13 INFO - Running setup.py install for mozhttpd 11:06:13 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 11:06:13 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 11:06:13 INFO - Running setup.py install for mozinfo 11:06:13 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 11:06:13 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 11:06:13 INFO - Running setup.py install for mozInstall 11:06:14 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Running setup.py install for mozleak 11:06:14 INFO - Running setup.py install for mozlog 11:06:14 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 11:06:14 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 11:06:15 INFO - Running setup.py install for moznetwork 11:06:15 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 11:06:15 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 11:06:15 INFO - Running setup.py install for mozprocess 11:06:16 INFO - Running setup.py install for mozprofile 11:06:16 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 11:06:16 INFO - Running setup.py install for mozrunner 11:06:17 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 11:06:17 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 11:06:17 INFO - Running setup.py install for mozscreenshot 11:06:18 INFO - Running setup.py install for moztest 11:06:18 INFO - Running setup.py install for mozversion 11:06:18 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 11:06:18 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 11:06:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:06:18 INFO - Cleaning up... 11:06:18 INFO - Return code: 0 11:06:18 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:06: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')]} 11:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06: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')]} 11:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:18 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:18 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 11:06:18 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 11:06:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:18 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:18 INFO - 'DCLOCATION': 'SCL3', 11:06:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:18 INFO - 'HOMEDRIVE': 'C:', 11:06:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:18 INFO - 'KTS_VERSION': '1.19c', 11:06:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:18 INFO - 'MOZ_NO_REMOTE': '1', 11:06:18 INFO - 'NO_EM_RESTART': '1', 11:06:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:18 INFO - 'OS': 'Windows_NT', 11:06:18 INFO - 'OURDRIVE': 'C:', 11:06: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;', 11:06:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:18 INFO - 'PROCESSOR_LEVEL': '6', 11:06:18 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:18 INFO - 'PROMPT': '$P$G', 11:06:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:18 INFO - 'PWD': 'C:\\slave\\test', 11:06:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:18 INFO - 'SYSTEMDRIVE': 'C:', 11:06:18 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:18 INFO - 'TEST1': 'testie', 11:06:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:18 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:18 INFO - 'USERNAME': 'cltbld', 11:06:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:18 INFO - 'WINDIR': 'C:\\windows', 11:06:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-egie5a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 11:06:19 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)) 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rwpiaz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 11:06:19 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)) 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k0wyci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 11:06:19 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)) 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybp8db-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 11:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3txz3_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 11:06:19 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)) 11:06:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 11:06:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4rjdls-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7l1kbz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nvug6f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-onha9v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jrjfew-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wcu8p9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qq0ro5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 11:06:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c6t7tx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 11:06:20 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)) 11:06:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 11:06:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s30df6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 11:06:21 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)) 11:06:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 11:06:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zovkmc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 11:06:21 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)) 11:06:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 11:06:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-odetmo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 11:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 11:06:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 11:06:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-etbavo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 11:06:21 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)) 11:06:21 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)) 11:06:21 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)) 11:06:21 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)) 11:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 11:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 11:06:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 11:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:22 INFO - Downloading blessings-1.6.tar.gz 11:06:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 11:06:23 INFO - Installing collected packages: blessings 11:06:23 INFO - Running setup.py install for blessings 11:06:23 INFO - Successfully installed blessings 11:06:23 INFO - Cleaning up... 11:06:26 INFO - Return code: 0 11:06:26 INFO - Done creating virtualenv C:\slave\test\build/venv. 11:06:26 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 11:06:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 11:06:26 INFO - Reading from file tmpfile_stdout 11:06:26 INFO - Using _rmtree_windows ... 11:06:26 INFO - Using _rmtree_windows ... 11:06:26 INFO - Current package versions: 11:06:26 INFO - blessings == 1.6 11:06:26 INFO - blobuploader == 1.2.4 11:06:26 INFO - docopt == 0.6.1 11:06:26 INFO - functools32 == 3.2.3-2 11:06:26 INFO - jsonschema == 2.5.1 11:06:26 INFO - manifestparser == 1.1 11:06:26 INFO - mozInstall == 1.12 11:06:26 INFO - mozcrash == 1.0 11:06:26 INFO - mozdebug == 0.1 11:06:26 INFO - mozdevice == 0.48 11:06:26 INFO - mozfile == 1.2 11:06:26 INFO - mozhttpd == 0.7 11:06:26 INFO - mozinfo == 0.9 11:06:26 INFO - mozleak == 0.1 11:06:26 INFO - mozlog == 3.4 11:06:26 INFO - moznetwork == 0.27 11:06:26 INFO - mozprocess == 0.23 11:06:26 INFO - mozprofile == 0.28 11:06:26 INFO - mozrunner == 6.13 11:06:26 INFO - mozscreenshot == 0.1 11:06:26 INFO - mozsystemmonitor == 0.3 11:06:26 INFO - moztest == 0.8 11:06:26 INFO - mozversion == 1.4 11:06:26 INFO - psutil == 3.1.1 11:06:26 INFO - requests == 1.2.3 11:06:26 INFO - Installing None into virtualenv C:\slave\test\build/venv 11:06:26 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')]} 11:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:26 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')]} 11:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:26 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:26 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 11:06:26 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 11:06:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:26 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:26 INFO - 'DCLOCATION': 'SCL3', 11:06:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:26 INFO - 'HOMEDRIVE': 'C:', 11:06:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:26 INFO - 'KTS_VERSION': '1.19c', 11:06:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:26 INFO - 'MOZ_NO_REMOTE': '1', 11:06:26 INFO - 'NO_EM_RESTART': '1', 11:06:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:26 INFO - 'OS': 'Windows_NT', 11:06:26 INFO - 'OURDRIVE': 'C:', 11:06:26 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;', 11:06:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:26 INFO - 'PROCESSOR_LEVEL': '6', 11:06:26 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:26 INFO - 'PROMPT': '$P$G', 11:06:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:26 INFO - 'PWD': 'C:\\slave\\test', 11:06:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:26 INFO - 'SYSTEMDRIVE': 'C:', 11:06:26 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:26 INFO - 'TEST1': 'testie', 11:06:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:26 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:26 INFO - 'USERNAME': 'cltbld', 11:06:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:26 INFO - 'WINDIR': 'C:\\windows', 11:06:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 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)) 11:06:26 INFO - Cleaning up... 11:06:26 INFO - Return code: 0 11:06:26 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 11:06:26 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')]} 11:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:26 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')]} 11:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:26 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 0x0183A640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017539B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-116', 'USERDOMAIN': 'T-W732-IX-116', '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-116', '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 11:06:26 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 11:06:26 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 11:06:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:26 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:26 INFO - 'DCLOCATION': 'SCL3', 11:06:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:26 INFO - 'HOMEDRIVE': 'C:', 11:06:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:26 INFO - 'KTS_VERSION': '1.19c', 11:06:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:26 INFO - 'MOZ_NO_REMOTE': '1', 11:06:26 INFO - 'NO_EM_RESTART': '1', 11:06:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:26 INFO - 'OS': 'Windows_NT', 11:06:26 INFO - 'OURDRIVE': 'C:', 11:06:26 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;', 11:06:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:26 INFO - 'PROCESSOR_LEVEL': '6', 11:06:26 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:26 INFO - 'PROMPT': '$P$G', 11:06:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:26 INFO - 'PWD': 'C:\\slave\\test', 11:06:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:26 INFO - 'SYSTEMDRIVE': 'C:', 11:06:26 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:26 INFO - 'TEST1': 'testie', 11:06:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:26 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:26 INFO - 'USERNAME': 'cltbld', 11:06:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:26 INFO - 'WINDIR': 'C:\\windows', 11:06:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 11:06:27 INFO - Cleaning up... 11:06:27 INFO - Return code: 0 11:06:27 INFO - Running post-action listener: _resource_record_post_action 11:06:27 INFO - Running post-action listener: _start_resource_monitoring 11:06:27 INFO - Starting resource monitoring. 11:06:27 INFO - [mozharness: 2017-02-09 19:06:27.181000Z] Finished create-virtualenv step (success) 11:06:27 INFO - [mozharness: 2017-02-09 19:06:27.181000Z] Running install step. 11:06:27 INFO - Running pre-action listener: _resource_record_pre_action 11:06:27 INFO - Running main action method: install 11:06:27 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 11:06:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 11:06:27 INFO - Reading from file tmpfile_stdout 11:06:27 INFO - Using _rmtree_windows ... 11:06:27 INFO - Using _rmtree_windows ... 11:06:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:06:27 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 11:06:27 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 11:06:27 INFO - Reading from file tmpfile_stdout 11:06:27 INFO - Output received: 11:06:27 INFO - Usage: mozinstall-script.py [options] installer 11:06:27 INFO - Options: 11:06:27 INFO - -h, --help show this help message and exit 11:06:27 INFO - -d DEST, --destination=DEST 11:06:27 INFO - Directory to install application into. [default: 11:06:27 INFO - "C:\slave\test"] 11:06:27 INFO - --app=APP Application being installed. [default: firefox] 11:06:27 INFO - Using _rmtree_windows ... 11:06:27 INFO - Using _rmtree_windows ... 11:06:27 INFO - mkdir: C:\slave\test\build\application 11:06:27 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 11:06:27 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0.en-US.win32.zip --destination C:\slave\test\build\application 11:06:28 INFO - Reading from file tmpfile_stdout 11:06:28 INFO - Output received: 11:06:28 INFO - C:\slave\test\build\application\firefox\firefox.exe 11:06:28 INFO - Using _rmtree_windows ... 11:06:28 INFO - Using _rmtree_windows ... 11:06:28 INFO - Running post-action listener: _resource_record_post_action 11:06:28 INFO - [mozharness: 2017-02-09 19:06:28.990000Z] Finished install step (success) 11:06:28 INFO - [mozharness: 2017-02-09 19:06:28.990000Z] Running run-tests step. 11:06:28 INFO - Running pre-action listener: _resource_record_pre_action 11:06:28 INFO - Running main action method: run_tests 11:06:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 11:06:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 11:06:28 INFO - Python 2.7.3 11:06:28 INFO - Return code: 0 11:06:28 INFO - grabbing minidump binary from tooltool 11:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:28 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 0x0188EE90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0128A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01EE5520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 11:06:28 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 11:06:28 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 11:06:28 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 11:06:29 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 11:06:29 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpswprs6 11:06:29 INFO - INFO - File integrity verified, renaming tmpswprs6 to win32-minidump_stackwalk.exe 11:06:29 INFO - Return code: 0 11:06:29 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 11:06:29 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 11:06:29 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 11:06:29 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 11:06:29 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 11:06:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-116', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build 11:06:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-116 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log 11:06:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 11:06:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 11:06:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 11:06:29 INFO - 'COMPUTERNAME': 'T-W732-IX-116', 11:06:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 11:06:29 INFO - 'CYGWINBASE': 'C:\\cygwin', 11:06:29 INFO - 'DCLOCATION': 'SCL3', 11:06:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 11:06:29 INFO - 'FP_NO_HOST_CHECK': 'NO', 11:06:29 INFO - 'HOMEDRIVE': 'C:', 11:06:29 INFO - 'HOMEPATH': '\\Users\\cltbld', 11:06:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 11:06:29 INFO - 'KTS_VERSION': '1.19c', 11:06:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 11:06:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-116', 11:06:29 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 11:06:29 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 11:06:29 INFO - 'MONDIR': 'C:\\Monitor_config\\', 11:06:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 11:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:29 INFO - 'MOZ_NO_REMOTE': '1', 11:06:29 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 11:06:29 INFO - 'NO_EM_RESTART': '1', 11:06:29 INFO - 'NUMBER_OF_PROCESSORS': '8', 11:06:29 INFO - 'OS': 'Windows_NT', 11:06:29 INFO - 'OURDRIVE': 'C:', 11:06: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;', 11:06:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 11:06:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 11:06:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 11:06:29 INFO - 'PROCESSOR_LEVEL': '6', 11:06:29 INFO - 'PROCESSOR_REVISION': '1e05', 11:06:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 11:06:29 INFO - 'PROGRAMFILES': 'C:\\Program Files', 11:06:29 INFO - 'PROMPT': '$P$G', 11:06:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 11:06:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 11:06:29 INFO - 'PUBLIC': 'C:\\Users\\Public', 11:06:29 INFO - 'PWD': 'C:\\slave\\test', 11:06:29 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 11:06:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 11:06:29 INFO - 'SLAVEDIR': 'C:\\slave\\', 11:06:29 INFO - 'SYSTEMDRIVE': 'C:', 11:06:29 INFO - 'SYSTEMROOT': 'C:\\windows', 11:06:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:29 INFO - 'TEST1': 'testie', 11:06:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 11:06:29 INFO - 'USERDOMAIN': 'T-W732-IX-116', 11:06:29 INFO - 'USERNAME': 'cltbld', 11:06:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 11:06:29 INFO - 'WINDIR': 'C:\\windows', 11:06:29 INFO - 'WINDOWS_TRACING_FLAGS': '3', 11:06:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 11:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 11:06:29 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-116', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600 11:06:30 INFO - mozversion application_buildid: 20170209092520 11:06:30 INFO - mozversion application_changeset: 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 11:06:30 INFO - mozversion application_display_name: Firefox 11:06:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:06:30 INFO - mozversion application_name: Firefox 11:06:30 INFO - mozversion application_remotingname: firefox 11:06:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:06:30 INFO - mozversion application_vendor: Mozilla 11:06:30 INFO - mozversion application_version: 52.0 11:06:30 INFO - mozversion platform_buildid: 20170209092520 11:06:30 INFO - mozversion platform_changeset: 15d8f9d49bfe2318622bc9c92b6b97cbd56b3a91 11:06:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:06:30 INFO - mozversion platform_version: 52.0 11:06:30 INFO - using testdate: 1486667190 11:06:30 INFO - actual date: 1486667190 11:06:30 INFO - starting webserver on 'localhost:49894' 11:06:30 INFO - SUITE-START | Running 2 tests 11:06:30 INFO - TEST-START | dromaeo_css 11:06:30 INFO - operating with platform_type : w7_ 11:06:30 INFO - Initialising browser for dromaeo_css test... 11:06:30 INFO - TEST-INFO | started process 1720 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpa1xbyo\profile http://localhost:49894/getInfo.html) 11:06:38 INFO - PROCESS | 1720 | __metrics Screen width/height:1600/1200 11:06:38 INFO - PROCESS | 1720 | colorDepth:24 11:06:38 INFO - PROCESS | 1720 | Browser inner width/height: 1010/674 11:06:38 INFO - PROCESS | 1720 | __metrics 11:06:38 ERROR - PROCESS | 1720 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 11:06:39 INFO - TEST-INFO | 1720: exit 0 11:06:39 INFO - Browser initialized. 11:06:39 INFO - Running cycle 1/1 for dromaeo_css test... 11:06:39 INFO - TEST-INFO | started process 3048 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpa1xbyo\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:40 INFO - PROCESS | 3048 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 11:06:51 INFO - PROCESS | 3048 | RSS: Main: 113643520 11:06:51 INFO - PROCESS | 3048 | 11:09:49 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49894/tests/dromaeo/cssquery-ext.html) 11:09:50 INFO - PROCESS | 3048 | RSS: Main: 99614720 11:09:50 INFO - PROCESS | 3048 | 11:12:52 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-ext.html (next: http://localhost:49894/tests/dromaeo/cssquery-jquery.html) 11:12:52 INFO - PROCESS | 3048 | RSS: Main: 80920576 11:12:52 INFO - PROCESS | 3048 | 11:15:54 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49894/tests/dromaeo/cssquery-mootools.html) 11:15:55 INFO - PROCESS | 3048 | RSS: Main: 73043968 11:15:55 INFO - PROCESS | 3048 | 11:18:56 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49894/tests/dromaeo/cssquery-prototype.html) 11:18:57 INFO - PROCESS | 3048 | RSS: Main: 71081984 11:18:57 INFO - PROCESS | 3048 | 11:21:59 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49894/tests/dromaeo/cssquery-yui.html) 11:22:00 INFO - PROCESS | 3048 | RSS: Main: 74354688 11:22:00 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | Cycle 1(1): loaded http://localhost:49894/tests/dromaeo/cssquery-yui.html (next: http://localhost:49894/tests/dromaeo/cssquery-dojo.html) 11:25:02 INFO - PROCESS | 3048 | RSS: Main: 70795264 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | __start_tp_report 11:25:02 INFO - PROCESS | 3048 | _x_x_mozilla_page_load 11:25:02 INFO - PROCESS | 3048 | _x_x_mozilla_page_load_details 11:25:02 INFO - PROCESS | 3048 | |i|pagename|runs| 11:25:02 INFO - PROCESS | 3048 | |0;dojo.html;2882.985585072075;2888.7977841551087;2906.796524243304;2910.37426953205;2910.9854450727744;5031.245313203021;5079.060373830844;5080.5681517071;5084.38987321521;5129.563987061104;5040.36995375578;5170.586353091753;5180.300659410978;5202.609804264676;5238.816641417551;4349.325854492558;4454.13144436835;4553.2942393929;4569.6801223914335;4581.931271030929;4432.423784907957;4457.59881610655;4472.753998530079;4490.663200259977;4567.794449249792;4381.890452738691;4416.227160246944;4436.068425630621;4469.843555475559;4488.192125417424;4432.445944256968;4507.4591049074;4512.909741805179;4530.909381812378;4540.046590216059;4402.965303153756;4416.381706561084;4456.442944631921;4457.28683410656;4485.641148708096;4917.311576379309;4943.456219815817;4966.130927087781;5008.073506401291;5063.11395505785;5234.973825130888;5238.947610523922;5266.8683282917855;5303.204519322094;5381.569474442035;5315.468453154654;5342.67943923362;5390.676559406448;5412.539934105605;5416.51251387377;4896.167651499253;4896.583790377857;4912.656114072016;4942.481039490874;4981.37732783402;5357.5446087082255;5426.267453893753;5489.890202195934;5505.752241149158;5523.530499907472;5119.078565858182;5192.506711862367;5229.555487783581;5266.34170728659;5287;5179.507946745053;5285.233641122016;5307.41618421981;5329.094054010828;5348.251244825728;5183.377994640668;5289.867753306198;5332.386775520865;5392.191171324254;5393.78424863009;5303.708296043677;5308.203769434615;5314.654547454403;5371.435999220103;5389.676619402849;3424.9486257706153;3454.29187016661;3521.506989021539;3567.500549923013;3568.3398571264397;3197.8401079946375;3202.0233828682008;3205.2627895584146;3230.466972949484;3249.333886553251;4921.311016457699;4973;5007.4241462231585;5028.698278103326;5045.5459008689395;5215.139501982207;5267.2889159962915;5278.20826875964;5341.5192632662465;5348.812791552355;4913.213885778258;4930.383702037246;4990.3762029746285;5046.621503387188;5063.645544811828;4129.256733787887;4416.205083085011;4499.730016199039;4504.121696269196;4506.752128632891;5493.560515158778;5508.531774799107;5637.577181711388;5645.463680950304;5669.461401166883;5308.601854860824;5320.361556613231;5325.467453254643;5378.112611419072;5433.103537916358;5517.200005999028;5545.7781688732;5602.411746766694;5618.943810561842;5639.7180140993605;5141.845744627667;5247.790088396421;5311.628186027096;5315.362156541085;5334.039872822774;5272.657277276965;5329.280547126244;5371.946280537301;5372.140457526777;5400.486953739389;5169.250458683545;5196.766145523385;5240.71176085319;5260.894782104413;5262.973720124539;5201.973990130025;5215.739213039257;5249.6850188987255;5313.521783039545;5320.201969704575;5036.6726162799305;5082.313887625123;5128.743562821918;5139.331886854685;5166.638335316492;5244.737763111905;5299.549538289134;5301.814436494704;5305.973470132624;5322.8935421292135;5163.148080566664;5185.22221666753;5190.079384645638;5250.97374513125;5268.841934741964 11:25:02 INFO - PROCESS | 3048 | |1;ext.html;510.7880229704673;529.1929807044257;560.8065217499961;570.1220121013639;572.7222297185864;27468.313292167706;38930.99999999993;39487.21025579487;39801.80099099508;40192.19615607686;39825.60174398255;39843.20313593726;40174.79475615736;40217.195656086864;40309;8105.351571874251;8154.3884208684585;8228.547429891354;8235.547044912528;8244.258016778489;8075.596220188996;8177.5502347370875;8177.5911204439835;8181.345492360597;8202.179782021809;8084.312833409167;8137.226963438494;8175.63209655567;8193.754187374388;8199.508029518218;8085.191480851897;8133.715319963802;8138.470999385051;8148.348132149414;8156.836863262702;8053.677852885878;8106.47307924983;8116.715914943008;8122.7157049502985;8127.3904457165945;37912;38040;38316.23367532634;38442.42336364957;38492.42261366082;2805.326721586825;2848.9002884899137;3180.4593219152566;3349.6147942986627;3404.914877128067;28928.855355723295;29296.267593310124;29345.85327073351;29662;30065.549016764766;3265.7224135948472;3379.729621630264;3395.3888300105973;3420.110771199481;3434.690877820983;309917;310108;310346;310404;311176;51681;51788.223176652384;51843;52010.739946300026;52059.219111713355;27333.180004599893;27727.306817329325;27732.30669233245;28439.573406398507;53882;12263;12333.93833030829;12351.69120771988;12370.938145309216;12390.31853248062;9975.451350175665;10072.798544029078;10109.545070471846;10113.64602238918;10128.645497407702;17373.13134343278;17406.39077632302;17410.738838917427;17418.912905435645;17432.12839358027;6646.86706265872;6664.8000559983275;6668.933310666928;6701.29636388182;6738.696758645872;722.9372821951828;777.3470284960661;779.283059585183;802.835418739157;821.482466046387;27075.13602981255;27262.863685681445;27305.31736706598;27345.316367090585;27667.308317292227;848.9363297752569;852.3564708644933;882.6116508736136;907.8683590879417;910.349100393222;14123.717525649636;14319.21244331551;14534.200618965848;14588.85411145875;14612.488562900247;470024;470328;472920;473614;474187;48166.27750583744;48424.51575484271;48563;48620.27069593959;48726.02547949092;1066.4774260612244;1081.9080378167787;1084.2843723142687;1092.137211602825;1094.2067001424064;26215.73784262133;26758.732412676403;26846.865765671046;27017.459650806482;27129;42359.36460953027;42531.3620295702;42576.361354580324;42690.146197075264;42764.78617606892;375325;398337;401589;401841;402518;21961.890190548944;22187.445313866636;22747.88626056859;22802.771972280065;23403;40657.79671101626;41362.172756545304;41589;41642.79178604088;41761.791191045064;35367;35754;35875;35951.820240898625;36027.45958810672;35251.47122793212;35270.64729352776;35551.11122221965;35581;36458.453123203704;27942.441151176456;28490.57264140998;33660.66339336575;33665;33738.493922591675;31281.687183127877;32315.19212019718;33046.17384565405;34049;34624.65375346315;24296.51406971886;24761;24889.62665559982;24917;25123.371915702253 11:25:02 INFO - PROCESS | 3048 | |2;jquery.html;306.4698072334862;306.96623371429143;307.77993734479844;312.24281118288144;315.0941044497071;12390.690232744186;15522.22388880557;16894.32422703092;17330.91334543327;18034.278628854845;16905.7464138038;16976.236069376875;17311.82688173118;17440.651186976254;17444;5237.685738855662;5367.1680889462;5416.133418653017;5418.322709661293;5424.701641409721;5118.923216151761;5367.624266301361;5405.729713514328;5412.9458705413035;5437.13005919053;5324.7071411072375;5353.1167357386175;5359.1425371940495;5370.301860758096;5378.596605254622;5113.156329205676;5162.277281180619;5197.428282888879;5204.843854684365;5361.329833770778;4528.04910968697;4535.4103966484145;4541.227991241497;4549.203889319389;4554.157480866051;21836.344909652737;21944.45138871525;22451.663225051638;22524.54950900973;22568.66147007796;1957.765068191826;2005.418428655688;2018.8586798924082;2024.038581673708;2024.210557882427;13541.119827211298;14118.576442706633;14229.359678814479;14385.64035899111;14804.259787010606;1639.737641977278;1671.849533541975;1693.229580540851;1704.8806583539033;1735.8958462492292;85321;85677.57161214216;86802;86922.13077869204;87000;8993.640254389751;9093.727188184364;9213.078692130868;9225.815483690423;9273.211776998896;11395.829062563902;11534.365609891536;11800.232984855957;11812.35032073227;27654;8439.451435656663;8686.696995450606;8787.165219304155;8909.064548222474;9080.772980675536;7254.492185546961;7304.8173795654475;7334.743283985035;7359.755274641286;7539.773806785805;8080.797980050545;8244.051934027544;8246.876296855551;8287.295579875778;8370.790730231793;3288.1122097033667;3344.4648856182894;3345.9498107528407;3348.5646865907765;3406.403879321109;750.6284389227368;790.6165509727778;791.2759824760395;883.7039591736711;912.5984566790723;17567.20947557363;17749.911250443663;17780.288788448575;18162.001089939917;18195.18121684501;755.8828381600861;800.2997377294867;838.1450920061503;847.9067302596709;877.4735158904606;9276.90723092774;9779.41323520591;9793.559289831976;9821.36161149523;9948;431009;431713.36573268985;438034;438299;440057;90208.5489572548;90391.09608903958;97436;101215;101803;791.2562191539934;812.2649002652608;818.3698552114838;820.7619790260877;836.7364280251701;16046.197690115214;16127.838721613103;16310.429134980219;16571.751423728412;16671.166441677626;41657.37513937233;41923.37114943339;42107.578924210364;42654.36018459788;42661.1467770649;57767.71116144393;58085.70957145187;61072.083918742144;61566.69216653888;62362.68818655878;6751.93248067513;6898.413634841095;6918.481113916577;7050.330218629222;7096.680649370894;8485.490870547786;10887.346759194474;12085.214461060003;12223.877761222275;12240.08199385032;10045.146162576262;10055.245856560641;10172.593096276067;10182;10294.536745846304;9114.13415725505;10178.898211018091;10385;10391.636292729718;10641.574337026432;10237.897621023694;10363.326383785332;10441.895581044093;10583.576656933636;10626.309289896131;6909.412699920563;7045.436365090883;7138.072050633385;7158.785236442915;7374;6297.307296197323;6640.701168447528;7233.31283528064;8788;10425.53085111187 11:25:02 INFO - PROCESS | 3048 | |3;mootools.html;1097.182598963772;1102.2284400919355;1209.1656756837783;1353.7698591239493;1422.6514503946535;6548.3779040991085;6695.129633147684;6886;6905.827354316145;6912.654367281641;6910.378065974061;6987.895181572268;6993.825154371143;7026.6486675666265;7045.647717614124;1900.743399641048;1927.6144771045776;1927.787943326233;1930.1217945834612;1931.642646110474;1816.7002444596692;1824.0788401857044;1862.6554087493857;1869.3176990398474;1886.1606585069649;1853.694140466828;1856.368834596237;1874.0442374389092;1875.3811242290012;1882.8023057578894;1818.109126528005;1818.7180986947044;1822.7265910113456;1829.8444632206279;1829.9085045747793;1835.5686413692772;1841.5027942455463;1851.0837135617821;1857.071464267866;1863.8974856382893;6567.310432404624;6661.833454163636;6680.899786503206;6779.525433219676;6788.456923446161;3931.272714547818;4007.438958545806;4014.3577027675433;4016.357382818764;4026.4966879140106;2674.745899139579;2750.339918419585;2789.5955086512545;2791.0649932272713;2797.5943488194303;807.2734538914965;828;834.7871292820292;837.6398148796015;861.5606040919114;8516.70191543293;8543.487390756574;8570.057293697688;8614.138586141398;8618.870716939251;3161.7944833585743;3242.951355729666;3270.9018729438153;3278.885239016623;3289.7203737682084;6977.441804655616;6983.441324694012;6993.0209770632155;6994.930050699428;6997.860042799116;3820.8853734388013;3881.514810648669;3894.7857867817543;3899.415087736862;3935.6851451884354;3323.4350160472777;3703.703703703697;3743.8689645862264;3750.924981500409;3760.4735337052834;2699.635549200872;2810.747032767061;2827.943441131166;2856.3287627407544;2874.755645770091;869.9260562852102;884.3323290915317;889.9555022248861;889.9777505562413;892.5448021509077;586.9735861886226;689.6207086102623;696.8188271049563;699.7445932234698;708.4190963410057;4099.590040995877;4100.220958017967;4190.895227619273;4316.244657185043;4355.15074560464;699.2867275379085;718.5904034700275;722.5953466058919;762.0702742653954;764.8355603545265;2631.1843328568207;2676.4547682132725;2696.12375977808;2696.298962269824;2704.810663253593;7896.407769417202;8142.552159631277;8148.837023259502;8152.429329946846;8166.224208700164;7735.419843511759;7835.725749598851;7957.681692732226;8052.275295223458;8108.148644392372;6110.7861224856915;6320.399562041599;6417.807465776034;6475.4819614430735;6486.059521369375;4135.421041054315;4149.33610622299;4201.7268877522865;4271.380649805823;4274.358846172974;7346.595937223504;7368.115826100902;7607.31534161906;7632.465727399029;7642.579658118857;7243.601601911945;7406;7414.518056326321;7579.962100189684;7642.1593624702355;5870.735816888336;5914.733836977261;5975.701214939322;5980.132880732356;5984.1323008164445;6245.625262484084;6379.138816259746;6462.741490340334;6487.3188315226225;6554.770583029571;5953.732082056405;6003.909941350796;6046.78836240747;6061.545384096298;6090.056041313604;5552.389237183826;5634.971825140848;5650.971745141413;5842;5899.085641725539;5191.974040129775;5250.553702935294;5291.629585929102;5294.682319060868;5298.28473156119;3565.8573657054467;3593.5025157515274;3629.074585980664;3677.6138505456543;3697.556293244828;3559.4482855157494;3575.964240357563;3576;3592.8562857486454;3632.891013269606 11:25:02 INFO - PROCESS | 3048 | |4;prototype.html;254.11694362091723;254.4249995011274;255.64593038641482;257.58785942492017;262.41874248539494;5302.541801004726;5414.648047876895;5495.120780675088;5502.5597952163835;5508.6588200014985;5299.178627312763;5396.298481197448;5403.378611459673;5406.972965135179;5409.729513524318;3929.8821035368983;3935.5474120476083;3953.6639385652256;3954.9802250988705;3961.5642279349395;3907.2771537265553;3915.0799562103025;3922.9607703923025;3935.134270460494;3938.6061393860527;3826.9043273918237;3884.6309600587897;3905.9609403905883;3914.17802261524;3924.725269231155;3859.9807000965097;3872.9930218143486;3898.5906479819496;3904.12157264616;3905.258000979819;3368.1579605098727;3370.848311825949;3371.4437117875523;3376;3376.40912840252;5255.211718242256;5260.9736951315;5272.497338258616;5285.735713214324;5293.57651387888;4551.908961820745;4570.702904311209;4576.009435895397;4577.862664120082;4579.0767877491535;5348.946510534884;5591.384947655754;5594.86012849682;5609.971950140223;5631.63394379364;3563.7861728296384;3586.569611646593;3597.3092646456125;3597.80212088335;3606.098475381155;5874.882502349972;5904.2029326040865;5907.261592300963;5911.970440147815;5939.584229103922;3468.115630514253;3557.839897204632;3684.6868016218386;3686.4838922550866;3697.9307175044646;3644.1254099016046;3708.8887333380044;3720.441933709911;3729.2355067211156;3744.3634582121103;4449.421575195204;4459.264221403497;4461.910761784746;4462.776861156929;4465.24090904547;5687.914681279784;5699.914501282484;5701.629394089371;5705.315362156568;5717.942820571824;1251.1117106854235;1270.8054428836863;1275.7894947333587;1275.9090977214535;1280.9871901281053;774.9031371078615;785.7446329942792;801.7354273089867;810.720301495983;810.8499927513429;5122.692638441706;5136.8202112925865;5168.431472538018;5183.81856635016;5219.556337711261;5619.049998750688;5640.943590564124;5648.774049037993;5692.288463942007;5695.971520142456;5180.197069454241;5193.1171700810955;5213.947860521422;5219.4780521947505;5242.475752424803;2903.6515618125536;2981.344104297051;2992.371601963612;2999.1902186409548;3036.407900459389;5849.766009359663;5938.703064846741;5939.732712027969;5947.791827286111;5974.61165024272;5767.174084698468;5770.711464426846;5786.652800831964;5788.82633520995;5816.302043754777;5855.882882342329;5857.824265272048;6154.3845615438095;6230.6573138478725;6237.906431403532;5902.852428689317;5930.555208359304;5933.228680271537;5933.584649074696;5939.910901336397;5816.040353341651;5828.417158284307;5833.562482813891;5849;5879.529637629065;5936.406359364029;6178.69106544662;6214.782482613267;6221.160143380585;6231.594946328474;3622.583402908699;3630.1287690953454;3638.6361363863402;3643.0892276930767;3649.525561676965;5901.557383196192;5901.940980590139;5909.615874968285;5919.6152250103605;5920.171176035273;5606.215129881738;5723.427657234243;5751.079827227623;5756.683382414008;5763.394843541536;5584.776608935597;5634.126710359901;5669.149627555899;5688.943110568841;5701.315842098975;5564.693941833238;5587.776488940397;5601.971990140023;5614.466625670555;5622.32532096151;5547.084731019337;5712.828615141552;5729.656220626775;5746.942530574641;5758.568107391879;5632.521690456186;5657.32112146545;5661.490465858093;5662.235598194191;5687.1753595729215 11:25:02 INFO - PROCESS | 3048 | |5;yui.html;593.5311104227658;656.7208936202113;669.5480550628326;675.7297081167533;683.9179298484181;13061.346932653378;13094;13254.469821207153;13817.24005179716;14128.788068178985;12713.555025574107;12763.744725105493;12943.546975855847;13018.73962520749;13038.282894440827;602.3614968133782;610.8839320529107;611.743067911476;612.4304396910871;612.8835521250948;593.5370411079365;604.2446941323345;608.6135304081918;609.1076572820816;610.042233693102;634.1724050114599;635.0918186992599;635.5582869905418;635.7584118035163;636.6243916089506;624.0389799708444;628.9937100628981;630.3161070238797;630.4011189370071;633.0599060395332;462.4127358255031;463.4508107892125;464.40324183424474;464.42411409851877;464.7304563353247;13397.062205645609;13425.66435839112;13473.258970756604;13479.258640774753;13499.460021599125;11431.885681143165;11863.28820270778;11969;12622.242665440011;12725.809112863315;10912.836307455394;11021.83467247992;11109.555617775279;11365.886341136647;11531.48108335127;3337.9790595132604;3376.57792775903;3417.4691637292744;3446.5002574626537;3565.714742820568;53801.964852635814;62274.37725622731;64977.350226497605;65435.3456465434;65517.99999999952;9853.901460985442;9864.161546268504;9897.257705672104;9975.501224938724;10098.545565449573;15938.681226375407;17124.058176800394;17137.714671399695;17310.307587696603;17338.3064677414;6776.93223067763;6860.416864566568;6871.587704737732;6895.103636527319;7012.439004879598;3753.6997040236715;3810.466534685146;3813.389857622767;3885.300645883768;3904.121572646132;2592;2701.3516755978817;2727.5772255300462;2739.0960982876;2742.698303186648;479.0371353579261;480.7668280883769;484.2349088440256;485.3665965914515;492.9605631549539;550.8099705601561;553.3608681972329;646.3536463536464;677.1298880937971;681.3261029338732;8607.827843443096;8992.325575581726;9011.684591039282;9077.546122693839;9313;616.3866952382318;637.4804534304527;667.302668711198;677.4715721736954;695.655650453029;4144.233316836395;4265.3815196796295;4483.32750087483;4502.482214545304;4504.90300840445;63095;66244.33755662479;69639.65180174066;70851;73625.63187184029;49655.503444965085;55279;65378;68396.6580167106;69669.65165174141;54737;55817.441825582035;58881.70559147177;63375;64302.99999999906;8641.481511109354;9075.546222688707;9287.582058807235;9290.303227257848;9434.669786557437;38668.419973699856;39192.6080739189;39807.20385592214;40464.59535404608;40717.59282407256;52435.21347179719;52878.471215287354;53075;53816;53871;11894.821577676516;11986.160968732454;12013.939930300643;12072.75854482923;12164.513419463123;14311.713765724835;14335.56993290203;14522.564323070324;14577.416903323749;14731.116133032052;12325.198862073936;12621.368931553201;12983.221006739626;13045.739085218433;13215.537456189371;13080.607581772561;13346.10950577186;13379.866201337862;13392.464301428223;13554.983376246624;12989.675258118623;12989.740205196033;13285.40215690277;13735.862641373858;13743.587692369245;10424.04665058123;10619.522121504397;11363.715907102389;11457.026152777107;11707.356095414494;11017.724556885822;11117.27737697047;11170.664880053611;11300.717482062686;11324.830127547928 11:25:02 INFO - PROCESS | 3048 | __end_tp_report 11:25:02 INFO - PROCESS | 3048 | __start_cc_report 11:25:02 INFO - PROCESS | 3048 | _x_x_mozilla_cycle_collect,279 11:25:02 INFO - PROCESS | 3048 | __end_cc_report 11:25:02 INFO - PROCESS | 3048 | __startTimestamp1486668302834__endTimestamp 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | ------- Summary: start ------- 11:25:02 INFO - PROCESS | 3048 | Number of tests: 6 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#0] dojo.html Cycles:165 Average:4920.41 Median:5180.30 stddev:644.46 (12.4%) stddev-sans-first:626.31 11:25:02 INFO - PROCESS | 3048 | Values: 2883.0 2888.8 2906.8 2910.4 2911.0 5031.2 5079.1 5080.6 5084.4 5129.6 5040.4 5170.6 5180.3 5202.6 5238.8 4349.3 4454.1 4553.3 4569.7 4581.9 4432.4 4457.6 4472.8 4490.7 4567.8 4381.9 4416.2 4436.1 4469.8 4488.2 4432.4 4507.5 4512.9 4530.9 4540.0 4403.0 4416.4 4456.4 4457.3 4485.6 4917.3 4943.5 4966.1 5008.1 5063.1 5235.0 5238.9 5266.9 5303.2 5381.6 5315.5 5342.7 5390.7 5412.5 5416.5 4896.2 4896.6 4912.7 4942.5 4981.4 5357.5 5426.3 5489.9 5505.8 5523.5 5119.1 5192.5 5229.6 5266.3 5287.0 5179.5 5285.2 5307.4 5329.1 5348.3 5183.4 5289.9 5332.4 5392.2 5393.8 5303.7 5308.2 5314.7 5371.4 5389.7 3424.9 3454.3 3521.5 3567.5 3568.3 3197.8 3202.0 3205.3 3230.5 3249.3 4921.3 4973.0 5007.4 5028.7 5045.5 5215.1 5267.3 5278.2 5341.5 5348.8 4913.2 4930.4 4990.4 5046.6 5063.6 4129.3 4416.2 4499.7 4504.1 4506.8 5493.6 5508.5 5637.6 5645.5 5669.5 5308.6 5320.4 5325.5 5378.1 5433.1 5517.2 5545.8 5602.4 5618.9 5639.7 5141.8 5247.8 5311.6 5315.4 5334.0 5272.7 5329.3 5371.9 5372.1 5400.5 5169.3 5196.8 5240.7 5260.9 5263.0 5202.0 5215.7 5249.7 5313.5 5320.2 5036.7 5082.3 5128.7 5139.3 5166.6 5244.7 5299.5 5301.8 5306.0 5322.9 5163.1 5185.2 5190.1 5251.0 5268.8 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#1] ext.html Cycles:180 Average:52496.67 Median:37976.00 stddev:105768.99 (278.5%) stddev-sans-first:105993.69 11:25:02 INFO - PROCESS | 3048 | Values: 510.8 529.2 560.8 570.1 572.7 27468.3 38931.0 39487.2 39801.8 40192.2 39825.6 39843.2 40174.8 40217.2 40309.0 8105.4 8154.4 8228.5 8235.5 8244.3 8075.6 8177.6 8177.6 8181.3 8202.2 8084.3 8137.2 8175.6 8193.8 8199.5 8085.2 8133.7 8138.5 8148.3 8156.8 8053.7 8106.5 8116.7 8122.7 8127.4 37912.0 38040.0 38316.2 38442.4 38492.4 2805.3 2848.9 3180.5 3349.6 3404.9 28928.9 29296.3 29345.9 29662.0 30065.5 3265.7 3379.7 3395.4 3420.1 3434.7 309917.0 310108.0 310346.0 310404.0 311176.0 51681.0 51788.2 51843.0 52010.7 52059.2 27333.2 27727.3 27732.3 28439.6 53882.0 12263.0 12333.9 12351.7 12370.9 12390.3 9975.5 10072.8 10109.5 10113.6 10128.6 17373.1 17406.4 17410.7 17418.9 17432.1 6646.9 6664.8 6668.9 6701.3 6738.7 722.9 777.3 779.3 802.8 821.5 27075.1 27262.9 27305.3 27345.3 27667.3 848.9 852.4 882.6 907.9 910.3 14123.7 14319.2 14534.2 14588.9 14612.5 470024.0 470328.0 472920.0 473614.0 474187.0 48166.3 48424.5 48563.0 48620.3 48726.0 1066.5 1081.9 1084.3 1092.1 1094.2 26215.7 26758.7 26846.9 27017.5 27129.0 42359.4 42531.4 42576.4 42690.1 42764.8 375325.0 398337.0 401589.0 401841.0 402518.0 21961.9 22187.4 22747.9 22802.8 23403.0 40657.8 41362.2 41589.0 41642.8 41761.8 35367.0 35754.0 35875.0 35951.8 36027.5 35251.5 35270.6 35551.1 35581.0 36458.5 27942.4 28490.6 33660.7 33665.0 33738.5 31281.7 32315.2 33046.2 34049.0 34624.7 24296.5 24761.0 24889.6 24917.0 25123.4 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#2] jquery.html Cycles:180 Average:27287.30 Median:4833.66 stddev:72569.53 (1501.3%) stddev-sans-first:72744.83 11:25:02 INFO - PROCESS | 3048 | Values: 306.5 307.0 307.8 312.2 315.1 12390.7 15522.2 16894.3 17330.9 18034.3 16905.7 16976.2 17311.8 17440.7 17444.0 5237.7 5367.2 5416.1 5418.3 5424.7 5118.9 5367.6 5405.7 5412.9 5437.1 5324.7 5353.1 5359.1 5370.3 5378.6 5113.2 5162.3 5197.4 5204.8 5361.3 4528.0 4535.4 4541.2 4549.2 4554.2 21836.3 21944.5 22451.7 22524.5 22568.7 1957.8 2005.4 2018.9 2024.0 2024.2 13541.1 14118.6 14229.4 14385.6 14804.3 1639.7 1671.8 1693.2 1704.9 1735.9 85321.0 85677.6 86802.0 86922.1 87000.0 8993.6 9093.7 9213.1 9225.8 9273.2 11395.8 11534.4 11800.2 11812.4 27654.0 8439.5 8686.7 8787.2 8909.1 9080.8 7254.5 7304.8 7334.7 7359.8 7539.8 8080.8 8244.1 8246.9 8287.3 8370.8 3288.1 3344.5 3345.9 3348.6 3406.4 750.6 790.6 791.3 883.7 912.6 17567.2 17749.9 17780.3 18162.0 18195.2 755.9 800.3 838.1 847.9 877.5 9276.9 9779.4 9793.6 9821.4 9948.0 431009.0 431713.4 438034.0 438299.0 440057.0 90208.5 90391.1 97436.0 101215.0 101803.0 791.3 812.3 818.4 820.8 836.7 16046.2 16127.8 16310.4 16571.8 16671.2 41657.4 41923.4 42107.6 42654.4 42661.1 57767.7 58085.7 61072.1 61566.7 62362.7 6751.9 6898.4 6918.5 7050.3 7096.7 8485.5 10887.3 12085.2 12223.9 12240.1 10045.1 10055.2 10172.6 10182.0 10294.5 9114.1 10178.9 10385.0 10391.6 10641.6 10237.9 10363.3 10441.9 10583.6 10626.3 6909.4 7045.4 7138.1 7158.8 7374.0 6297.3 6640.7 7233.3 8788.0 10425.5 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#3] mootools.html Cycles:180 Average:4310.29 Median:5593.68 stddev:2415.48 (43.2%) stddev-sans-first:2410.19 11:25:02 INFO - PROCESS | 3048 | Values: 1097.2 1102.2 1209.2 1353.8 1422.7 6548.4 6695.1 6886.0 6905.8 6912.7 6910.4 6987.9 6993.8 7026.6 7045.6 1900.7 1927.6 1927.8 1930.1 1931.6 1816.7 1824.1 1862.7 1869.3 1886.2 1853.7 1856.4 1874.0 1875.4 1882.8 1818.1 1818.7 1822.7 1829.8 1829.9 1835.6 1841.5 1851.1 1857.1 1863.9 6567.3 6661.8 6680.9 6779.5 6788.5 3931.3 4007.4 4014.4 4016.4 4026.5 2674.7 2750.3 2789.6 2791.1 2797.6 807.3 828.0 834.8 837.6 861.6 8516.7 8543.5 8570.1 8614.1 8618.9 3161.8 3243.0 3270.9 3278.9 3289.7 6977.4 6983.4 6993.0 6994.9 6997.9 3820.9 3881.5 3894.8 3899.4 3935.7 3323.4 3703.7 3743.9 3750.9 3760.5 2699.6 2810.7 2827.9 2856.3 2874.8 869.9 884.3 890.0 890.0 892.5 587.0 689.6 696.8 699.7 708.4 4099.6 4100.2 4190.9 4316.2 4355.2 699.3 718.6 722.6 762.1 764.8 2631.2 2676.5 2696.1 2696.3 2704.8 7896.4 8142.6 8148.8 8152.4 8166.2 7735.4 7835.7 7957.7 8052.3 8108.1 6110.8 6320.4 6417.8 6475.5 6486.1 4135.4 4149.3 4201.7 4271.4 4274.4 7346.6 7368.1 7607.3 7632.5 7642.6 7243.6 7406.0 7414.5 7580.0 7642.2 5870.7 5914.7 5975.7 5980.1 5984.1 6245.6 6379.1 6462.7 6487.3 6554.8 5953.7 6003.9 6046.8 6061.5 6090.1 5552.4 5635.0 5651.0 5842.0 5899.1 5192.0 5250.6 5291.6 5294.7 5298.3 3565.9 3593.5 3629.1 3677.6 3697.6 3559.4 3576.0 3576.0 3592.9 3632.9 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#4] prototype.html Cycles:180 Average:4643.67 Median:5408.35 stddev:1495.60 (27.7%) stddev-sans-first:1463.06 11:25:02 INFO - PROCESS | 3048 | Values: 254.1 254.4 255.6 257.6 262.4 5302.5 5414.6 5495.1 5502.6 5508.7 5299.2 5396.3 5403.4 5407.0 5409.7 3929.9 3935.5 3953.7 3955.0 3961.6 3907.3 3915.1 3923.0 3935.1 3938.6 3826.9 3884.6 3906.0 3914.2 3924.7 3860.0 3873.0 3898.6 3904.1 3905.3 3368.2 3370.8 3371.4 3376.0 3376.4 5255.2 5261.0 5272.5 5285.7 5293.6 4551.9 4570.7 4576.0 4577.9 4579.1 5348.9 5591.4 5594.9 5610.0 5631.6 3563.8 3586.6 3597.3 3597.8 3606.1 5874.9 5904.2 5907.3 5912.0 5939.6 3468.1 3557.8 3684.7 3686.5 3697.9 3644.1 3708.9 3720.4 3729.2 3744.4 4449.4 4459.3 4461.9 4462.8 4465.2 5687.9 5699.9 5701.6 5705.3 5717.9 1251.1 1270.8 1275.8 1275.9 1281.0 774.9 785.7 801.7 810.7 810.8 5122.7 5136.8 5168.4 5183.8 5219.6 5619.0 5640.9 5648.8 5692.3 5696.0 5180.2 5193.1 5213.9 5219.5 5242.5 2903.7 2981.3 2992.4 2999.2 3036.4 5849.8 5938.7 5939.7 5947.8 5974.6 5767.2 5770.7 5786.7 5788.8 5816.3 5855.9 5857.8 6154.4 6230.7 6237.9 5902.9 5930.6 5933.2 5933.6 5939.9 5816.0 5828.4 5833.6 5849.0 5879.5 5936.4 6178.7 6214.8 6221.2 6231.6 3622.6 3630.1 3638.6 3643.1 3649.5 5901.6 5901.9 5909.6 5919.6 5920.2 5606.2 5723.4 5751.1 5756.7 5763.4 5584.8 5634.1 5669.1 5688.9 5701.3 5564.7 5587.8 5602.0 5614.5 5622.3 5547.1 5712.8 5729.7 5746.9 5758.6 5632.5 5657.3 5661.5 5662.2 5687.2 11:25:02 INFO - PROCESS | 3048 | 11:25:02 INFO - PROCESS | 3048 | [#5] yui.html Cycles:180 Average:15786.51 Median:2483.66 stddev:19833.13 (798.5%) stddev-sans-first:19855.95 11:25:02 INFO - PROCESS | 3048 | Values: 593.5 656.7 669.5 675.7 683.9 13061.3 13094.0 13254.5 13817.2 14128.8 12713.6 12763.7 12943.5 13018.7 13038.3 602.4 610.9 611.7 612.4 612.9 593.5 604.2 608.6 609.1 610.0 634.2 635.1 635.6 635.8 636.6 624.0 629.0 630.3 630.4 633.1 462.4 463.5 464.4 464.4 464.7 13397.1 13425.7 13473.3 13479.3 13499.5 11431.9 11863.3 11969.0 12622.2 12725.8 10912.8 11021.8 11109.6 11365.9 11531.5 3338.0 3376.6 3417.5 3446.5 3565.7 53802.0 62274.4 64977.4 65435.3 65518.0 9853.9 9864.2 9897.3 9975.5 10098.5 15938.7 17124.1 17137.7 17310.3 17338.3 6776.9 6860.4 6871.6 6895.1 7012.4 3753.7 3810.5 3813.4 3885.3 3904.1 2592.0 2701.4 2727.6 2739.1 2742.7 479.0 480.8 484.2 485.4 493.0 550.8 553.4 646.4 677.1 681.3 8607.8 8992.3 9011.7 9077.5 9313.0 616.4 637.5 667.3 677.5 695.7 4144.2 4265.4 4483.3 4502.5 4504.9 63095.0 66244.3 69639.7 70851.0 73625.6 49655.5 55279.0 65378.0 68396.7 69669.7 54737.0 55817.4 58881.7 63375.0 64303.0 8641.5 9075.5 9287.6 9290.3 9434.7 38668.4 39192.6 39807.2 40464.6 40717.6 52435.2 52878.5 53075.0 53816.0 53871.0 11894.8 11986.2 12013.9 12072.8 12164.5 14311.7 14335.6 14522.6 14577.4 14731.1 12325.2 12621.4 12983.2 13045.7 13215.5 13080.6 13346.1 13379.9 13392.5 13555.0 12989.7 12989.7 13285.4 13735.9 13743.6 10424.0 10619.5 11363.7 11457.0 11707.4 11017.7 11117.3 11170.7 11300.7 11324.8 11:25:02 INFO - PROCESS | 3048 | -------- Summary: end -------- 11:25:02 INFO - PROCESS | 3048 | 11:25:03 INFO - TEST-INFO | 3048: exit 0 11:25:04 INFO - TEST-OK | dromaeo_css | took 1113404ms 11:25:04 INFO - TEST-START | kraken 11:25:04 INFO - operating with platform_type : w7_ 11:25:04 INFO - Initialising browser for kraken test... 11:25:04 INFO - TEST-INFO | started process 3724 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp5k8cbd\profile http://localhost:49894/getInfo.html) 11:25:09 INFO - PROCESS | 3724 | __metrics Screen width/height:1600/1200 11:25:09 INFO - PROCESS | 3724 | colorDepth:24 11:25:09 INFO - PROCESS | 3724 | Browser inner width/height: 1010/674 11:25:09 INFO - PROCESS | 3724 | __metrics 11:25:09 ERROR - PROCESS | 3724 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 11:25:09 INFO - PROCESS | 3724 | [Child 820] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-beta-w32-0000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 345 11:25:09 INFO - PROCESS | 3724 | console.error: 11:25:09 INFO - PROCESS | 3724 | Message: Error: Connection closed before committing the transaction. 11:25:09 INFO - PROCESS | 3724 | Stack: 11:25:09 INFO - PROCESS | 3724 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 11:25:09 INFO - PROCESS | 3724 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 11:25:09 INFO - PROCESS | 3724 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 11:25:09 INFO - PROCESS | 3724 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 11:25:09 INFO - PROCESS | 3724 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 11:25:09 INFO - PROCESS | 3724 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 11:25:09 INFO - PROCESS | 3724 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 11:25:09 INFO - PROCESS | 3724 | Barrier/this.client.addBlocker/promise13.4% 11:26:07 INFO - TinderboxPrint: I/O read bytes / time
68,881,920 / 1,260,140 11:26:07 INFO - TinderboxPrint: I/O write bytes / time
487,366,144 / 3,467,170 11:26:07 INFO - TinderboxPrint: CPU idle
8,169.3 (86.6%) 11:26:07 INFO - TinderboxPrint: CPU user
1,219.7 (12.9%) 11:26:07 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 49152; Read time: 0; Write time: 230 11:26:07 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 68881920; Write bytes: 449455616; Read time: 1260140; Write time: 3391700 11:26:07 INFO - Running post-run listener: _upload_blobber_files 11:26:07 INFO - Blob upload gear active. 11:26:07 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 11:26:07 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 11:26:07 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', 'Mozilla-Beta', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 11:26:07 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 Mozilla-Beta -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 11:26:08 INFO - (blobuploader) - INFO - Open directory for files ... 11:26:08 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 11:26:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:09 INFO - (blobuploader) - INFO - Done attempting. 11:26:09 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 11:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:26:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:10 INFO - (blobuploader) - INFO - Done attempting. 11:26:10 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 11:26:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:26:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:17 INFO - (blobuploader) - INFO - Done attempting. 11:26:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:26:17 INFO - Return code: 0 11:26:17 INFO - rmtree: C:\slave\test\build\uploaded_files.json 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 11:26:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7dd31870e7fd392eae13f0aaa5a6d6461e722ed21741446a1ae3e5b3eeddec45a8da1416ea9a5ff81c6b4e6122633ae04461d026132db218fb73f50fe322ed18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99c63b635ed11d1afa18ee9a41fb1054aa3484e1aac9e805cec55f5ab000b7e5fbbf3e39a0e06c0eb7a790f4e10a1aac39abe812d172a3024f729f855ebb6445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e8f28f5caf12f6ccde727dd87fe9ba37ea122cfe15c06de50031a3cdb18572ea716003346f3213834e0f133db5d367d1b5be84158b39b6fd0a9087c3bdf329c"} 11:26:17 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 11:26:17 INFO - Writing to file C:\slave\test\properties\blobber_files 11:26:17 INFO - Contents: 11:26:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7dd31870e7fd392eae13f0aaa5a6d6461e722ed21741446a1ae3e5b3eeddec45a8da1416ea9a5ff81c6b4e6122633ae04461d026132db218fb73f50fe322ed18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99c63b635ed11d1afa18ee9a41fb1054aa3484e1aac9e805cec55f5ab000b7e5fbbf3e39a0e06c0eb7a790f4e10a1aac39abe812d172a3024f729f855ebb6445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e8f28f5caf12f6ccde727dd87fe9ba37ea122cfe15c06de50031a3cdb18572ea716003346f3213834e0f133db5d367d1b5be84158b39b6fd0a9087c3bdf329c"} 11:26:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:26:17 INFO - Copying logs to upload dir... 11:26:17 INFO - mkdir: C:\slave\test\build\upload\logs 11:26:17 INFO - Copying logs to upload dir... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... 11:26:17 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1412.285000 ========= master_lag: 0.06 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 32 secs) (at 2017-02-09 11:26:17.918556) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:17.919282) ========= '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-116 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-116 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-116 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7dd31870e7fd392eae13f0aaa5a6d6461e722ed21741446a1ae3e5b3eeddec45a8da1416ea9a5ff81c6b4e6122633ae04461d026132db218fb73f50fe322ed18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99c63b635ed11d1afa18ee9a41fb1054aa3484e1aac9e805cec55f5ab000b7e5fbbf3e39a0e06c0eb7a790f4e10a1aac39abe812d172a3024f729f855ebb6445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e8f28f5caf12f6ccde727dd87fe9ba37ea122cfe15c06de50031a3cdb18572ea716003346f3213834e0f133db5d367d1b5be84158b39b6fd0a9087c3bdf329c"} build_url:https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/XVPEVV8oQ92ndPm4xAkgcw/artifacts/public/build/firefox-52.0.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7dd31870e7fd392eae13f0aaa5a6d6461e722ed21741446a1ae3e5b3eeddec45a8da1416ea9a5ff81c6b4e6122633ae04461d026132db218fb73f50fe322ed18", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99c63b635ed11d1afa18ee9a41fb1054aa3484e1aac9e805cec55f5ab000b7e5fbbf3e39a0e06c0eb7a790f4e10a1aac39abe812d172a3024f729f855ebb6445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e8f28f5caf12f6ccde727dd87fe9ba37ea122cfe15c06de50031a3cdb18572ea716003346f3213834e0f133db5d367d1b5be84158b39b6fd0a9087c3bdf329c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:18.032835) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:18.033154) ========= '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-116 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-116 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-116 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:18.143163) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:18.143474) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-02-09 11:26:18.551480) ========= ========= Total master_lag: 0.17 =========