builder: mozilla-central_win7_ix_test-dromaeojs slave: t-w732-ix-009 starttime: 1474596426.77 results: success (0) buildid: 20160922162200 builduid: f29f7ac6d6324e879e965ff93cc02774 revision: 058cf01f6cf2d2526c28b864a78afd4b97189b2a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:06.772172) ========= master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-22 19:07:06.772639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 23 secs) (at 2016-09-22 19:07:06.773282) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 23 secs) (at 2016-09-22 19:07:30.093998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2016-09-22 19:07:30.094381) ========= '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-009 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-009 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-009 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 --19:07:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 7.53 MB/s 19:07:41 (7.53 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.054000 ========= master_lag: 16.46 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2016-09-22 19:07:47.604284) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2016-09-22 19:07:47.604591) ========= '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-009 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-009 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-009 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=10.701000 ========= master_lag: 16.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2016-09-22 19:08:14.373781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-22 19:08:14.374143) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 058cf01f6cf2d2526c28b864a78afd4b97189b2a --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 058cf01f6cf2d2526c28b864a78afd4b97189b2a --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-09-22 19:08:15,486 truncating revision to first 12 chars 2016-09-22 19:08:15,486 Setting DEBUG logging. 2016-09-22 19:08:15,486 attempt 1/10 2016-09-22 19:08:15,486 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/058cf01f6cf2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-22 19:08:16,082 unpacking tar archive at: mozilla-central-058cf01f6cf2/testing/mozharness/ 2016-09-22 19:08:16,229 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.329000 ========= master_lag: 1.99 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-09-22 19:08:17.690933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:08:17.691260) ========= script_repo_revision: 058cf01f6cf2d2526c28b864a78afd4b97189b2a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-22 19:08:17.691666) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-09-22 19:08:17.691966) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 10 secs) (at 2016-09-22 19:08:28.509384) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 1 secs) (at 2016-09-22 19:08:28.509702) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-009 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-009 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-009 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 19:08:29 INFO - MultiFileLogger online at 20160922 19:08:29 in C:\slave\test 19:08:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:08:29 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 19:08:29 INFO - {'append_to_log': False, 19:08:29 INFO - 'base_work_dir': 'C:\\slave\\test', 19:08:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:08:29 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 19:08:29 INFO - 'branch': 'Firefox-Non-PGO', 19:08:29 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:29 INFO - 'config_files': ('talos/windows_config.py',), 19:08:29 INFO - 'default_actions': ('clobber', 19:08:29 INFO - 'read-buildbot-config', 19:08:29 INFO - 'download-and-extract', 19:08:29 INFO - 'populate-webroot', 19:08:29 INFO - 'create-virtualenv', 19:08:29 INFO - 'install', 19:08:29 INFO - 'run-tests'), 19:08:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:29 INFO - 'download_minidump_stackwalk': True, 19:08:29 INFO - 'download_symbols': 'ondemand', 19:08:29 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 19:08:29 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 19:08:29 INFO - 'hg': 'c:/mozilla-build/hg/hg', 19:08:29 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 19:08:29 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 19:08:29 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 19:08:29 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 19:08:29 INFO - 'C:/mozilla-build/tooltool.py'), 19:08:29 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 19:08:29 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 19:08:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:29 INFO - 'installer_path': 'installer.exe', 19:08:29 INFO - 'log_level': 'info', 19:08:29 INFO - 'log_name': 'talos', 19:08:29 INFO - 'log_to_console': True, 19:08:29 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 19:08:29 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 19:08:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 19:08:29 INFO - 'opt_config_files': (), 19:08:29 INFO - 'pip_index': False, 19:08:29 INFO - 'sps_profile': False, 19:08:29 INFO - 'sps_profile_interval': 0, 19:08:29 INFO - 'suite': 'dromaeojs', 19:08:29 INFO - 'system_bits': '32', 19:08:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:29 INFO - 'title': 'T-W732-IX-009', 19:08:29 INFO - 'use_talos_json': True, 19:08:29 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 19:08:29 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 19:08:29 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 19:08:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:29 INFO - 'work_dir': 'build'} 19:08:29 INFO - [mozharness: 2016-09-23 02:08:29.306000Z] Running clobber step. 19:08:29 INFO - Running pre-action listener: _resource_record_pre_action 19:08:29 INFO - Running main action method: clobber 19:08:29 INFO - rmtree: C:\slave\test\build 19:08:29 INFO - Using _rmtree_windows ... 19:08:29 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 19:10:52 INFO - Running post-action listener: _resource_record_post_action 19:10:52 INFO - [mozharness: 2016-09-23 02:10:52.234000Z] Finished clobber step (success) 19:10:52 INFO - [mozharness: 2016-09-23 02:10:52.234000Z] Running read-buildbot-config step. 19:10:52 INFO - Running pre-action listener: _resource_record_pre_action 19:10:52 INFO - Running main action method: read_buildbot_config 19:10:52 INFO - Using buildbot properties: 19:10:52 INFO - { 19:10:52 INFO - "project": "", 19:10:52 INFO - "product": "firefox", 19:10:52 INFO - "script_repo_revision": "production", 19:10:52 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 19:10:52 INFO - "repository": "", 19:10:52 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 19:10:52 INFO - "buildid": "20160922162200", 19:10:52 INFO - "pgo_build": "False", 19:10:52 INFO - "basedir": "C:\\slave\\test", 19:10:52 INFO - "buildnumber": 51, 19:10:52 INFO - "slavename": "t-w732-ix-009", 19:10:52 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 19:10:52 INFO - "platform": "win7_ix", 19:10:52 INFO - "scheduler": "tests-mozilla-central-win32-talos", 19:10:52 INFO - "branch": "mozilla-central", 19:10:52 INFO - "repo_path": "mozilla-central", 19:10:52 INFO - "revision": "058cf01f6cf2d2526c28b864a78afd4b97189b2a", 19:10:52 INFO - "stage_platform": "win32", 19:10:52 INFO - "builduid": "f29f7ac6d6324e879e965ff93cc02774", 19:10:52 INFO - "slavebuilddir": "test" 19:10:52 INFO - } 19:10:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 19:10:52 INFO - Running post-action listener: _resource_record_post_action 19:10:52 INFO - [mozharness: 2016-09-23 02:10:52.238000Z] Finished read-buildbot-config step (success) 19:10:52 INFO - [mozharness: 2016-09-23 02:10:52.238000Z] Running download-and-extract step. 19:10:52 INFO - Running pre-action listener: _resource_record_pre_action 19:10:52 INFO - Running main action method: download_and_extract 19:10:52 INFO - mkdir: C:\slave\test\build\tests 19:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:10:52 INFO - trying https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 19:10:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 19:10:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 19:10:54 INFO - Downloaded 1274 bytes. 19:10:54 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 19:10:54 INFO - Using the following test package requirements: 19:10:54 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 19:10:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 19:10:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 19:10:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'jsshell-win32.zip'], 19:10:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 19:10:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 19:10:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 19:10:54 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 19:10:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 19:10:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 19:10:54 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 19:10:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 19:10:54 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 19:10:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 19:10:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 19:10:55 INFO - Expected file size: 17286352 19:11:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 19:11:14 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 19:11:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 19:11:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 19:11:15 INFO - Expected file size: 17286352 19:11:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 19:11:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 19:11:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 19:11:23 INFO - Expected file size: 14414059 19:11: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')]} 19:11:26 INFO - trying https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 19:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 19:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 19:11:30 INFO - Downloaded 53472702 bytes. 19:11:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 19:11:30 INFO - mkdir: C:\slave\test\properties 19:11:30 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 19:11:30 INFO - Writing to file C:\slave\test\properties\build_url 19:11:30 INFO - Contents: 19:11:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 19:11:30 INFO - Running post-action listener: _resource_record_post_action 19:11:30 INFO - Running post-action listener: set_extra_try_arguments 19:11:30 INFO - [mozharness: 2016-09-23 02:11:30.865000Z] Finished download-and-extract step (success) 19:11:30 INFO - [mozharness: 2016-09-23 02:11:30.865000Z] Running populate-webroot step. 19:11:30 INFO - Running pre-action listener: _resource_record_pre_action 19:11:30 INFO - Running main action method: populate_webroot 19:11:30 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 19:11:30 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 19:11:30 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 19:11:30 INFO - u'--tppagecycles', 19:11:30 INFO - u'7'], 19:11:30 INFO - u'tests': [u'tsvgm']}}, 19:11:30 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 19:11:30 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 19:11:30 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 19:11:30 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 19:11:30 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 19:11:30 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 19:11:30 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'damp', u'tps']}, 19:11:30 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'tests': [u'damp', u'tps']}, 19:11:30 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'dromaeo_dom']}, 19:11:30 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 19:11:30 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'basic_compositor_video']}, 19:11:30 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 19:11:30 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'other-e10s': {u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'a11yr', 19:11:30 INFO - u'ts_paint', 19:11:30 INFO - u'tpaint', 19:11:30 INFO - u'sessionrestore', 19:11:30 INFO - u'sessionrestore_no_auto_restore', 19:11:30 INFO - u'tabpaint']}, 19:11:30 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'tsvgx', 19:11:30 INFO - u'tsvgr_opacity', 19:11:30 INFO - u'tart', 19:11:30 INFO - u'tscrollx', 19:11:30 INFO - u'cart']}, 19:11:30 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 19:11:30 INFO - u'tsvgr_opacity', 19:11:30 INFO - u'tart', 19:11:30 INFO - u'tscrollx', 19:11:30 INFO - u'cart']}, 19:11:30 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'talos_options': [u'--disable-e10s'], 19:11:30 INFO - u'tests': [u'tp5o']}, 19:11:30 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'tests': [u'tp5o']}, 19:11:30 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'talos_options': [u'--disable-e10s', 19:11:30 INFO - u'--xperf_path', 19:11:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:30 INFO - u'tests': [u'tp5n']}, 19:11:30 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:11:30 INFO - u'talos_options': [u'--xperf_path', 19:11:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:30 INFO - u'tests': [u'tp5n']}}, 19:11:30 INFO - u'talos.zip': {u'path': u'', 19:11:30 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:11:30 INFO - Running post-action listener: _resource_record_post_action 19:11:30 INFO - [mozharness: 2016-09-23 02:11:30.905000Z] Finished populate-webroot step (success) 19:11:30 INFO - [mozharness: 2016-09-23 02:11:30.905000Z] Running create-virtualenv step. 19:11:30 INFO - Running pre-action listener: _resource_record_pre_action 19:11:30 INFO - Running main action method: create_virtualenv 19:11:30 INFO - Creating virtualenv C:\slave\test\build/venv 19:11:30 INFO - mkdir: C:\slave\test\build\venv\Scripts 19:11:31 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 19:11:31 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'! 19:11:31 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 19:11:31 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 19:11:35 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 19:11:39 INFO - Installing distribute......................................................................................................................................................................................done. 19:11:40 INFO - Return code: 0 19:11:40 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 19:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 19:11:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:11:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:40 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:40 INFO - 'DCLOCATION': 'SCL3', 19:11:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:40 INFO - 'HOMEDRIVE': 'C:', 19:11:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:40 INFO - 'KTS_VERSION': '1.19c', 19:11:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:40 INFO - 'MOZ_NO_REMOTE': '1', 19:11:40 INFO - 'NO_EM_RESTART': '1', 19:11:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:40 INFO - 'OS': 'Windows_NT', 19:11:40 INFO - 'OURDRIVE': 'C:', 19:11:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:11:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:40 INFO - 'PROCESSOR_LEVEL': '6', 19:11:40 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:40 INFO - 'PROMPT': '$P$G', 19:11:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:40 INFO - 'PWD': 'C:\\slave\\test', 19:11:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:40 INFO - 'SYSTEMDRIVE': 'C:', 19:11:40 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:40 INFO - 'TEST1': 'testie', 19:11:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:40 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:40 INFO - 'USERNAME': 'cltbld', 19:11:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:40 INFO - 'WINDIR': 'C:\\windows', 19:11:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:11:42 INFO - Downloading/unpacking pip>=1.5 19:11:47 INFO - Running setup.py egg_info for package pip 19:11:47 INFO - warning: no files found matching 'pip\cacert.pem' 19:11:47 INFO - warning: no files found matching '*.html' under directory 'docs' 19:11:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 19:11:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 19:11:47 INFO - Installing collected packages: pip 19:11:47 INFO - Found existing installation: pip 0.8.2 19:11:47 INFO - Uninstalling pip: 19:11:47 INFO - Successfully uninstalled pip 19:11:47 INFO - Running setup.py install for pip 19:11:47 INFO - warning: no files found matching 'pip\cacert.pem' 19:11:47 INFO - warning: no files found matching '*.html' under directory 'docs' 19:11:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 19:11:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 19:11:47 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 19:11:47 INFO - Successfully installed pip 19:11:47 INFO - Cleaning up... 19:11:47 INFO - Return code: 0 19:11:47 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:47 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:47 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 19:11:47 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:11:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:47 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:47 INFO - 'DCLOCATION': 'SCL3', 19:11:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:47 INFO - 'HOMEDRIVE': 'C:', 19:11:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:47 INFO - 'KTS_VERSION': '1.19c', 19:11:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:47 INFO - 'MOZ_NO_REMOTE': '1', 19:11:47 INFO - 'NO_EM_RESTART': '1', 19:11:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:47 INFO - 'OS': 'Windows_NT', 19:11:47 INFO - 'OURDRIVE': 'C:', 19:11:47 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:11:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:47 INFO - 'PROCESSOR_LEVEL': '6', 19:11:47 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:47 INFO - 'PROMPT': '$P$G', 19:11:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:47 INFO - 'PWD': 'C:\\slave\\test', 19:11:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:47 INFO - 'SYSTEMDRIVE': 'C:', 19:11:47 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:47 INFO - 'TEST1': 'testie', 19:11:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:47 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:47 INFO - 'USERNAME': 'cltbld', 19:11:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:47 INFO - 'WINDIR': 'C:\\windows', 19:11:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:48 INFO - Downloading/unpacking psutil>=3.1.1 19:11:48 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 19:11:48 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 19:11:49 INFO - Installing collected packages: psutil 19:11:49 INFO - Successfully installed psutil 19:11:49 INFO - Cleaning up... 19:11:49 INFO - Return code: 0 19:11:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 19:11: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')]} 19:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 19:11:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:11:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:49 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:49 INFO - 'DCLOCATION': 'SCL3', 19:11:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:49 INFO - 'HOMEDRIVE': 'C:', 19:11:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:49 INFO - 'KTS_VERSION': '1.19c', 19:11:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:49 INFO - 'MOZ_NO_REMOTE': '1', 19:11:49 INFO - 'NO_EM_RESTART': '1', 19:11:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:49 INFO - 'OS': 'Windows_NT', 19:11:49 INFO - 'OURDRIVE': 'C:', 19:11: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;', 19:11:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:49 INFO - 'PROCESSOR_LEVEL': '6', 19:11:49 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:49 INFO - 'PROMPT': '$P$G', 19:11:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:49 INFO - 'PWD': 'C:\\slave\\test', 19:11:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:49 INFO - 'SYSTEMDRIVE': 'C:', 19:11:49 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:49 INFO - 'TEST1': 'testie', 19:11:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:49 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:49 INFO - 'USERNAME': 'cltbld', 19:11:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:49 INFO - 'WINDIR': 'C:\\windows', 19:11:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:11:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 19:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 19:11:50 INFO - Installing collected packages: mozsystemmonitor 19:11:50 INFO - Running setup.py install for mozsystemmonitor 19:11:51 INFO - Successfully installed mozsystemmonitor 19:11:51 INFO - Cleaning up... 19:11:51 INFO - Return code: 0 19:11:51 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 19:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 19:11:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:11:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:51 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:51 INFO - 'DCLOCATION': 'SCL3', 19:11:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:51 INFO - 'HOMEDRIVE': 'C:', 19:11:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:51 INFO - 'KTS_VERSION': '1.19c', 19:11:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:51 INFO - 'MOZ_NO_REMOTE': '1', 19:11:51 INFO - 'NO_EM_RESTART': '1', 19:11:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:51 INFO - 'OS': 'Windows_NT', 19:11:51 INFO - 'OURDRIVE': 'C:', 19:11:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:11:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:51 INFO - 'PROCESSOR_LEVEL': '6', 19:11:51 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:51 INFO - 'PROMPT': '$P$G', 19:11:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:51 INFO - 'PWD': 'C:\\slave\\test', 19:11:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:51 INFO - 'SYSTEMDRIVE': 'C:', 19:11:51 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:51 INFO - 'TEST1': 'testie', 19:11:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:51 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:51 INFO - 'USERNAME': 'cltbld', 19:11:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:51 INFO - 'WINDIR': 'C:\\windows', 19:11:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:51 INFO - Downloading/unpacking jsonschema==2.5.1 19:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:11:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:52 INFO - Downloading functools32-3.2.3-2.tar.gz 19:11:52 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 19:11:52 INFO - warning: no files found matching '*.txt' 19:11:52 INFO - no previously-included directories found matching 'build' 19:11:52 INFO - no previously-included directories found matching 'dist' 19:11:52 INFO - no previously-included directories found matching '.git*' 19:11:52 INFO - Installing collected packages: jsonschema, functools32 19:11:52 INFO - Running setup.py install for functools32 19:11:53 INFO - warning: no files found matching '*.txt' 19:11:53 INFO - no previously-included directories found matching 'build' 19:11:53 INFO - no previously-included directories found matching 'dist' 19:11:53 INFO - no previously-included directories found matching '.git*' 19:11:53 INFO - Successfully installed jsonschema functools32 19:11:53 INFO - Cleaning up... 19:11:53 INFO - Return code: 0 19:11:53 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 19:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 19:11:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:11:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:53 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:53 INFO - 'DCLOCATION': 'SCL3', 19:11:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:53 INFO - 'HOMEDRIVE': 'C:', 19:11:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:53 INFO - 'KTS_VERSION': '1.19c', 19:11:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:53 INFO - 'MOZ_NO_REMOTE': '1', 19:11:53 INFO - 'NO_EM_RESTART': '1', 19:11:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:53 INFO - 'OS': 'Windows_NT', 19:11:53 INFO - 'OURDRIVE': 'C:', 19:11:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:11:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:53 INFO - 'PROCESSOR_LEVEL': '6', 19:11:53 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:53 INFO - 'PROMPT': '$P$G', 19:11:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:53 INFO - 'PWD': 'C:\\slave\\test', 19:11:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:53 INFO - 'SYSTEMDRIVE': 'C:', 19:11:53 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:53 INFO - 'TEST1': 'testie', 19:11:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:53 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:53 INFO - 'USERNAME': 'cltbld', 19:11:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:53 INFO - 'WINDIR': 'C:\\windows', 19:11:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 19:11:54 INFO - Cleaning up... 19:11:54 INFO - Return code: 0 19:11:54 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 19:11: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')]} 19:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11: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', '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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11: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', 'blobuploader==1.2.4'] in C:\slave\test\build 19:11: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 blobuploader==1.2.4 19:11:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:54 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:54 INFO - 'DCLOCATION': 'SCL3', 19:11:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:54 INFO - 'HOMEDRIVE': 'C:', 19:11:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:54 INFO - 'KTS_VERSION': '1.19c', 19:11:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:54 INFO - 'MOZ_NO_REMOTE': '1', 19:11:54 INFO - 'NO_EM_RESTART': '1', 19:11:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:54 INFO - 'OS': 'Windows_NT', 19:11:54 INFO - 'OURDRIVE': 'C:', 19:11: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;', 19:11:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:54 INFO - 'PROCESSOR_LEVEL': '6', 19:11:54 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:54 INFO - 'PROMPT': '$P$G', 19:11:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:54 INFO - 'PWD': 'C:\\slave\\test', 19:11:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:54 INFO - 'SYSTEMDRIVE': 'C:', 19:11:54 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:54 INFO - 'TEST1': 'testie', 19:11:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:54 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:54 INFO - 'USERNAME': 'cltbld', 19:11:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:54 INFO - 'WINDIR': 'C:\\windows', 19:11:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:54 INFO - Downloading/unpacking blobuploader==1.2.4 19:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:55 INFO - Downloading blobuploader-1.2.4.tar.gz 19:11:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 19:11:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 19:11:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:56 INFO - Downloading docopt-0.6.1.tar.gz 19:11:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 19:11:56 INFO - Installing collected packages: blobuploader, requests, docopt 19:11:56 INFO - Running setup.py install for blobuploader 19:11:56 INFO - Running setup.py install for requests 19:11:57 INFO - Running setup.py install for docopt 19:11:57 INFO - Successfully installed blobuploader requests docopt 19:11:57 INFO - Cleaning up... 19:11:59 INFO - Return code: 0 19:11:59 INFO - Installing None into virtualenv C:\slave\test\build/venv 19:11: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')]} 19:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:59 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:11:59 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 19:11:59 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 19:11:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:11:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:11:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:11:59 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:11:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:11:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:11:59 INFO - 'DCLOCATION': 'SCL3', 19:11:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:11:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:11:59 INFO - 'HOMEDRIVE': 'C:', 19:11:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:11:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:11:59 INFO - 'KTS_VERSION': '1.19c', 19:11:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:11:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:11:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:11:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:59 INFO - 'MOZ_NO_REMOTE': '1', 19:11:59 INFO - 'NO_EM_RESTART': '1', 19:11:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:11:59 INFO - 'OS': 'Windows_NT', 19:11:59 INFO - 'OURDRIVE': 'C:', 19:11: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;', 19:11:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:11:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:11:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:11:59 INFO - 'PROCESSOR_LEVEL': '6', 19:11:59 INFO - 'PROCESSOR_REVISION': '1e05', 19:11:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:11:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:11:59 INFO - 'PROMPT': '$P$G', 19:11:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:11:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:11:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:11:59 INFO - 'PWD': 'C:\\slave\\test', 19:11:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:11:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:11:59 INFO - 'SYSTEMDRIVE': 'C:', 19:11:59 INFO - 'SYSTEMROOT': 'C:\\windows', 19:11:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:59 INFO - 'TEST1': 'testie', 19:11:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:11:59 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:11:59 INFO - 'USERNAME': 'cltbld', 19:11:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:11:59 INFO - 'WINDIR': 'C:\\windows', 19:11:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:11:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 19:12:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qdgwlq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 19:12:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-usxmb3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oia1ta-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kjlbyn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wllcnt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_einps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gps_ng-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-15pjvo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 19:12:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 19:12:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ftjhtl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1wfiyt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bj0z0y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eqm9v4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ozagfy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqgj5h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 19:12:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 19:12:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-swlya2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 19:12:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 19:12:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ddsvbp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 19:12:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 19:12:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wwzsrw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 19:12:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:03 INFO - Running setup.py install for manifestparser 19:12:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 19:12:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 19:12:03 INFO - Running setup.py install for mozcrash 19:12:04 INFO - Running setup.py install for mozdebug 19:12:04 INFO - Running setup.py install for mozdevice 19:12:04 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 19:12:04 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 19:12:04 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 19:12:04 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 19:12:05 INFO - Running setup.py install for mozfile 19:12:05 INFO - Running setup.py install for mozhttpd 19:12:05 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 19:12:05 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Running setup.py install for mozinfo 19:12:06 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Running setup.py install for mozInstall 19:12:06 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 19:12:06 INFO - Running setup.py install for mozleak 19:12:07 INFO - Running setup.py install for mozlog 19:12:07 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 19:12:07 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 19:12:07 INFO - Running setup.py install for moznetwork 19:12:08 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Running setup.py install for mozprocess 19:12:08 INFO - Running setup.py install for mozprofile 19:12:08 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 19:12:08 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 19:12:09 INFO - Running setup.py install for mozrunner 19:12:09 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 19:12:09 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 19:12:10 INFO - Running setup.py install for mozscreenshot 19:12:10 INFO - Running setup.py install for moztest 19:12:10 INFO - Running setup.py install for mozversion 19:12:10 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 19:12:10 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 19:12:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:11 INFO - Cleaning up... 19:12:11 INFO - Return code: 0 19:12:11 INFO - Installing None into virtualenv C:\slave\test\build/venv 19:12:11 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')]} 19:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:11 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')]} 19:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:11 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:12:11 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 19:12:11 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 19:12:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:12:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:12:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:12:11 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:12:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:12:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:12:11 INFO - 'DCLOCATION': 'SCL3', 19:12:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:12:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:12:11 INFO - 'HOMEDRIVE': 'C:', 19:12:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:12:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:12:11 INFO - 'KTS_VERSION': '1.19c', 19:12:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:12:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:12:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:12:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:11 INFO - 'MOZ_NO_REMOTE': '1', 19:12:11 INFO - 'NO_EM_RESTART': '1', 19:12:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:12:11 INFO - 'OS': 'Windows_NT', 19:12:11 INFO - 'OURDRIVE': 'C:', 19:12:11 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;', 19:12:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:12:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:12:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:12:11 INFO - 'PROCESSOR_LEVEL': '6', 19:12:11 INFO - 'PROCESSOR_REVISION': '1e05', 19:12:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:12:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:12:11 INFO - 'PROMPT': '$P$G', 19:12:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:12:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:12:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:12:11 INFO - 'PWD': 'C:\\slave\\test', 19:12:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:12:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:12:11 INFO - 'SYSTEMDRIVE': 'C:', 19:12:11 INFO - 'SYSTEMROOT': 'C:\\windows', 19:12:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:11 INFO - 'TEST1': 'testie', 19:12:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:11 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:12:11 INFO - 'USERNAME': 'cltbld', 19:12:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:12:11 INFO - 'WINDIR': 'C:\\windows', 19:12:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:12:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 19:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tdib0q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 19:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 19:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 19:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gr8akl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 19:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 19:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 19:12:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tub8er-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 19:12:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 19:12:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-exqdjv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 19:12:12 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)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2nyhum-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 19:12:12 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)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dinbrw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 19:12:12 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)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mnouna-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 19:12:12 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)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aprdrq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 19:12:12 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)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hcsmo3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 19:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 19:12:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 19:12:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bnzkgm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 19:12:13 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)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m_rrut-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 19:12:13 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)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a5fnay-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 19:12:13 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)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jhzg4d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 19:12:13 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)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cy7srk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2mq70n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lukbrp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 19:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 19:12:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 19:12:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qg3jna-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 19:12:14 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)) 19:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 19:12:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 19:12:14 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)) 19:12:14 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)) 19:12:14 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)) 19:12:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 19:12:14 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 19:12:14 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 19:12:15 INFO - Downloading blessings-1.6.tar.gz 19:12:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 19:12:15 INFO - Installing collected packages: blessings 19:12:15 INFO - Running setup.py install for blessings 19:12:15 INFO - Successfully installed blessings 19:12:15 INFO - Cleaning up... 19:12:18 INFO - Return code: 0 19:12:18 INFO - Done creating virtualenv C:\slave\test\build/venv. 19:12:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 19:12:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 19:12:18 INFO - Reading from file tmpfile_stdout 19:12:18 INFO - Using _rmtree_windows ... 19:12:18 INFO - Using _rmtree_windows ... 19:12:18 INFO - Current package versions: 19:12:18 INFO - blessings == 1.6 19:12:18 INFO - blobuploader == 1.2.4 19:12:18 INFO - docopt == 0.6.1 19:12:18 INFO - functools32 == 3.2.3-2 19:12:18 INFO - jsonschema == 2.5.1 19:12:18 INFO - manifestparser == 1.1 19:12:18 INFO - mozInstall == 1.12 19:12:18 INFO - mozcrash == 0.17 19:12:18 INFO - mozdebug == 0.1 19:12:18 INFO - mozdevice == 0.48 19:12:18 INFO - mozfile == 1.2 19:12:18 INFO - mozhttpd == 0.7 19:12:18 INFO - mozinfo == 0.9 19:12:18 INFO - mozleak == 0.1 19:12:18 INFO - mozlog == 3.4 19:12:18 INFO - moznetwork == 0.27 19:12:18 INFO - mozprocess == 0.23 19:12:18 INFO - mozprofile == 0.28 19:12:18 INFO - mozrunner == 6.12 19:12:18 INFO - mozscreenshot == 0.1 19:12:18 INFO - mozsystemmonitor == 0.3 19:12:18 INFO - moztest == 0.7 19:12:18 INFO - mozversion == 1.4 19:12:18 INFO - psutil == 3.1.1 19:12:18 INFO - requests == 1.2.3 19:12:18 INFO - Installing None into virtualenv C:\slave\test\build/venv 19:12: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')]} 19:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12: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')]} 19:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:12:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 19:12:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:12:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:12:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:12:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:12:18 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:12:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:12:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:12:18 INFO - 'DCLOCATION': 'SCL3', 19:12:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:12:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:12:18 INFO - 'HOMEDRIVE': 'C:', 19:12:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:12:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:12:18 INFO - 'KTS_VERSION': '1.19c', 19:12:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:12:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:12:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:12:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:18 INFO - 'MOZ_NO_REMOTE': '1', 19:12:18 INFO - 'NO_EM_RESTART': '1', 19:12:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:12:18 INFO - 'OS': 'Windows_NT', 19:12:18 INFO - 'OURDRIVE': 'C:', 19:12: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;', 19:12:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:12:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:12:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:12:18 INFO - 'PROCESSOR_LEVEL': '6', 19:12:18 INFO - 'PROCESSOR_REVISION': '1e05', 19:12:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:12:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:12:18 INFO - 'PROMPT': '$P$G', 19:12:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:12:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:12:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:12:18 INFO - 'PWD': 'C:\\slave\\test', 19:12:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:12:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:12:18 INFO - 'SYSTEMDRIVE': 'C:', 19:12:18 INFO - 'SYSTEMROOT': 'C:\\windows', 19:12:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:18 INFO - 'TEST1': 'testie', 19:12:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:18 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:12:18 INFO - 'USERNAME': 'cltbld', 19:12:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:12:18 INFO - 'WINDIR': 'C:\\windows', 19:12:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:12:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 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)) 19:12:19 INFO - Cleaning up... 19:12:19 INFO - Return code: 0 19:12:19 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 19:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:19 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 0x01820640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CBE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-009', 'USERDOMAIN': 'T-W732-IX-009', '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-009', '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 19:12:19 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 19:12:19 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 19:12:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:12:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:12:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:12:19 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:12:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:12:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:12:19 INFO - 'DCLOCATION': 'SCL3', 19:12:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:12:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:12:19 INFO - 'HOMEDRIVE': 'C:', 19:12:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:12:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:12:19 INFO - 'KTS_VERSION': '1.19c', 19:12:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:12:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:12:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:12:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:19 INFO - 'MOZ_NO_REMOTE': '1', 19:12:19 INFO - 'NO_EM_RESTART': '1', 19:12:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:12:19 INFO - 'OS': 'Windows_NT', 19:12:19 INFO - 'OURDRIVE': 'C:', 19:12:19 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;', 19:12:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:12:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:12:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:12:19 INFO - 'PROCESSOR_LEVEL': '6', 19:12:19 INFO - 'PROCESSOR_REVISION': '1e05', 19:12:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:12:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:12:19 INFO - 'PROMPT': '$P$G', 19:12:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:12:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:12:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:12:19 INFO - 'PWD': 'C:\\slave\\test', 19:12:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:12:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:12:19 INFO - 'SYSTEMDRIVE': 'C:', 19:12:19 INFO - 'SYSTEMROOT': 'C:\\windows', 19:12:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:19 INFO - 'TEST1': 'testie', 19:12:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:19 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:12:19 INFO - 'USERNAME': 'cltbld', 19:12:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:12:19 INFO - 'WINDIR': 'C:\\windows', 19:12:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:12:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 19:12:19 INFO - Cleaning up... 19:12:19 INFO - Return code: 0 19:12:19 INFO - Running post-action listener: _resource_record_post_action 19:12:19 INFO - Running post-action listener: _start_resource_monitoring 19:12:19 INFO - Starting resource monitoring. 19:12:19 INFO - [mozharness: 2016-09-23 02:12:19.636000Z] Finished create-virtualenv step (success) 19:12:19 INFO - [mozharness: 2016-09-23 02:12:19.636000Z] Running install step. 19:12:19 INFO - Running pre-action listener: _resource_record_pre_action 19:12:19 INFO - Running main action method: install 19:12:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 19:12:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 19:12:20 INFO - Reading from file tmpfile_stdout 19:12:20 INFO - Using _rmtree_windows ... 19:12:20 INFO - Using _rmtree_windows ... 19:12:20 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:20 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 19:12:20 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 19:12:20 INFO - Reading from file tmpfile_stdout 19:12:20 INFO - Output received: 19:12:20 INFO - Usage: mozinstall-script.py [options] installer 19:12:20 INFO - Options: 19:12:20 INFO - -h, --help show this help message and exit 19:12:20 INFO - -d DEST, --destination=DEST 19:12:20 INFO - Directory to install application into. [default: 19:12:20 INFO - "C:\slave\test"] 19:12:20 INFO - --app=APP Application being installed. [default: firefox] 19:12:20 INFO - Using _rmtree_windows ... 19:12:20 INFO - Using _rmtree_windows ... 19:12:20 INFO - mkdir: C:\slave\test\build\application 19:12:20 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 19:12:20 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 19:12:22 INFO - Reading from file tmpfile_stdout 19:12:22 INFO - Output received: 19:12:22 INFO - C:\slave\test\build\application\firefox\firefox.exe 19:12:22 INFO - Using _rmtree_windows ... 19:12:22 INFO - Using _rmtree_windows ... 19:12:22 INFO - Running post-action listener: _resource_record_post_action 19:12:22 INFO - [mozharness: 2016-09-23 02:12:22.433000Z] Finished install step (success) 19:12:22 INFO - [mozharness: 2016-09-23 02:12:22.433000Z] Running run-tests step. 19:12:22 INFO - Running pre-action listener: _resource_record_pre_action 19:12:22 INFO - Running main action method: run_tests 19:12:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 19:12:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 19:12:22 INFO - Python 2.7.3 19:12:22 INFO - Return code: 0 19:12:22 INFO - grabbing minidump binary from tooltool 19:12:22 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')]} 19:12:22 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 0x018E8E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F58520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 19:12:22 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 19:12:22 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 19:12:22 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 19:12:22 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 19:12:24 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmppkgbrd 19:12:24 INFO - INFO - File integrity verified, renaming tmppkgbrd to win32-minidump_stackwalk.exe 19:12:24 INFO - Return code: 0 19:12:24 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 19:12:24 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 19:12:24 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 19:12:24 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 19:12:24 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 19:12:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 19:12:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 19:12:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:12:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:12:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:12:24 INFO - 'COMPUTERNAME': 'T-W732-IX-009', 19:12:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:12:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:12:24 INFO - 'DCLOCATION': 'SCL3', 19:12:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:12:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:12:24 INFO - 'HOMEDRIVE': 'C:', 19:12:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:12:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:12:24 INFO - 'KTS_VERSION': '1.19c', 19:12:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:12:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-009', 19:12:24 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 19:12:24 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 19:12:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:12:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:24 INFO - 'MOZ_NO_REMOTE': '1', 19:12:24 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 19:12:24 INFO - 'NO_EM_RESTART': '1', 19:12:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:12:24 INFO - 'OS': 'Windows_NT', 19:12:24 INFO - 'OURDRIVE': 'C:', 19:12:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:12:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:12:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:12:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:12:24 INFO - 'PROCESSOR_LEVEL': '6', 19:12:24 INFO - 'PROCESSOR_REVISION': '1e05', 19:12:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:12:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:12:24 INFO - 'PROMPT': '$P$G', 19:12:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 19:12:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:12:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:12:24 INFO - 'PWD': 'C:\\slave\\test', 19:12:24 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 19:12:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:12:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:12:24 INFO - 'SYSTEMDRIVE': 'C:', 19:12:24 INFO - 'SYSTEMROOT': 'C:\\windows', 19:12:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:24 INFO - 'TEST1': 'testie', 19:12:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:12:24 INFO - 'USERDOMAIN': 'T-W732-IX-009', 19:12:24 INFO - 'USERNAME': 'cltbld', 19:12:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:12:24 INFO - 'WINDIR': 'C:\\windows', 19:12:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:12:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:12:24 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 19:12:25 INFO - mozversion application_buildid: 20160922162200 19:12:25 INFO - mozversion application_changeset: 058cf01f6cf2d2526c28b864a78afd4b97189b2a 19:12:25 INFO - mozversion application_display_name: Nightly 19:12:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:12:25 INFO - mozversion application_name: Firefox 19:12:25 INFO - mozversion application_remotingname: firefox 19:12:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:12:25 INFO - mozversion application_vendor: Mozilla 19:12:25 INFO - mozversion application_version: 52.0a1 19:12:25 INFO - mozversion platform_buildid: 20160922162200 19:12:25 INFO - mozversion platform_changeset: 058cf01f6cf2d2526c28b864a78afd4b97189b2a 19:12:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:12:25 INFO - mozversion platform_version: 52.0a1 19:12:25 INFO - using testdate: 1474596745 19:12:25 INFO - actual date: 1474596745 19:12:25 INFO - starting webserver on 'localhost:49897' 19:12:25 INFO - SUITE-START | Running 2 tests 19:12:25 INFO - TEST-START | dromaeo_css 19:12:25 INFO - operating with platform_type : w7_ 19:12:25 INFO - Initialising browser for dromaeo_css test... 19:12:25 INFO - TEST-INFO | started process 3140 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwwzjg_\profile http://localhost:49897/getInfo.html) 19:12:33 INFO - PROCESS | 3140 | __metrics Screen width/height:1600/1200 19:12:33 INFO - PROCESS | 3140 | colorDepth:24 19:12:33 INFO - PROCESS | 3140 | Browser inner width/height: 1010/674 19:12:33 INFO - PROCESS | 3140 | __metrics 19:12:34 INFO - PROCESS | 3140 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:12:34 INFO - PROCESS | 3140 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:12:36 INFO - TEST-INFO | 3140: exit 0 19:12:36 INFO - Browser initialized. 19:12:36 INFO - Running cycle 1/1 for dromaeo_css test... 19:12:36 INFO - TEST-INFO | started process 3572 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwwzjg_\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:36 INFO - PROCESS | 3572 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 19:12:48 INFO - PROCESS | 3572 | RSS: Main: 139276288 19:12:48 INFO - PROCESS | 3572 | 19:13:39 INFO - PROCESS | 3572 | 1474596819256 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:15:52 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49897/tests/dromaeo/cssquery-ext.html) 19:15:53 INFO - PROCESS | 3572 | RSS: Main: 191700992 19:15:53 INFO - PROCESS | 3572 | 19:18:56 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-ext.html (next: http://localhost:49897/tests/dromaeo/cssquery-jquery.html) 19:18:57 INFO - PROCESS | 3572 | RSS: Main: 201035776 19:18:57 INFO - PROCESS | 3572 | 19:21:59 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49897/tests/dromaeo/cssquery-mootools.html) 19:22:00 INFO - PROCESS | 3572 | RSS: Main: 147701760 19:22:00 INFO - PROCESS | 3572 | 19:25:02 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49897/tests/dromaeo/cssquery-prototype.html) 19:25:03 INFO - PROCESS | 3572 | RSS: Main: 141680640 19:25:03 INFO - PROCESS | 3572 | 19:28:06 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49897/tests/dromaeo/cssquery-yui.html) 19:28:06 INFO - PROCESS | 3572 | RSS: Main: 139526144 19:28:06 INFO - PROCESS | 3572 | 19:31:09 INFO - PROCESS | 3572 | Cycle 1(1): loaded http://localhost:49897/tests/dromaeo/cssquery-yui.html (next: http://localhost:49897/tests/dromaeo/cssquery-dojo.html) 19:31:10 INFO - PROCESS | 3572 | RSS: Main: 193343488 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | __start_tp_report 19:31:10 INFO - PROCESS | 3572 | _x_x_mozilla_page_load 19:31:10 INFO - PROCESS | 3572 | _x_x_mozilla_page_load_details 19:31:10 INFO - PROCESS | 3572 | |i|pagename|runs| 19:31:10 INFO - PROCESS | 3572 | |0;dojo.html;2275.3628983884514;2295.6441751528514;2308.342122495088;2317.8841057947097;2319.257837492002;3876.55419626743;3902.375619900816;3998.9800050999743;4026.2752704513177;4028.1138149607077;3883.6698880595113;3937.2519221347998;4000.719949603529;4015.2571774221788;4067.9389809152885;3387.2717365766416;3502.1594817243804;3532.7703699259596;3547.255076433952;3579.7673151245217;3552.6802587767097;3554.0581745837376;3620.945685814715;3621.3119507293663;3625.6374362563665;3542.964570354289;3578.3022310649435;3582.1223800168864;3587.3901436755814;3607.873724419646;3393.33829903169;3438.432658611326;3455.9654403455893;3495.9300813983577;3503.8598456061727;3491.1621210909198;3533.3463309287863;3556.039869235292;3557.2351944331836;3563.714902807795;3900.161465284949;3934.1148241645687;3935;3943.5662077171487;3974.880753577397;4056.1076563156057;4058.594140585947;4073.2871747444374;4194.580541945811;4199.727017743868;4102.261592913274;4270.807813648394;4280.101178752435;4280.229558679436;4283.850065247701;3867.400552914303;3935.2129574085297;3975.1453437511364;3998.3402738547816;4008.318585840414;4127.030147915238;4186.246475634415;4239.046214601752;4290.184864875664;4335.82656693735;4094.815733292009;4144.067584793458;4192.287311157111;4204.306289462265;4206.600372964564;4158.979205104016;4173.144505376391;4251.256030194703;4258.04194056341;4259.8509052183645;4000.139969906484;4059.3099173140045;4156.06488540076;4209.095044565433;4230.344296634026;4144.5026596808575;4179.623833854967;4258.127083947783;4259.212045771542;4260.659147268211;2989.536621823621;3006.2634654509784;3025.7428118610164;3033.9696603033685;3046.405950839565;2704.6619172603423;2734.46677897808;2783.7633801126726;2799.6080548723194;2802.0473039166372;3897.2205558888336;3905.33609286422;3910.882673519799;3937.015746063484;4001.679865610744;4217.725847819882;4238.279492486285;4269.146170765859;4282.89292767677;4286.314189729628;3798.3922572388838;3990.06233535119;3991.6207960243732;3996.8401263949117;4000.19996000801;3485.634008429129;3518.472229165646;3559.537260156163;3603.1352475405715;3604.85580576774;4303.8493652723255;4327.891802704895;4361.890952726208;4368.30107182862;4392.231359512137;4070.9185816282916;4100.91798164041;4283.079137985348;4284.785760711889;4287.614114729689;4158.500979882312;4224.197402493516;4317.805698743501;4356.237658409702;4373.928387545072;4153.771542565067;4222.535521092739;4229.5770422958685;4230.15396920611;4244.1087371651065;4175.185838761412;4188.706790524634;4258.425112609758;4267.935980960226;4268.295731204441;3915.6280153385383;3965.504311961005;3965.524137103566;4079.0618157823274;4133.751974881517;3950.427188057773;4011.398290256485;4084.836606535824;4092.7544347339253;4094.9795251023556;3906.335922893058;3944.566097729305;4013.578574249662;4022.7586344819406;4023.9597604023584;3969.8015099244344;4007.6393124618926;4031.548642488643;4067.4508941292547;4118.176364727126;3967.5635680074593;4012.8394864205106;4037.394390841397;4092.140650463317;4094.344904815215 19:31:10 INFO - PROCESS | 3572 | |1;ext.html;524.0383895551664;541.891621675665;543.4918351341495;548.0956421903858;571.1375822508012;25505.234842954695;35018.29963400731;36237.275254494896;36385.818070909605;39151.41272880909;37049.444258336145;37834.24331513368;37840.810795946054;37880.62119378805;37891.24217515648;7594.1646418894015;7746.380289576849;7780.299773020427;7783;7791.571463569501;6452.774152904649;6627.834304142387;9459.337846350758;9721.951390243039;9723.12491875731;9608.135267825894;9697.660581879636;9701.369410988264;9705.951470242639;9710.708678739615;9640.951795241013;9709.174720148796;9713.757156071084;9716.708498745049;9717.12545870875;6415.550911436201;6598.7030583623855;9244.58399372023;9612.66355677548;9616.23070154393;34837.65162348395;35227;35249.82375088134;35289.47065794015;35292.47061294083;2615.9476810463875;2638.9868050659816;2800.1739486851225;3070.47801873682;3154.6372167200607;26365.20904372872;26657.20028399151;26745.197644070708;27433;27486.312842178904;3276.5576647152566;3313.8840140595203;3345.0466617014035;3359.2105855124028;3443.6556344365613;216777;216843;216959.99999999843;217971;218600;44564.10871782546;44610;44725.05498900233;44832;44892.55107448949;25972.220833375028;26744.331391714975;48266.27600585994;48389;48579.757101214265;11852.822207666892;12218.511259549696;12232.571859984857;12264.509419623115;12369.690757731129;8144.022717273847;8161.143079976517;8235.38234632405;8310.127436619188;8353.37349698775;15329.386824527117;15763.842361576466;15928.442504512286;16205.918970405073;16370.345186192657;6372.872542549216;6411.179081204255;6412.775552855627;6415.358464153547;6474.158359413246;742.1502379775127;762.4091329219788;826.2646244842095;871.7776966873407;938.4463166731662;24916.875415622806;25673;25814.612780808304;26036.609450858254;26060.218193454228;902.8374892519279;921.8294615496154;924.5377311344328;1015.2740790334946;1049.5539395756773;13577.864221357857;13617.591472255848;13723.931380343234;13959.86040139606;14011.789823152661;304868;555922;570948;575083.6162202992;575301;45110.54889451129;45272.773636131606;45554.77222613866;45695.54304456979;45717.08565828665;994.8955359687274;1023.4013102335217;1078.800421921947;1108.5842808946645;1125.6960620632547;25277.873610632563;25710.228693139237;25774.742252577984;26112.73887261103;26179;37550.24899502049;38175.6182438172;38236.80881595686;38263;38306.80846595749;277032;279328;280515;467303;488741;33897;34125.97622071342;34492;34629.653703462645;34655;37921.4311785329;38275.80862095672;38331.80834095923;38584;38731;30313.848430757706;31550.684493154775;31996.84001579977;32899.50650740289;33206.33587328192;31609.20976975502;32209.355812883143;32690.182745431553;33231.667683322856;33747.49378759271;30766;30779.53830692586;31575.21061973469;31688;33918.8304058488;28323.57514637241;29647.703522964493;29778.70221297846;30548.694513054586;30597.694023060376;22349.441263968532;23434;23685.28944131679;23687.289381318587;23818.40453988664 19:31:10 INFO - PROCESS | 3572 | |2;jquery.html;290.7572177232011;304.44135012252514;313.16385251378824;315.39286872769924;321.9645838957714;11572;12434.751304973906;15752.873362096128;17014.23435945382;17065.061421621805;14637.194954277536;15468.149251791147;15783.684326313496;15846.683066338666;15858.762118568202;4598.425196850402;4914.877128071809;5173;5201.661891977009;5275.393329767087;5182.689038657673;5188.118019936601;5205.505476979681;5212.296339994109;5214.139666954948;3217.9195520111953;3383.5770528683915;4876.878078048042;5115.437301896788;5190.403103643072;5219.86950326243;5229.947700522984;5279.498447647467;5286.233496143038;5292.841214763564;4291.721038132543;4636.652251081183;4701.224297990828;5095.133827249377;5125.333706618116;10816.242862999514;10939.12487001038;11006.229563930528;11007.229493935427;11011.229213955026;1571.449992502615;1632.6653036127566;1664.858487028604;1790.8030116687155;1801.8378345948927;8058.11360750317;8314.293285070777;11018.669439916815;11642.301461912228;12524.499020039188;1555.60332115311;1638.9180540972905;1649.010593643816;1663.4843198608348;1676.3043337015124;45504;58433;61680.69159654173;62640.68679656618;64312;8618.569071546397;8638.222559969632;8849.69026084084;8937.195652391187;8997.685081022133;11218.326900386002;11227.494762735532;11234.831477527843;11238.831417528743;11258;6407.583507072025;6468.223813142453;6474.417302442803;6533.771318003942;6540.182477190351;6250.312465628777;6278.968605157036;6379.66027134307;6384.074309225136;6400.39196276353;3587.408077667208;3645.872394466181;3660.6156353583024;3668.5597728272246;3746.9437958430644;1588.7855139556243;1657.4778944632644;1660.219696742529;1670.3485640600175;1672.6821903838231;767.094828102844;797.194833218442;798.1978111997405;841.066416277931;906.3157316226207;15669.750633537664;15817.68364632724;16031.438899638457;16086.195690215442;16105.597360065858;775.7052320118431;792.908815486215;797.4816369359846;816.5917041479261;856.6830272798979;8458.36562257833;8807.735767926972;8937.821243575092;9272.350935434455;9312.115349041831;184819;259431;288461;302113;302925;59338.70330648406;75553.62223188848;75631;77348;78656;769.819092513259;798.0503201190564;800.7197480881645;808.9191080891864;808.9676412943417;14635.268236588341;14785.852141478877;14891.702165956836;15010.47463338778;15165.696686066438;37026;37111;37206.627933720316;37241.62758372381;37525.81237093797;47349.52650473451;47385.28922066103;48234.27648585344;49759.253611195134;51788.48211517837;6595.241547222131;6701.32986701326;6776.119104516381;6776.864462710817;6911.481638877205;9486.810263794823;10586.682399528027;10793.29843560166;10815.35107893529;10893.291936024134;8440.15598440151;8871.512066836385;8985.281177505914;9004.234640055407;9094.181523662903;8913.331500137387;9379.812403752023;9402;9405.529723513933;9447.763805904633;9062.546872656209;9512.429254244767;9581.137697607248;9615.423074615266;9654.227661787181;5801.448862358069;6110.663913484623;6178.969105154625;6251.093416507827;6775.491838112062;7221.205667376479;8726.389152759246;8985.101489850962;9045.231155351868;9315.208207302185 19:31:10 INFO - PROCESS | 3572 | |3;mootools.html;1087.255230167335;1145.1125377832182;1157.5890558851609;1221.6457227404053;1331.4075236519745;5498.175273708941;6284.905726414102;6467.579607325532;6473.4497567706685;6487.286398496161;6336.176297081373;6525.902111468332;6577.967110164443;6589.341065893408;6594.109795177649;1803.7745281839802;1817.0460508233218;1835.3667984545377;1835.843953263974;1836.5959488912486;1718.8453039226467;1762.9471115866481;1763.2946821271466;1786.2676302715888;1786.4104845400987;1722.603801125742;1769.761082253892;1771.0967406622651;1771.840534351908;1778.5553611597102;1677.8238284980023;1696.0841145781387;1699.0400423760598;1700.9829901701073;1703.6166862455973;1649.1177220187262;1664.0930692772347;1678.630701245724;1685.612309168898;1686.3254698120727;5824.621399609012;6179.258488981351;6216.782412615582;6221.657808820558;6231.376862313778;3730.235301763132;3892.9416058759143;3897.337452633059;3901.960980390188;3903.1217975955465;2405.482821193434;2438.8170887183355;2440.73151953285;2440.755924407563;2445.6209287560455;720.7981765105726;724.804302838236;760.0195747485736;760.3916866506828;762.3481922955904;8212.712555060603;8224.588770561448;8262.669493220264;8273.793155171168;8281.917180828175;3231.789933654305;3266.3304022675293;3266.3304022675293;3311.850966706504;3325.8503367348526;6405;6670.232923213795;6712.060311556386;6726.899096513556;6729.798106056826;3616.909577260537;3654.8903532894055;3670.4127339625534;3679.79761113136;3689.8524059037336;3010.322677397568;3109.113902537811;3448.77582957107;3449.275652113034;3452.016175390052;2683.2889284339667;2738.657667791526;2743.204470703474;2762.9447411051665;2772.9584056239173;809.9473534220257;814.1532805881935;814.4461765999177;816.1919699497573;822.0669540072062;716.813628456605;722.0432926372472;722.6711846110007;725.7786375155523;729.8832186850078;4044.8179831907046;4117.835286588563;4142.171565686875;4164.208800327928;4478.619317357996;748.3377211168155;795.6737737527586;801.2588355770889;807.3581502705364;810.9310708589718;2843.1470558832266;2858.2711408590676;2867.354845159822;2904.825710457337;2928.575356573285;7265.8546829063125;7363.337299642951;7555.244475552401;7597.962010189914;7675;7576.7348142814735;7643.4267429942975;7661.731839385706;7665.271799179069;7683.500572462772;5912.23140991677;5972.790952316561;6024.518038556905;6054.636721796618;6066.241719785027;4095.9795201023803;4120.21715873983;4131.648809851196;4204.495460544754;4243;6912.619805910723;7107.395871350786;7184.928150718426;7213.819654508469;7236.638168091469;6599.548099418121;6769.661516924232;6952.374286314256;7045.084139061889;7173.10336207974;5456.53619442336;5690.174924635988;5702.714864256687;5710.971445142914;5746.856328591819;5097.668651537638;5273.604479664117;5392.838214853561;5536.307961504812;6129.141920131096;5074.137396642654;5349.759260833194;5397.27136836522;5444.537214336668;5694.629849059798;5114.079465696211;5175.948240517547;5195.19474481456;5465.918011229915;5531.944680553143;5219.530242278213;5314.415414304501;5419.295491586068;5428.864278392914;5439.4288599696465;3594.3709850775485;3702.777833330009;3738.065483629093;3792.0020696311126;3800.353939830291;3650.908727281839;3654.4518322251874;3775.6979441645153;3854.1520865409566;3895.2793733159456 19:31:10 INFO - PROCESS | 3572 | |4;prototype.html;229.94941112955166;230.7415694422247;236.889846221507;238.02765702106893;242.04151559823111;4387.714798538097;4450.532694067125;4462.509123996362;4462.665300102497;4473.642108631309;4116.938245926313;4275.25183092959;4286.571342865712;4295.441592592959;4314.1803057419065;3147.9370412591734;3166.097662166283;3176.6188057433083;3276.6068071831414;3285.622153452348;3242.1408326793417;3285.589301337333;3288.983555082233;3292.259241670617;3294.2917272786344;3252.251982044131;3256;3263.967360326402;3265.771396002269;3267.395531826608;3070.1710538154684;3164.7309978651842;3183.904482865518;3239.49787782894;3263.1515805890403;2820.3654177810035;2839.6166517520282;2841.602175695404;2844.9715502844915;2855.600215969766;4207.747535147871;4289.276309543431;4289.828406863721;4301.204277208726;4311.245532031913;3807.714421418377;3811.3901775715754;3820.5033345665165;3821.45231181372;3825.9234815303817;4550.431196100488;4554;4554.407926969506;4560.452745670507;4565.794539245742;2898.0001899344693;3069.7083777041144;3071.1247294521177;3074.1546074829375;3085.9845700771575;4670.369500117508;4672.789724462407;4741.288806678991;4753.144434001843;4760.33355330254;3043.193553708269;3110.9844450778055;3112.595362602847;3122.5480151729553;3122.656507784142;3147.7953932994283;3151.952720709191;3152.0543836848856;3153.290509635314;3157.826319552447;3641.851307728453;3697.279030589063;3698.7965661888315;3706.240220754739;3716.163863130828;4307.5261721210645;4553.931691024637;4557.088582283556;4564.863054108382;4598.540145985442;1128.418864284894;1140.6292954789726;1146.2721172055806;1147.260017288844;1147.925384849982;718.7843646906011;731.4879584291018;748.135903031999;749.5203070035182;749.7825630567183;4137.937930931039;4292.540536217639;4298.656107511391;4390.319500477431;4410.713303635253;4734.668573199911;4739.4075740532435;4772.809087636525;4781.131321207317;4782.928256076161;4374.812593703148;4379.759113248802;4386.188555117314;4389;4416.580424859633;2500.9746004138215;2525.4949010198034;2530.329462692388;2532.4555220627653;2532.8227024108505;4575.428071491064;4845.781939812717;4858.368412106474;4887.315775791392;4889.193283108248;4804.903901922012;4873.366462359942;4881;4884.902301954012;4886.3403440535785;4867.1261564298;4885.511448855086;4885.755712214375;4890.559849613481;4892.315075889378;4518.9322160168285;4781.474037855903;4782.943334366256;4796.448408432935;4797.280407938837;4643.698159619614;4674.415698037746;4676.228422310281;4686.695364801277;4688.132695451352;4853.927191092207;4857.445617402397;4860.295257187618;4880.682755620874;4895.632827537877;3115.859786309667;3137.341158356771;3140.654528001872;3142.245860993391;3143.7799354044996;4738.241881298975;4742.739149346819;4745.240761478147;4750.216214324598;4751.596114330182;4436.689431739876;4510.684252102321;4543.5910768031035;4570.4972453030805;4592.196365636067;4502.414686090919;4546.181687296318;4549.545045495424;4566.908661826811;4568.1548913451115;4468.664850136188;4481.193385190697;4485.618722408632;4503.414556107685;4503.752293623882;4551.291981271138;4583.129205450954;4584.862454126382;4595.287730401793;4605.355250264898;4302.376155457504;4542.7501487417185;4545.840895568639;4557.339185818104;4576.244919588364 19:31:10 INFO - PROCESS | 3572 | |5;yui.html;568.8122919436584;632.7816903168406;644.6808829629334;648.2415573778678;664.2062735031637;12318.384080795959;13402.865971340283;13511.66220844477;13534.729305413886;13583.18500889946;12316;12849.743005139915;13452.596422107328;13633.659158521017;13839.446422143128;642.8328634555014;663.6913835066694;667.5160508631228;668.5220067651628;668.5620918298522;508.7608823852783;508.9974550127244;639.5043841023221;654.7839213059679;661.1801366305771;687.9931200688004;701.421327404893;701.7087908517959;704.4047779626234;704.531486561436;684.8904175331947;685.1572565744137;698.2075344484048;698.6646409723311;701.7368486817444;388.32237745134637;392.6956608628307;395.9307121253769;396.06528592521624;397.0668928019161;12275.017998560093;12661.30362830044;12699.809502857464;13139;13219.140755850838;11664.241824281395;11707.356095414749;12005.879941200563;12168.81746773799;12444.564440244549;10207.285490015702;10249.590016399336;10270.229732770073;10379;10432.947835260851;3152.7320177784914;3219.2112932331493;3230.2893363460003;3277.836108194581;3309.93380132396;60642.98471919014;63098.369016309705;63317.05024424638;63513;63795.043074354384;9684.75788105303;9720.222382209406;9749.317547771589;9840.803183936425;9850.458224797705;17443.389481368093;17444.738328925076;17459.21433535494;17507.824921750875;17515.211815468334;5833.095870140135;6049.969750151221;6177.135201071854;6376.744930202833;6498.350164983463;3534.9646503534636;3654.6710796028483;3745.3258413485833;3764.5294338207723;3838.6353296436796;2396.2811156652933;2493.4639052603416;2500.699916010054;2506.624006399018;2516.194817658332;416.8603517821564;417.13652738830933;429.7185343599947;434.7413289092985;434.8847555397822;455.362492510488;511.8541215753492;513.8766695992935;546.710243570908;601.8014055361808;7490.363319117936;7745.651445684957;7798.961005194938;7923.9603801981775;8115.472494287852;523.0898237067476;587.7913340764053;589.1633879890566;594.1236675903009;605.7153138025121;3719.516462859811;3770.792606406619;3806.885793426202;3824.158685089276;3901.4147877818555;53207;81268;84240.15759842446;85767;86284.56857715671;72014;74110.6294468535;80801.59599201966;81841.59079204565;81941.02336527784;58173;71217.6439117801;72518;73961.6301918487;75031.62484187544;7213.134423869169;7422.331990120915;7663.27198916102;7710.306072453506;8285.461445006054;34960;39897.60102398939;40909.38635920523;41693;41848.79075604602;54783.45216547784;56471;58493.70753146207;59578.70210648919;61112.08331874936;10443.843342350023;10692;10729.785404291715;10843.403612801056;10870.402127883042;12415.44130514147;12644.494220231456;12772.233665979698;12774.594794572775;13104.868951310365;10876.456177191267;11061.225714199929;11084.722881928017;11220.38287894141;11307.321560706385;11807.350595717318;11948.70128246766;12013.399330033288;12079.335636540074;12149.635510934686;10903.12774977984;10982.835257470984;11063.557457701601;11073.723156921142;11324.37715925632;8966.910330896868;10076;10164.288499805238;10185.490725463846;10194.592216311265;9246.630134794534;9639.855402168832;9642.373245739005;9666.867478877331;9879.50602469888 19:31:10 INFO - PROCESS | 3572 | __end_tp_report 19:31:10 INFO - PROCESS | 3572 | __start_cc_report 19:31:10 INFO - PROCESS | 3572 | _x_x_mozilla_cycle_collect,976 19:31:10 INFO - PROCESS | 3572 | __end_cc_report 19:31:10 INFO - PROCESS | 3572 | __startTimestamp1474597870158__endTimestamp 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | ------- Summary: start ------- 19:31:10 INFO - PROCESS | 3572 | Number of tests: 6 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#0] dojo.html Cycles:165 Average:3880.56 Median:4012.84 stddev:461.38 (11.5%) stddev-sans-first:445.28 19:31:10 INFO - PROCESS | 3572 | Values: 2275.4 2295.6 2308.3 2317.9 2319.3 3876.6 3902.4 3999.0 4026.3 4028.1 3883.7 3937.3 4000.7 4015.3 4067.9 3387.3 3502.2 3532.8 3547.3 3579.8 3552.7 3554.1 3620.9 3621.3 3625.6 3543.0 3578.3 3582.1 3587.4 3607.9 3393.3 3438.4 3456.0 3495.9 3503.9 3491.2 3533.3 3556.0 3557.2 3563.7 3900.2 3934.1 3935.0 3943.6 3974.9 4056.1 4058.6 4073.3 4194.6 4199.7 4102.3 4270.8 4280.1 4280.2 4283.9 3867.4 3935.2 3975.1 3998.3 4008.3 4127.0 4186.2 4239.0 4290.2 4335.8 4094.8 4144.1 4192.3 4204.3 4206.6 4159.0 4173.1 4251.3 4258.0 4259.9 4000.1 4059.3 4156.1 4209.1 4230.3 4144.5 4179.6 4258.1 4259.2 4260.7 2989.5 3006.3 3025.7 3034.0 3046.4 2704.7 2734.5 2783.8 2799.6 2802.0 3897.2 3905.3 3910.9 3937.0 4001.7 4217.7 4238.3 4269.1 4282.9 4286.3 3798.4 3990.1 3991.6 3996.8 4000.2 3485.6 3518.5 3559.5 3603.1 3604.9 4303.8 4327.9 4361.9 4368.3 4392.2 4070.9 4100.9 4283.1 4284.8 4287.6 4158.5 4224.2 4317.8 4356.2 4373.9 4153.8 4222.5 4229.6 4230.2 4244.1 4175.2 4188.7 4258.4 4267.9 4268.3 3915.6 3965.5 3965.5 4079.1 4133.8 3950.4 4011.4 4084.8 4092.8 4095.0 3906.3 3944.6 4013.6 4022.8 4024.0 3969.8 4007.6 4031.5 4067.5 4118.2 3967.6 4012.8 4037.4 4092.1 4094.3 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#1] ext.html Cycles:180 Average:49400.68 Median:35238.41 stddev:105296.40 (298.8%) stddev-sans-first:105527.84 19:31:10 INFO - PROCESS | 3572 | Values: 524.0 541.9 543.5 548.1 571.1 25505.2 35018.3 36237.3 36385.8 39151.4 37049.4 37834.2 37840.8 37880.6 37891.2 7594.2 7746.4 7780.3 7783.0 7791.6 6452.8 6627.8 9459.3 9722.0 9723.1 9608.1 9697.7 9701.4 9706.0 9710.7 9641.0 9709.2 9713.8 9716.7 9717.1 6415.6 6598.7 9244.6 9612.7 9616.2 34837.7 35227.0 35249.8 35289.5 35292.5 2615.9 2639.0 2800.2 3070.5 3154.6 26365.2 26657.2 26745.2 27433.0 27486.3 3276.6 3313.9 3345.0 3359.2 3443.7 216777.0 216843.0 216960.0 217971.0 218600.0 44564.1 44610.0 44725.1 44832.0 44892.6 25972.2 26744.3 48266.3 48389.0 48579.8 11852.8 12218.5 12232.6 12264.5 12369.7 8144.0 8161.1 8235.4 8310.1 8353.4 15329.4 15763.8 15928.4 16205.9 16370.3 6372.9 6411.2 6412.8 6415.4 6474.2 742.2 762.4 826.3 871.8 938.4 24916.9 25673.0 25814.6 26036.6 26060.2 902.8 921.8 924.5 1015.3 1049.6 13577.9 13617.6 13723.9 13959.9 14011.8 304868.0 555922.0 570948.0 575083.6 575301.0 45110.5 45272.8 45554.8 45695.5 45717.1 994.9 1023.4 1078.8 1108.6 1125.7 25277.9 25710.2 25774.7 26112.7 26179.0 37550.2 38175.6 38236.8 38263.0 38306.8 277032.0 279328.0 280515.0 467303.0 488741.0 33897.0 34126.0 34492.0 34629.7 34655.0 37921.4 38275.8 38331.8 38584.0 38731.0 30313.8 31550.7 31996.8 32899.5 33206.3 31609.2 32209.4 32690.2 33231.7 33747.5 30766.0 30779.5 31575.2 31688.0 33918.8 28323.6 29647.7 29778.7 30548.7 30597.7 22349.4 23434.0 23685.3 23687.3 23818.4 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#2] jquery.html Cycles:180 Average:19598.23 Median:5185.40 stddev:45647.89 (880.3%) stddev-sans-first:45752.93 19:31:10 INFO - PROCESS | 3572 | Values: 290.8 304.4 313.2 315.4 322.0 11572.0 12434.8 15752.9 17014.2 17065.1 14637.2 15468.1 15783.7 15846.7 15858.8 4598.4 4914.9 5173.0 5201.7 5275.4 5182.7 5188.1 5205.5 5212.3 5214.1 3217.9 3383.6 4876.9 5115.4 5190.4 5219.9 5229.9 5279.5 5286.2 5292.8 4291.7 4636.7 4701.2 5095.1 5125.3 10816.2 10939.1 11006.2 11007.2 11011.2 1571.4 1632.7 1664.9 1790.8 1801.8 8058.1 8314.3 11018.7 11642.3 12524.5 1555.6 1638.9 1649.0 1663.5 1676.3 45504.0 58433.0 61680.7 62640.7 64312.0 8618.6 8638.2 8849.7 8937.2 8997.7 11218.3 11227.5 11234.8 11238.8 11258.0 6407.6 6468.2 6474.4 6533.8 6540.2 6250.3 6279.0 6379.7 6384.1 6400.4 3587.4 3645.9 3660.6 3668.6 3746.9 1588.8 1657.5 1660.2 1670.3 1672.7 767.1 797.2 798.2 841.1 906.3 15669.8 15817.7 16031.4 16086.2 16105.6 775.7 792.9 797.5 816.6 856.7 8458.4 8807.7 8937.8 9272.4 9312.1 184819.0 259431.0 288461.0 302113.0 302925.0 59338.7 75553.6 75631.0 77348.0 78656.0 769.8 798.1 800.7 808.9 809.0 14635.3 14785.9 14891.7 15010.5 15165.7 37026.0 37111.0 37206.6 37241.6 37525.8 47349.5 47385.3 48234.3 49759.3 51788.5 6595.2 6701.3 6776.1 6776.9 6911.5 9486.8 10586.7 10793.3 10815.4 10893.3 8440.2 8871.5 8985.3 9004.2 9094.2 8913.3 9379.8 9402.0 9405.5 9447.8 9062.5 9512.4 9581.1 9615.4 9654.2 5801.4 6110.7 6179.0 6251.1 6775.5 7221.2 8726.4 8985.1 9045.2 9315.2 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#3] mootools.html Cycles:180 Average:4087.99 Median:5246.57 stddev:2250.52 (42.9%) stddev-sans-first:2245.54 19:31:10 INFO - PROCESS | 3572 | Values: 1087.3 1145.1 1157.6 1221.6 1331.4 5498.2 6284.9 6467.6 6473.4 6487.3 6336.2 6525.9 6578.0 6589.3 6594.1 1803.8 1817.0 1835.4 1835.8 1836.6 1718.8 1762.9 1763.3 1786.3 1786.4 1722.6 1769.8 1771.1 1771.8 1778.6 1677.8 1696.1 1699.0 1701.0 1703.6 1649.1 1664.1 1678.6 1685.6 1686.3 5824.6 6179.3 6216.8 6221.7 6231.4 3730.2 3892.9 3897.3 3902.0 3903.1 2405.5 2438.8 2440.7 2440.8 2445.6 720.8 724.8 760.0 760.4 762.3 8212.7 8224.6 8262.7 8273.8 8281.9 3231.8 3266.3 3266.3 3311.9 3325.9 6405.0 6670.2 6712.1 6726.9 6729.8 3616.9 3654.9 3670.4 3679.8 3689.9 3010.3 3109.1 3448.8 3449.3 3452.0 2683.3 2738.7 2743.2 2762.9 2773.0 809.9 814.2 814.4 816.2 822.1 716.8 722.0 722.7 725.8 729.9 4044.8 4117.8 4142.2 4164.2 4478.6 748.3 795.7 801.3 807.4 810.9 2843.1 2858.3 2867.4 2904.8 2928.6 7265.9 7363.3 7555.2 7598.0 7675.0 7576.7 7643.4 7661.7 7665.3 7683.5 5912.2 5972.8 6024.5 6054.6 6066.2 4096.0 4120.2 4131.6 4204.5 4243.0 6912.6 7107.4 7184.9 7213.8 7236.6 6599.5 6769.7 6952.4 7045.1 7173.1 5456.5 5690.2 5702.7 5711.0 5746.9 5097.7 5273.6 5392.8 5536.3 6129.1 5074.1 5349.8 5397.3 5444.5 5694.6 5114.1 5175.9 5195.2 5465.9 5531.9 5219.5 5314.4 5419.3 5428.9 5439.4 3594.4 3702.8 3738.1 3792.0 3800.4 3650.9 3654.5 3775.7 3854.2 3895.3 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#4] prototype.html Cycles:180 Average:3800.97 Median:4400.52 stddev:1169.96 (26.6%) stddev-sans-first:1142.13 19:31:10 INFO - PROCESS | 3572 | Values: 229.9 230.7 236.9 238.0 242.0 4387.7 4450.5 4462.5 4462.7 4473.6 4116.9 4275.3 4286.6 4295.4 4314.2 3147.9 3166.1 3176.6 3276.6 3285.6 3242.1 3285.6 3289.0 3292.3 3294.3 3252.3 3256.0 3264.0 3265.8 3267.4 3070.2 3164.7 3183.9 3239.5 3263.2 2820.4 2839.6 2841.6 2845.0 2855.6 4207.7 4289.3 4289.8 4301.2 4311.2 3807.7 3811.4 3820.5 3821.5 3825.9 4550.4 4554.0 4554.4 4560.5 4565.8 2898.0 3069.7 3071.1 3074.2 3086.0 4670.4 4672.8 4741.3 4753.1 4760.3 3043.2 3111.0 3112.6 3122.5 3122.7 3147.8 3152.0 3152.1 3153.3 3157.8 3641.9 3697.3 3698.8 3706.2 3716.2 4307.5 4553.9 4557.1 4564.9 4598.5 1128.4 1140.6 1146.3 1147.3 1147.9 718.8 731.5 748.1 749.5 749.8 4137.9 4292.5 4298.7 4390.3 4410.7 4734.7 4739.4 4772.8 4781.1 4782.9 4374.8 4379.8 4386.2 4389.0 4416.6 2501.0 2525.5 2530.3 2532.5 2532.8 4575.4 4845.8 4858.4 4887.3 4889.2 4804.9 4873.4 4881.0 4884.9 4886.3 4867.1 4885.5 4885.8 4890.6 4892.3 4518.9 4781.5 4782.9 4796.4 4797.3 4643.7 4674.4 4676.2 4686.7 4688.1 4853.9 4857.4 4860.3 4880.7 4895.6 3115.9 3137.3 3140.7 3142.2 3143.8 4738.2 4742.7 4745.2 4750.2 4751.6 4436.7 4510.7 4543.6 4570.5 4592.2 4502.4 4546.2 4549.5 4566.9 4568.2 4468.7 4481.2 4485.6 4503.4 4503.8 4551.3 4583.1 4584.9 4595.3 4605.4 4302.4 4542.8 4545.8 4557.3 4576.2 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - PROCESS | 3572 | [#5] yui.html Cycles:180 Average:16483.54 Median:21139.25 stddev:22874.47 (108.2%) stddev-sans-first:22907.42 19:31:10 INFO - PROCESS | 3572 | Values: 568.8 632.8 644.7 648.2 664.2 12318.4 13402.9 13511.7 13534.7 13583.2 12316.0 12849.7 13452.6 13633.7 13839.4 642.8 663.7 667.5 668.5 668.6 508.8 509.0 639.5 654.8 661.2 688.0 701.4 701.7 704.4 704.5 684.9 685.2 698.2 698.7 701.7 388.3 392.7 395.9 396.1 397.1 12275.0 12661.3 12699.8 13139.0 13219.1 11664.2 11707.4 12005.9 12168.8 12444.6 10207.3 10249.6 10270.2 10379.0 10432.9 3152.7 3219.2 3230.3 3277.8 3309.9 60643.0 63098.4 63317.1 63513.0 63795.0 9684.8 9720.2 9749.3 9840.8 9850.5 17443.4 17444.7 17459.2 17507.8 17515.2 5833.1 6050.0 6177.1 6376.7 6498.4 3535.0 3654.7 3745.3 3764.5 3838.6 2396.3 2493.5 2500.7 2506.6 2516.2 416.9 417.1 429.7 434.7 434.9 455.4 511.9 513.9 546.7 601.8 7490.4 7745.7 7799.0 7924.0 8115.5 523.1 587.8 589.2 594.1 605.7 3719.5 3770.8 3806.9 3824.2 3901.4 53207.0 81268.0 84240.2 85767.0 86284.6 72014.0 74110.6 80801.6 81841.6 81941.0 58173.0 71217.6 72518.0 73961.6 75031.6 7213.1 7422.3 7663.3 7710.3 8285.5 34960.0 39897.6 40909.4 41693.0 41848.8 54783.5 56471.0 58493.7 59578.7 61112.1 10443.8 10692.0 10729.8 10843.4 10870.4 12415.4 12644.5 12772.2 12774.6 13104.9 10876.5 11061.2 11084.7 11220.4 11307.3 11807.4 11948.7 12013.4 12079.3 12149.6 10903.1 10982.8 11063.6 11073.7 11324.4 8966.9 10076.0 10164.3 10185.5 10194.6 9246.6 9639.9 9642.4 9666.9 9879.5 19:31:10 INFO - PROCESS | 3572 | -------- Summary: end -------- 19:31:10 INFO - PROCESS | 3572 | 19:31:10 INFO - TEST-INFO | 3572: exit 0 19:31:11 INFO - TEST-OK | dromaeo_css | took 1125950ms 19:31:11 INFO - TEST-START | kraken 19:31:11 INFO - operating with platform_type : w7_ 19:31:11 INFO - Initialising browser for kraken test... 19:31:11 INFO - TEST-INFO | started process 548 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpfrr1wm\profile http://localhost:49897/getInfo.html) 19:31:17 INFO - PROCESS | 548 | __metrics Screen width/height:1600/1200 19:31:17 INFO - PROCESS | 548 | colorDepth:24 19:31:17 INFO - PROCESS | 548 | Browser inner width/height: 1010/674 19:31:17 INFO - PROCESS | 548 | __metrics 19:31:18 INFO - PROCESS | 548 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:31:18 INFO - PROCESS | 548 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:19 INFO - PROCESS | 548 | console.error: 19:31:19 INFO - PROCESS | 548 | Message: Error: Connection closed before committing the transaction. 19:31:19 INFO - PROCESS | 548 | Stack: 19:31:19 INFO - PROCESS | 548 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 19:31:19 INFO - PROCESS | 548 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 19:31:19 INFO - PROCESS | 548 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 19:31:19 INFO - PROCESS | 548 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 19:31:19 INFO - PROCESS | 548 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 19:31:19 INFO - PROCESS | 548 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 19:31:19 INFO - PROCESS | 548 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 19:31:19 INFO - PROCESS | 548 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 19:31:19 INFO - PROCESS | 548 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 19:31:19 INFO - PROCESS | 548 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 19:31:19 INFO - PROCESS | 548 | Barrier/this.client.addBlocker/promise13.1% 19:32:19 INFO - TinderboxPrint: I/O read bytes / time
69,488,640 / 781,670 19:32:19 INFO - TinderboxPrint: I/O write bytes / time
458,013,184 / 3,037,510 19:32:19 INFO - TinderboxPrint: CPU idle
8,339.8 (86.9%) 19:32:19 INFO - TinderboxPrint: CPU user
1,220.7 (12.7%) 19:32:19 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 33945600; Read time: 0; Write time: 183970 19:32:19 INFO - run-tests - Wall time: 1197s; CPU: 13.0%; Read bytes: 69488640; Write bytes: 383727104; Read time: 781670; Write time: 2630060 19:32:20 INFO - Running post-run listener: _upload_blobber_files 19:32:20 INFO - Blob upload gear active. 19:32:20 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 19:32:20 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 19:32:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 19:32:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 19:32:20 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:20 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 19:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:21 INFO - (blobuploader) - INFO - Done attempting. 19:32:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 19:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:32:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:22 INFO - (blobuploader) - INFO - Done attempting. 19:32:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 19:32:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:30 INFO - (blobuploader) - INFO - Done attempting. 19:32:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:30 INFO - Return code: 0 19:32:30 INFO - rmtree: C:\slave\test\build\uploaded_files.json 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 19:32:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04267a21aaf375765956a6b6e97260b5e85b6bf1c0b38cf0510f788d3ec396bdddc75d896823f6b2a1f1e35a45e846b28031ffc0210feba235031330e426208a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5462f042f8a1c6029b30989e04f99fdf7b35cd14ecfca4bb07c073599a111b25e74be658ac33ab09280bf1cc280ed666269dfaf9217d8624a55b3b58ce2603d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b8f009a57f056348bf88c8eeb45807f9db8fbc2d99dae14a81a591e69434e1aca5731d59e68e5a401ca72462c784b6082a3bcd1c5782e5f7a4ccf1ebf942bd8"} 19:32:30 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 19:32:30 INFO - Writing to file C:\slave\test\properties\blobber_files 19:32:30 INFO - Contents: 19:32:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04267a21aaf375765956a6b6e97260b5e85b6bf1c0b38cf0510f788d3ec396bdddc75d896823f6b2a1f1e35a45e846b28031ffc0210feba235031330e426208a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5462f042f8a1c6029b30989e04f99fdf7b35cd14ecfca4bb07c073599a111b25e74be658ac33ab09280bf1cc280ed666269dfaf9217d8624a55b3b58ce2603d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b8f009a57f056348bf88c8eeb45807f9db8fbc2d99dae14a81a591e69434e1aca5731d59e68e5a401ca72462c784b6082a3bcd1c5782e5f7a4ccf1ebf942bd8"} 19:32:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:30 INFO - Copying logs to upload dir... 19:32:30 INFO - mkdir: C:\slave\test\build\upload\logs 19:32:30 INFO - Copying logs to upload dir... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... 19:32:30 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1441.827000 ========= master_lag: -0.03 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 1 secs) (at 2016-09-22 19:32:30.310397) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:32:30.311554) ========= '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-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04267a21aaf375765956a6b6e97260b5e85b6bf1c0b38cf0510f788d3ec396bdddc75d896823f6b2a1f1e35a45e846b28031ffc0210feba235031330e426208a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5462f042f8a1c6029b30989e04f99fdf7b35cd14ecfca4bb07c073599a111b25e74be658ac33ab09280bf1cc280ed666269dfaf9217d8624a55b3b58ce2603d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b8f009a57f056348bf88c8eeb45807f9db8fbc2d99dae14a81a591e69434e1aca5731d59e68e5a401ca72462c784b6082a3bcd1c5782e5f7a4ccf1ebf942bd8"} build_url:https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/Mp09RS7vTYq7qNxICr_o_w/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04267a21aaf375765956a6b6e97260b5e85b6bf1c0b38cf0510f788d3ec396bdddc75d896823f6b2a1f1e35a45e846b28031ffc0210feba235031330e426208a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5462f042f8a1c6029b30989e04f99fdf7b35cd14ecfca4bb07c073599a111b25e74be658ac33ab09280bf1cc280ed666269dfaf9217d8624a55b3b58ce2603d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7b8f009a57f056348bf88c8eeb45807f9db8fbc2d99dae14a81a591e69434e1aca5731d59e68e5a401ca72462c784b6082a3bcd1c5782e5f7a4ccf1ebf942bd8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-22 19:32:30.427037) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:32:30.427370) ========= '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-009 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-009 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-009 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-22 19:32:30.539483) ========= ========= Started reboot slave lost (results: 0, elapsed: 5 secs) (at 2016-09-22 19:32:30.539970) ========= ========= Finished reboot slave lost (results: 0, elapsed: 5 secs) (at 2016-09-22 19:32:35.597821) ========= ========= Total master_lag: 34.51 =========