builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-089 starttime: 1480481746.51 results: success (0) buildid: 20161129171714 builduid: 4c697f9685534775b1330889cfcae254 revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:55:46.510355) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 20:55:46.511031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:55:46.511422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 20:55:46.659776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:55:46.660266) ========= '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-089 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-089 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-089 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 --20:55:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 4.26 MB/s 20:55:47 (4.26 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.160000 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-29 20:55:47.882058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-11-29 20:55:47.882449) ========= '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-089 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-089 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-089 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=15.807000 ========= master_lag: 0.61 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-11-29 20:56:04.299516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 20:56:04.299899) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --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 adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-089 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-089 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-089 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-11-29 20:56:04,601 truncating revision to first 12 chars 2016-11-29 20:56:04,601 Setting DEBUG logging. 2016-11-29 20:56:04,601 attempt 1/10 2016-11-29 20:56:04,601 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/adcc39e3cad0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 20:56:05,913 unpacking tar archive at: mozilla-central-adcc39e3cad0/testing/mozharness/ 2016-11-29 20:56:06,091 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.068000 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 20:56:06.540674) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:56:06.540995) ========= script_repo_revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 20:56:06.541381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:56:06.541661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 20:56:06.551187) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2016-11-29 20:56:06.551488) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-089 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-089 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-089 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 20:56:07 INFO - MultiFileLogger online at 20161129 20:56:07 in C:\slave\test 20:56:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:56:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 20:56:07 INFO - {'append_to_log': False, 20:56:07 INFO - 'base_work_dir': 'C:\\slave\\test', 20:56:07 INFO - 'blob_upload_branch': 'Firefox', 20:56:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 20:56:07 INFO - 'branch': 'Firefox', 20:56:07 INFO - 'buildbot_json_path': 'buildprops.json', 20:56:07 INFO - 'config_files': ('talos/windows_config.py',), 20:56:07 INFO - 'default_actions': ('clobber', 20:56:07 INFO - 'read-buildbot-config', 20:56:07 INFO - 'download-and-extract', 20:56:07 INFO - 'populate-webroot', 20:56:07 INFO - 'create-virtualenv', 20:56:07 INFO - 'install', 20:56:07 INFO - 'run-tests'), 20:56:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:56:07 INFO - 'download_minidump_stackwalk': True, 20:56:07 INFO - 'download_symbols': 'ondemand', 20:56:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 20:56:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 20:56:07 INFO - 'hg': 'c:/mozilla-build/hg/hg', 20:56:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 20:56:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 20:56:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 20:56:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 20:56:07 INFO - 'C:/mozilla-build/tooltool.py'), 20:56:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 20:56:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 20:56:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:56:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:56:07 INFO - 'installer_path': 'installer.exe', 20:56:07 INFO - 'log_level': 'info', 20:56:07 INFO - 'log_name': 'talos', 20:56:07 INFO - 'log_to_console': True, 20:56:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 20:56:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 20:56:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 20:56:07 INFO - 'opt_config_files': (), 20:56:07 INFO - 'pip_index': False, 20:56:07 INFO - 'sps_profile': False, 20:56:07 INFO - 'sps_profile_interval': 0, 20:56:07 INFO - 'suite': 'dromaeojs', 20:56:07 INFO - 'system_bits': '32', 20:56:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:56:07 INFO - 'title': 'T-W732-IX-089', 20:56:07 INFO - 'use_talos_json': True, 20:56:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 20:56:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 20:56:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 20:56:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:56:07 INFO - 'work_dir': 'build'} 20:56:07 INFO - [mozharness: 2016-11-30 04:56:07.283000Z] Running clobber step. 20:56:07 INFO - Running pre-action listener: _resource_record_pre_action 20:56:07 INFO - Running main action method: clobber 20:56:07 INFO - rmtree: C:\slave\test\build 20:56:07 INFO - Using _rmtree_windows ... 20:56:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 20:58:17 INFO - Running post-action listener: _resource_record_post_action 20:58:17 INFO - [mozharness: 2016-11-30 04:58:17.817000Z] Finished clobber step (success) 20:58:17 INFO - [mozharness: 2016-11-30 04:58:17.817000Z] Running read-buildbot-config step. 20:58:17 INFO - Running pre-action listener: _resource_record_pre_action 20:58:17 INFO - Running main action method: read_buildbot_config 20:58:17 INFO - Using buildbot properties: 20:58:17 INFO - { 20:58:17 INFO - "project": "", 20:58:17 INFO - "product": "firefox", 20:58:17 INFO - "script_repo_revision": "production", 20:58:17 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 20:58:17 INFO - "repository": "", 20:58:17 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 20:58:17 INFO - "buildid": "20161129171714", 20:58:17 INFO - "stage_platform": "win32-pgo", 20:58:17 INFO - "basedir": "C:\\slave\\test", 20:58:17 INFO - "nightly_build": "True", 20:58:17 INFO - "buildnumber": 13, 20:58:17 INFO - "slavename": "t-w732-ix-089", 20:58:17 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 20:58:17 INFO - "platform": "win7_ix", 20:58:17 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 20:58:17 INFO - "branch": "mozilla-central", 20:58:17 INFO - "repo_path": "mozilla-central", 20:58:17 INFO - "revision": "adcc39e3cad0f32aba0efb478cc4a023a5dfc43f", 20:58:17 INFO - "pgo_build": "True", 20:58:17 INFO - "builduid": "4c697f9685534775b1330889cfcae254", 20:58:17 INFO - "slavebuilddir": "test" 20:58:17 INFO - } 20:58:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 20:58:17 INFO - Running post-action listener: _resource_record_post_action 20:58:17 INFO - [mozharness: 2016-11-30 04:58:17.820000Z] Finished read-buildbot-config step (success) 20:58:17 INFO - [mozharness: 2016-11-30 04:58:17.821000Z] Running download-and-extract step. 20:58:17 INFO - Running pre-action listener: _resource_record_pre_action 20:58:17 INFO - Running main action method: download_and_extract 20:58:17 INFO - mkdir: C:\slave\test\build\tests 20:58:17 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')]} 20:58:17 INFO - trying https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 20:58:17 INFO - Downloading https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 20:58:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 20:58:18 INFO - Downloaded 1218 bytes. 20:58:18 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 20:58:18 INFO - Using the following test package requirements: 20:58:18 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 20:58:18 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 20:58:18 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 20:58:18 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'jsshell-win32.zip'], 20:58:18 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 20:58:18 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 20:58:18 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 20:58:18 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 20:58:18 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 20:58:18 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 20:58:18 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 20:58:18 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 20:58:18 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 20:58:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 20:58:18 INFO - Fetch https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 20:58:19 INFO - Http code: 200 20:58:19 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 20:58:19 INFO - x-amz-cf-id: i_bS4wyes_0Jd6uWAucmpLHNLth4Y5rZItMLFtJ46FuCSiLQXfV1-g== 20:58:19 INFO - x-amz-version-id: 5iuiKLg0z9jIzddoFucruSlD0dGHvGdf 20:58:19 INFO - Expected file size: 16901208 20:58:19 INFO - Obtained file size: 16901208 20:58:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 20:58:46 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 20:58:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 20:58:46 INFO - Fetch https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 20:58:47 INFO - Http code: 200 20:58:47 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 20:58:47 INFO - x-amz-cf-id: C4SkIaInACPoPkuSo652ZQVHOfKohWpzYppdNxVHta_i_laEqQK0mg== 20:58:47 INFO - x-amz-version-id: 5iuiKLg0z9jIzddoFucruSlD0dGHvGdf 20:58:48 INFO - Expected file size: 16901208 20:58:48 INFO - Obtained file size: 16901208 20:58:59 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 20:58:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 20:58:59 INFO - Fetch https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 20:58:59 INFO - Http code: 200 20:58:59 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 20:58:59 INFO - x-amz-cf-id: gbhLwHgvjdA1DvVE7hYeZaj19PLqB2Ssv-lq1VedQooP-sBcYem3lg== 20:58:59 INFO - x-amz-version-id: hIw5NiMdNohvO3cJ.tPuexxxIXlaunwl 20:59:00 INFO - Expected file size: 14409702 20:59:00 INFO - Obtained file size: 14409702 20:59: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')]} 20:59:11 INFO - trying https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:59:11 INFO - Downloading https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 20:59:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 20:59:14 INFO - Downloaded 54685919 bytes. 20:59:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:59:14 INFO - mkdir: C:\slave\test\properties 20:59:14 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 20:59:14 INFO - Writing to file C:\slave\test\properties\build_url 20:59:14 INFO - Contents: 20:59:14 INFO - build_url:https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 20:59:14 INFO - Running post-action listener: _resource_record_post_action 20:59:14 INFO - Running post-action listener: set_extra_try_arguments 20:59:14 INFO - [mozharness: 2016-11-30 04:59:14.733000Z] Finished download-and-extract step (success) 20:59:14 INFO - [mozharness: 2016-11-30 04:59:14.733000Z] Running populate-webroot step. 20:59:14 INFO - Running pre-action listener: _resource_record_pre_action 20:59:14 INFO - Running main action method: populate_webroot 20:59:14 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 20:59:14 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 20:59:14 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 20:59:14 INFO - u'--tppagecycles', 20:59:14 INFO - u'7'], 20:59:14 INFO - u'tests': [u'tsvgm']}}, 20:59:14 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 20:59:14 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 20:59:14 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 20:59:14 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 20:59:14 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:59:14 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:59:14 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'damp', u'tps']}, 20:59:14 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'tests': [u'damp', u'tps']}, 20:59:14 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'dromaeo_dom']}, 20:59:14 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 20:59:14 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'basic_compositor_video']}, 20:59:14 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 20:59:14 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'other-e10s': {u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'a11yr', 20:59:14 INFO - u'ts_paint', 20:59:14 INFO - u'tpaint', 20:59:14 INFO - u'sessionrestore', 20:59:14 INFO - u'sessionrestore_no_auto_restore', 20:59:14 INFO - u'tabpaint']}, 20:59:14 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'tsvgx', 20:59:14 INFO - u'tsvgr_opacity', 20:59:14 INFO - u'tart', 20:59:14 INFO - u'tscrollx', 20:59:14 INFO - u'cart', 20:59:14 INFO - u'tsvg_static']}, 20:59:14 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 20:59:14 INFO - u'tsvgr_opacity', 20:59:14 INFO - u'tart', 20:59:14 INFO - u'tscrollx', 20:59:14 INFO - u'cart', 20:59:14 INFO - u'tsvg_static']}, 20:59:14 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'talos_options': [u'--disable-e10s'], 20:59:14 INFO - u'tests': [u'tp5o']}, 20:59:14 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'tests': [u'tp5o']}, 20:59:14 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'talos_options': [u'--disable-e10s', 20:59:14 INFO - u'--xperf_path', 20:59:14 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:14 INFO - u'tests': [u'tp5n']}, 20:59:14 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 20:59:14 INFO - u'talos_options': [u'--xperf_path', 20:59:14 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:14 INFO - u'tests': [u'tp5n']}}, 20:59:14 INFO - u'talos.zip': {u'path': u'', 20:59:14 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:59:14 INFO - Running post-action listener: _resource_record_post_action 20:59:14 INFO - [mozharness: 2016-11-30 04:59:14.786000Z] Finished populate-webroot step (success) 20:59:14 INFO - [mozharness: 2016-11-30 04:59:14.787000Z] Running create-virtualenv step. 20:59:14 INFO - Running pre-action listener: _resource_record_pre_action 20:59:14 INFO - Running main action method: create_virtualenv 20:59:14 INFO - Creating virtualenv C:\slave\test\build/venv 20:59:14 INFO - mkdir: C:\slave\test\build\venv\Scripts 20:59:14 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 20:59:14 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'! 20:59:14 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 20:59:14 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 20:59:24 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 20:59:28 INFO - Installing distribute......................................................................................................................................................................................done. 20:59:29 INFO - Return code: 0 20:59:29 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 20:59:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 20:59:30 INFO - Reading from file tmpfile_stdout 20:59:30 INFO - Output received: 20:59:30 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 20:59:30 INFO - Using _rmtree_windows ... 20:59:30 INFO - Using _rmtree_windows ... 20:59:30 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 20:59:30 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')]} 20:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:30 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')]} 20:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:30 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:30 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 20:59:30 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 20:59:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:30 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:30 INFO - 'DCLOCATION': 'SCL3', 20:59:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:30 INFO - 'HOMEDRIVE': 'C:', 20:59:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:30 INFO - 'KTS_VERSION': '1.19c', 20:59:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:30 INFO - 'MOZ_NO_REMOTE': '1', 20:59:30 INFO - 'NO_EM_RESTART': '1', 20:59:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:30 INFO - 'OS': 'Windows_NT', 20:59:30 INFO - 'OURDRIVE': 'C:', 20:59:30 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;', 20:59:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:30 INFO - 'PROCESSOR_LEVEL': '6', 20:59:30 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:30 INFO - 'PROMPT': '$P$G', 20:59:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:30 INFO - 'PWD': 'C:\\slave\\test', 20:59:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:30 INFO - 'SYSTEMDRIVE': 'C:', 20:59:30 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:30 INFO - 'TEST1': 'testie', 20:59:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:30 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:30 INFO - 'USERNAME': 'cltbld', 20:59:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:30 INFO - 'WINDIR': 'C:\\windows', 20:59:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:30 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:59:30 INFO - Downloading/unpacking pip>=1.5 20:59:35 INFO - Running setup.py egg_info for package pip 20:59:35 INFO - warning: no files found matching 'pip\cacert.pem' 20:59:35 INFO - warning: no files found matching '*.html' under directory 'docs' 20:59:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:59:35 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:59:35 INFO - Installing collected packages: pip 20:59:35 INFO - Found existing installation: pip 0.8.2 20:59:35 INFO - Uninstalling pip: 20:59:35 INFO - Successfully uninstalled pip 20:59:35 INFO - Running setup.py install for pip 20:59:35 INFO - warning: no files found matching 'pip\cacert.pem' 20:59:35 INFO - warning: no files found matching '*.html' under directory 'docs' 20:59:35 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:59:35 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:59:35 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 20:59:35 INFO - Successfully installed pip 20:59:35 INFO - Cleaning up... 20:59:35 INFO - Return code: 0 20:59:35 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 20:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:35 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:35 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 20:59:35 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 20:59:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:35 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:35 INFO - 'DCLOCATION': 'SCL3', 20:59:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:35 INFO - 'HOMEDRIVE': 'C:', 20:59:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:35 INFO - 'KTS_VERSION': '1.19c', 20:59:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:35 INFO - 'MOZ_NO_REMOTE': '1', 20:59:35 INFO - 'NO_EM_RESTART': '1', 20:59:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:35 INFO - 'OS': 'Windows_NT', 20:59:35 INFO - 'OURDRIVE': 'C:', 20:59:35 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;', 20:59:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:35 INFO - 'PROCESSOR_LEVEL': '6', 20:59:35 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:35 INFO - 'PROMPT': '$P$G', 20:59:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:35 INFO - 'PWD': 'C:\\slave\\test', 20:59:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:35 INFO - 'SYSTEMDRIVE': 'C:', 20:59:35 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:35 INFO - 'TEST1': 'testie', 20:59:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:35 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:35 INFO - 'USERNAME': 'cltbld', 20:59:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:35 INFO - 'WINDIR': 'C:\\windows', 20:59:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:37 INFO - Downloading/unpacking psutil>=3.1.1 20:59:37 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 20:59:37 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 20:59:38 INFO - Installing collected packages: psutil 20:59:38 INFO - Successfully installed psutil 20:59:38 INFO - Cleaning up... 20:59:38 INFO - Return code: 0 20:59:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 20:59:38 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')]} 20:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:38 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')]} 20:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:38 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:38 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 20:59:38 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 20:59:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:38 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:38 INFO - 'DCLOCATION': 'SCL3', 20:59:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:38 INFO - 'HOMEDRIVE': 'C:', 20:59:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:38 INFO - 'KTS_VERSION': '1.19c', 20:59:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:38 INFO - 'MOZ_NO_REMOTE': '1', 20:59:38 INFO - 'NO_EM_RESTART': '1', 20:59:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:38 INFO - 'OS': 'Windows_NT', 20:59:38 INFO - 'OURDRIVE': 'C:', 20:59:38 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;', 20:59:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:38 INFO - 'PROCESSOR_LEVEL': '6', 20:59:38 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:38 INFO - 'PROMPT': '$P$G', 20:59:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:38 INFO - 'PWD': 'C:\\slave\\test', 20:59:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:38 INFO - 'SYSTEMDRIVE': 'C:', 20:59:38 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:38 INFO - 'TEST1': 'testie', 20:59:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:38 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:38 INFO - 'USERNAME': 'cltbld', 20:59:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:38 INFO - 'WINDIR': 'C:\\windows', 20:59:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:59:39 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 20:59:39 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 20:59:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:59:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 20:59:40 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) 20:59:40 INFO - Installing collected packages: mozsystemmonitor 20:59:40 INFO - Running setup.py install for mozsystemmonitor 20:59:40 INFO - Successfully installed mozsystemmonitor 20:59:40 INFO - Cleaning up... 20:59:40 INFO - Return code: 0 20:59:40 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 20:59: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')]} 20:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59: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')]} 20:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59: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', '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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59: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', 'jsonschema==2.5.1'] in C:\slave\test\build 20:59: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 jsonschema==2.5.1 20:59:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:40 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:40 INFO - 'DCLOCATION': 'SCL3', 20:59:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:40 INFO - 'HOMEDRIVE': 'C:', 20:59:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:40 INFO - 'KTS_VERSION': '1.19c', 20:59:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:40 INFO - 'MOZ_NO_REMOTE': '1', 20:59:40 INFO - 'NO_EM_RESTART': '1', 20:59:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:40 INFO - 'OS': 'Windows_NT', 20:59:40 INFO - 'OURDRIVE': 'C:', 20:59: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;', 20:59:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:40 INFO - 'PROCESSOR_LEVEL': '6', 20:59:40 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:40 INFO - 'PROMPT': '$P$G', 20:59:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:40 INFO - 'PWD': 'C:\\slave\\test', 20:59:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:40 INFO - 'SYSTEMDRIVE': 'C:', 20:59:40 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:40 INFO - 'TEST1': 'testie', 20:59:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:40 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:40 INFO - 'USERNAME': 'cltbld', 20:59:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:40 INFO - 'WINDIR': 'C:\\windows', 20:59:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:41 INFO - Downloading/unpacking jsonschema==2.5.1 20:59:41 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 20:59:41 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 20:59:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:59:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:59:42 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 20:59:42 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 20:59:42 INFO - Downloading functools32-3.2.3-2.tar.gz 20:59:42 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 20:59:42 INFO - warning: no files found matching '*.txt' 20:59:42 INFO - no previously-included directories found matching 'build' 20:59:42 INFO - no previously-included directories found matching 'dist' 20:59:42 INFO - no previously-included directories found matching '.git*' 20:59:42 INFO - Installing collected packages: jsonschema, functools32 20:59:42 INFO - Running setup.py install for functools32 20:59:42 INFO - warning: no files found matching '*.txt' 20:59:42 INFO - no previously-included directories found matching 'build' 20:59:42 INFO - no previously-included directories found matching 'dist' 20:59:42 INFO - no previously-included directories found matching '.git*' 20:59:42 INFO - Successfully installed jsonschema functools32 20:59:42 INFO - Cleaning up... 20:59:43 INFO - Return code: 0 20:59:43 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 20:59:43 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')]} 20:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:43 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')]} 20:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:43 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:43 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 20:59:43 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 20:59:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:43 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:43 INFO - 'DCLOCATION': 'SCL3', 20:59:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:43 INFO - 'HOMEDRIVE': 'C:', 20:59:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:43 INFO - 'KTS_VERSION': '1.19c', 20:59:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:43 INFO - 'MOZ_NO_REMOTE': '1', 20:59:43 INFO - 'NO_EM_RESTART': '1', 20:59:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:43 INFO - 'OS': 'Windows_NT', 20:59:43 INFO - 'OURDRIVE': 'C:', 20:59:43 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;', 20:59:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:43 INFO - 'PROCESSOR_LEVEL': '6', 20:59:43 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:43 INFO - 'PROMPT': '$P$G', 20:59:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:43 INFO - 'PWD': 'C:\\slave\\test', 20:59:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:43 INFO - 'SYSTEMDRIVE': 'C:', 20:59:43 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:43 INFO - 'TEST1': 'testie', 20:59:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:43 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:43 INFO - 'USERNAME': 'cltbld', 20:59:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:43 INFO - 'WINDIR': 'C:\\windows', 20:59:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 20:59:43 INFO - Cleaning up... 20:59:43 INFO - Return code: 0 20:59:43 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 20:59:43 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')]} 20:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:43 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')]} 20:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:43 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:43 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 20:59:43 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 20:59:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:43 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:43 INFO - 'DCLOCATION': 'SCL3', 20:59:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:43 INFO - 'HOMEDRIVE': 'C:', 20:59:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:43 INFO - 'KTS_VERSION': '1.19c', 20:59:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:43 INFO - 'MOZ_NO_REMOTE': '1', 20:59:43 INFO - 'NO_EM_RESTART': '1', 20:59:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:43 INFO - 'OS': 'Windows_NT', 20:59:43 INFO - 'OURDRIVE': 'C:', 20:59:43 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;', 20:59:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:43 INFO - 'PROCESSOR_LEVEL': '6', 20:59:43 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:43 INFO - 'PROMPT': '$P$G', 20:59:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:43 INFO - 'PWD': 'C:\\slave\\test', 20:59:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:43 INFO - 'SYSTEMDRIVE': 'C:', 20:59:43 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:43 INFO - 'TEST1': 'testie', 20:59:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:43 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:43 INFO - 'USERNAME': 'cltbld', 20:59:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:43 INFO - 'WINDIR': 'C:\\windows', 20:59:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:43 INFO - Downloading/unpacking blobuploader==1.2.4 20:59:43 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 20:59:43 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 20:59:44 INFO - Downloading blobuploader-1.2.4.tar.gz 20:59:44 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 20:59:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:59:44 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 20:59:44 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 20:59:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 20:59:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:59:45 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 20:59:45 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 20:59:45 INFO - Downloading docopt-0.6.1.tar.gz 20:59:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 20:59:45 INFO - Installing collected packages: blobuploader, requests, docopt 20:59:45 INFO - Running setup.py install for blobuploader 20:59:45 INFO - Running setup.py install for requests 20:59:46 INFO - Running setup.py install for docopt 20:59:46 INFO - Successfully installed blobuploader requests docopt 20:59:46 INFO - Cleaning up... 20:59:48 INFO - Return code: 0 20:59:48 INFO - Installing None into virtualenv C:\slave\test\build/venv 20:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:48 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:48 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 20:59:48 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 20:59:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:48 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:48 INFO - 'DCLOCATION': 'SCL3', 20:59:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:48 INFO - 'HOMEDRIVE': 'C:', 20:59:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:48 INFO - 'KTS_VERSION': '1.19c', 20:59:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:48 INFO - 'MOZ_NO_REMOTE': '1', 20:59:48 INFO - 'NO_EM_RESTART': '1', 20:59:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:48 INFO - 'OS': 'Windows_NT', 20:59:48 INFO - 'OURDRIVE': 'C:', 20:59:48 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:59:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:48 INFO - 'PROCESSOR_LEVEL': '6', 20:59:48 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:48 INFO - 'PROMPT': '$P$G', 20:59:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:48 INFO - 'PWD': 'C:\\slave\\test', 20:59:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:48 INFO - 'SYSTEMDRIVE': 'C:', 20:59:48 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:48 INFO - 'TEST1': 'testie', 20:59:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:48 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:48 INFO - 'USERNAME': 'cltbld', 20:59:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:48 INFO - 'WINDIR': 'C:\\windows', 20:59:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 20:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ssn32g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 20:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 20:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fuhtpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 20:59:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 20:59:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-byk5r5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-blwaf_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-trj5a2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vatlir-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-anik4l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s1ptrz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hwn3bl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 20:59:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 20:59:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-usd_md-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4xw4y4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rbsvev-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6fhado-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hodqat-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hyehp3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f_iuzg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 20:59:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 20:59:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-glarhw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 20:59:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:59:51 INFO - Running setup.py install for manifestparser 20:59:51 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 20:59:51 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 20:59:51 INFO - Running setup.py install for mozcrash 20:59:52 INFO - Running setup.py install for mozdebug 20:59:52 INFO - Running setup.py install for mozdevice 20:59:52 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 20:59:52 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 20:59:52 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 20:59:52 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 20:59:52 INFO - Running setup.py install for mozfile 20:59:53 INFO - Running setup.py install for mozhttpd 20:59:53 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 20:59:53 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 20:59:53 INFO - Running setup.py install for mozinfo 20:59:53 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 20:59:53 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Running setup.py install for mozInstall 20:59:54 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 20:59:54 INFO - Running setup.py install for mozleak 20:59:54 INFO - Running setup.py install for mozlog 20:59:55 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 20:59:55 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 20:59:55 INFO - Running setup.py install for moznetwork 20:59:55 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 20:59:55 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 20:59:55 INFO - Running setup.py install for mozprocess 20:59:56 INFO - Running setup.py install for mozprofile 20:59:56 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 20:59:56 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 20:59:56 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 20:59:56 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 20:59:56 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 20:59:56 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 20:59:57 INFO - Running setup.py install for mozrunner 20:59:57 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 20:59:57 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 20:59:57 INFO - Running setup.py install for mozscreenshot 20:59:58 INFO - Running setup.py install for moztest 20:59:58 INFO - Running setup.py install for mozversion 20:59:58 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 20:59:58 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 20:59:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:59:59 INFO - Cleaning up... 20:59:59 INFO - Return code: 0 20:59:59 INFO - Installing None into virtualenv C:\slave\test\build/venv 20:59: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')]} 20:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59: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')]} 20:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:59 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', '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 20:59:59 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 20:59:59 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 20:59:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:59:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:59:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:59:59 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 20:59:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:59:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:59:59 INFO - 'DCLOCATION': 'SCL3', 20:59:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:59:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:59:59 INFO - 'HOMEDRIVE': 'C:', 20:59:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:59:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:59:59 INFO - 'KTS_VERSION': '1.19c', 20:59:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:59:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 20:59:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:59:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:59 INFO - 'MOZ_NO_REMOTE': '1', 20:59:59 INFO - 'NO_EM_RESTART': '1', 20:59:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:59:59 INFO - 'OS': 'Windows_NT', 20:59:59 INFO - 'OURDRIVE': 'C:', 20:59: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;', 20:59:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:59:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:59:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:59:59 INFO - 'PROCESSOR_LEVEL': '6', 20:59:59 INFO - 'PROCESSOR_REVISION': '1e05', 20:59:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:59:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:59:59 INFO - 'PROMPT': '$P$G', 20:59:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 20:59:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:59:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:59:59 INFO - 'PWD': 'C:\\slave\\test', 20:59:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:59:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:59:59 INFO - 'SYSTEMDRIVE': 'C:', 20:59:59 INFO - 'SYSTEMROOT': 'C:\\windows', 20:59:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:59 INFO - 'TEST1': 'testie', 20:59:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:59:59 INFO - 'USERDOMAIN': 'T-W732-IX-089', 20:59:59 INFO - 'USERNAME': 'cltbld', 20:59:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:59:59 INFO - 'WINDIR': 'C:\\windows', 20:59:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:59:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 20:59:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-amkeem-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 20:59:59 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)) 20:59:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 20:59:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hvlvlx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 20:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 20:59:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 20:59:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7m2bn4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 20:59:59 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)) 20:59:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 20:59:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-temhs7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l8jn8r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ylydxl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ocei72-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-igej8v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fq7eil-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r9gwnb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 21:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 21:00:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 21:00:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xrdjoy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_rdbwz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ln8ex-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-23dg8y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xqxyci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5wnsw3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 21:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 21:00:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 21:00:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ebopye-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 21:00:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 21:00:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 21:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:03 INFO - Downloading blessings-1.6.tar.gz 21:00:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 21:00:03 INFO - Installing collected packages: blessings 21:00:03 INFO - Running setup.py install for blessings 21:00:03 INFO - Successfully installed blessings 21:00:03 INFO - Cleaning up... 21:00:06 INFO - Return code: 0 21:00:06 INFO - Done creating virtualenv C:\slave\test\build/venv. 21:00:06 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:00:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:00:06 INFO - Reading from file tmpfile_stdout 21:00:06 INFO - Using _rmtree_windows ... 21:00:06 INFO - Using _rmtree_windows ... 21:00:06 INFO - Current package versions: 21:00:06 INFO - blessings == 1.6 21:00:06 INFO - blobuploader == 1.2.4 21:00:06 INFO - docopt == 0.6.1 21:00:06 INFO - functools32 == 3.2.3-2 21:00:06 INFO - jsonschema == 2.5.1 21:00:06 INFO - manifestparser == 1.1 21:00:06 INFO - mozInstall == 1.12 21:00:06 INFO - mozcrash == 1.0 21:00:06 INFO - mozdebug == 0.1 21:00:06 INFO - mozdevice == 0.49 21:00:06 INFO - mozfile == 1.2 21:00:06 INFO - mozhttpd == 0.7 21:00:06 INFO - mozinfo == 0.9 21:00:06 INFO - mozleak == 0.1 21:00:06 INFO - mozlog == 3.4 21:00:06 INFO - moznetwork == 0.27 21:00:06 INFO - mozprocess == 0.23 21:00:06 INFO - mozprofile == 0.28 21:00:06 INFO - mozrunner == 6.13 21:00:06 INFO - mozscreenshot == 0.1 21:00:06 INFO - mozsystemmonitor == 0.3 21:00:06 INFO - moztest == 0.7 21:00:06 INFO - mozversion == 1.4 21:00:06 INFO - psutil == 3.1.1 21:00:06 INFO - requests == 1.2.3 21:00:06 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:06 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:00:06 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 21:00:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:00:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:00:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:00:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:00:06 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 21:00:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:00:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:00:06 INFO - 'DCLOCATION': 'SCL3', 21:00:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:00:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:00:06 INFO - 'HOMEDRIVE': 'C:', 21:00:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:00:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:00:06 INFO - 'KTS_VERSION': '1.19c', 21:00:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:00:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 21:00:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:00:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:06 INFO - 'MOZ_NO_REMOTE': '1', 21:00:06 INFO - 'NO_EM_RESTART': '1', 21:00:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:00:06 INFO - 'OS': 'Windows_NT', 21:00:06 INFO - 'OURDRIVE': 'C:', 21:00:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:00:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:00:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:00:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:00:06 INFO - 'PROCESSOR_LEVEL': '6', 21:00:06 INFO - 'PROCESSOR_REVISION': '1e05', 21:00:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:00:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:00:06 INFO - 'PROMPT': '$P$G', 21:00:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:00:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:00:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:00:06 INFO - 'PWD': 'C:\\slave\\test', 21:00:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:00:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:00:06 INFO - 'SYSTEMDRIVE': 'C:', 21:00:06 INFO - 'SYSTEMROOT': 'C:\\windows', 21:00:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:06 INFO - 'TEST1': 'testie', 21:00:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:06 INFO - 'USERDOMAIN': 'T-W732-IX-089', 21:00:06 INFO - 'USERNAME': 'cltbld', 21:00:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:00:06 INFO - 'WINDIR': 'C:\\windows', 21:00:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:00:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 21:00:07 INFO - Cleaning up... 21:00:07 INFO - Return code: 0 21:00:07 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 21:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:07 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 0x01851640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F19020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017FB7C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-089', 'USERDOMAIN': 'T-W732-IX-089', '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-089', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 21:00:07 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 21:00:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:00:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:00:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:00:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:00:07 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 21:00:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:00:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:00:07 INFO - 'DCLOCATION': 'SCL3', 21:00:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:00:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:00:07 INFO - 'HOMEDRIVE': 'C:', 21:00:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:00:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:00:07 INFO - 'KTS_VERSION': '1.19c', 21:00:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:00:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 21:00:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:00:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:07 INFO - 'MOZ_NO_REMOTE': '1', 21:00:07 INFO - 'NO_EM_RESTART': '1', 21:00:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:00:07 INFO - 'OS': 'Windows_NT', 21:00:07 INFO - 'OURDRIVE': 'C:', 21:00:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:00:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:00:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:00:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:00:07 INFO - 'PROCESSOR_LEVEL': '6', 21:00:07 INFO - 'PROCESSOR_REVISION': '1e05', 21:00:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:00:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:00:07 INFO - 'PROMPT': '$P$G', 21:00:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:00:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:00:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:00:07 INFO - 'PWD': 'C:\\slave\\test', 21:00:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:00:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:00:07 INFO - 'SYSTEMDRIVE': 'C:', 21:00:07 INFO - 'SYSTEMROOT': 'C:\\windows', 21:00:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:07 INFO - 'TEST1': 'testie', 21:00:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:07 INFO - 'USERDOMAIN': 'T-W732-IX-089', 21:00:07 INFO - 'USERNAME': 'cltbld', 21:00:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:00:07 INFO - 'WINDIR': 'C:\\windows', 21:00:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:00:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 21:00:07 INFO - Cleaning up... 21:00:07 INFO - Return code: 0 21:00:07 INFO - Running post-action listener: _resource_record_post_action 21:00:07 INFO - Running post-action listener: _start_resource_monitoring 21:00:07 INFO - Starting resource monitoring. 21:00:07 INFO - [mozharness: 2016-11-30 05:00:07.771000Z] Finished create-virtualenv step (success) 21:00:07 INFO - [mozharness: 2016-11-30 05:00:07.771000Z] Running install step. 21:00:07 INFO - Running pre-action listener: _resource_record_pre_action 21:00:07 INFO - Running main action method: install 21:00:07 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:00:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:00:08 INFO - Reading from file tmpfile_stdout 21:00:08 INFO - Using _rmtree_windows ... 21:00:08 INFO - Using _rmtree_windows ... 21:00:08 INFO - Detecting whether we're running mozinstall >=1.0... 21:00:08 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 21:00:08 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 21:00:08 INFO - Reading from file tmpfile_stdout 21:00:08 INFO - Output received: 21:00:08 INFO - Usage: mozinstall-script.py [options] installer 21:00:08 INFO - Options: 21:00:08 INFO - -h, --help show this help message and exit 21:00:08 INFO - -d DEST, --destination=DEST 21:00:08 INFO - Directory to install application into. [default: 21:00:08 INFO - "C:\slave\test"] 21:00:08 INFO - --app=APP Application being installed. [default: firefox] 21:00:08 INFO - Using _rmtree_windows ... 21:00:08 INFO - Using _rmtree_windows ... 21:00:08 INFO - mkdir: C:\slave\test\build\application 21:00:08 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 21:00:08 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 21:00:10 INFO - Reading from file tmpfile_stdout 21:00:10 INFO - Output received: 21:00:10 INFO - C:\slave\test\build\application\firefox\firefox.exe 21:00:10 INFO - Using _rmtree_windows ... 21:00:10 INFO - Using _rmtree_windows ... 21:00:10 INFO - Running post-action listener: _resource_record_post_action 21:00:10 INFO - [mozharness: 2016-11-30 05:00:10.175000Z] Finished install step (success) 21:00:10 INFO - [mozharness: 2016-11-30 05:00:10.175000Z] Running run-tests step. 21:00:10 INFO - Running pre-action listener: _resource_record_pre_action 21:00:10 INFO - Running main action method: run_tests 21:00:10 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 21:00:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 21:00:10 INFO - Python 2.7.3 21:00:10 INFO - Return code: 0 21:00:10 INFO - grabbing minidump binary from tooltool 21:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:10 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 0x018C4E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C55C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 21:00:10 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 21:00:10 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 21:00:10 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 21:00:10 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 21:00:11 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpimagre 21:00:11 INFO - INFO - File integrity verified, renaming tmpimagre to win32-minidump_stackwalk.exe 21:00:11 INFO - Return code: 0 21:00:11 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 21:00:11 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 21:00:11 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 21:00:11 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 21:00:11 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 21:00:11 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 21:00:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-089 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 21:00:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:00:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:00:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:00:11 INFO - 'COMPUTERNAME': 'T-W732-IX-089', 21:00:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:00:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:00:11 INFO - 'DCLOCATION': 'SCL3', 21:00:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:00:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:00:11 INFO - 'HOMEDRIVE': 'C:', 21:00:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:00:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:00:11 INFO - 'KTS_VERSION': '1.19c', 21:00:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:00:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-089', 21:00:11 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:00:11 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 21:00:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:00:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:11 INFO - 'MOZ_NO_REMOTE': '1', 21:00:11 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:00:11 INFO - 'NO_EM_RESTART': '1', 21:00:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:00:11 INFO - 'OS': 'Windows_NT', 21:00:11 INFO - 'OURDRIVE': 'C:', 21:00: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;', 21:00:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:00:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:00:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:00:11 INFO - 'PROCESSOR_LEVEL': '6', 21:00:11 INFO - 'PROCESSOR_REVISION': '1e05', 21:00:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:00:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:00:11 INFO - 'PROMPT': '$P$G', 21:00:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:00:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:00:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:00:11 INFO - 'PWD': 'C:\\slave\\test', 21:00:11 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 21:00:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:00:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:00:11 INFO - 'SYSTEMDRIVE': 'C:', 21:00:11 INFO - 'SYSTEMROOT': 'C:\\windows', 21:00:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:11 INFO - 'TEST1': 'testie', 21:00:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:00:11 INFO - 'USERDOMAIN': 'T-W732-IX-089', 21:00:11 INFO - 'USERNAME': 'cltbld', 21:00:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:00:11 INFO - 'WINDIR': 'C:\\windows', 21:00:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:00:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:00:11 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 21:00:12 INFO - mozversion application_buildid: 20161129171714 21:00:12 INFO - mozversion application_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 21:00:12 INFO - mozversion application_display_name: Nightly 21:00:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:00:12 INFO - mozversion application_name: Firefox 21:00:12 INFO - mozversion application_remotingname: firefox 21:00:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:00:12 INFO - mozversion application_vendor: Mozilla 21:00:12 INFO - mozversion application_version: 53.0a1 21:00:12 INFO - mozversion platform_buildid: 20161129171714 21:00:12 INFO - mozversion platform_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 21:00:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:00:12 INFO - mozversion platform_version: 53.0a1 21:00:12 INFO - using testdate: 1480482012 21:00:12 INFO - actual date: 1480482012 21:00:12 INFO - starting webserver on 'localhost:49615' 21:00:12 INFO - SUITE-START | Running 2 tests 21:00:12 INFO - TEST-START | dromaeo_css 21:00:12 INFO - operating with platform_type : w7_ 21:00:12 INFO - Initialising browser for dromaeo_css test... 21:00:12 INFO - TEST-INFO | started process 1676 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpb61f5b\profile http://localhost:49615/getInfo.html) 21:00:20 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 21:00:20 INFO - PROCESS | 1676 | colorDepth:24 21:00:20 INFO - PROCESS | 1676 | Browser inner width/height: 1010/674 21:00:20 INFO - PROCESS | 1676 | __metrics 21:00:20 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:00:20 INFO - PROCESS | 1676 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:00:22 INFO - TEST-INFO | 1676: exit 0 21:00:22 INFO - Browser initialized. 21:00:22 INFO - Running cycle 1/1 for dromaeo_css test... 21:00:22 INFO - TEST-INFO | started process 816 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpb61f5b\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:00:22 INFO - PROCESS | 816 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 21:00:33 INFO - PROCESS | 816 | RSS: Main: 123236352 21:00:33 INFO - PROCESS | 816 | 21:01:24 INFO - PROCESS | 816 | 1480482084885 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:03:35 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49615/tests/dromaeo/cssquery-ext.html) 21:03:38 INFO - PROCESS | 816 | RSS: Main: 291926016 21:03:38 INFO - PROCESS | 816 | 21:06:40 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-ext.html (next: http://localhost:49615/tests/dromaeo/cssquery-jquery.html) 21:06:41 INFO - PROCESS | 816 | RSS: Main: 197435392 21:06:41 INFO - PROCESS | 816 | 21:09:43 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49615/tests/dromaeo/cssquery-mootools.html) 21:09:44 INFO - PROCESS | 816 | RSS: Main: 152788992 21:09:44 INFO - PROCESS | 816 | 21:12:46 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49615/tests/dromaeo/cssquery-prototype.html) 21:12:46 INFO - PROCESS | 816 | RSS: Main: 147619840 21:12:46 INFO - PROCESS | 816 | 21:15:49 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49615/tests/dromaeo/cssquery-yui.html) 21:15:50 INFO - PROCESS | 816 | RSS: Main: 133349376 21:15:50 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | Cycle 1(1): loaded http://localhost:49615/tests/dromaeo/cssquery-yui.html (next: http://localhost:49615/tests/dromaeo/cssquery-dojo.html) 21:18:53 INFO - PROCESS | 816 | RSS: Main: 217063424 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | __start_tp_report 21:18:53 INFO - PROCESS | 816 | _x_x_mozilla_page_load 21:18:53 INFO - PROCESS | 816 | _x_x_mozilla_page_load_details 21:18:53 INFO - PROCESS | 816 | |i|pagename|runs| 21:18:53 INFO - PROCESS | 816 | |0;dojo.html;2751.2296556964034;2771.1409463066443;2806.508860949334;2810.49411106001;2818.704036076213;4730.0776348612035;4731.8817029574275;4795.640326975474;4820.204666230068;4839.298301746249;4632.6988745731505;4650.953490465095;4709.152352576535;4742.099001189771;4832.323474713534;4152.979235103828;4165.83336666533;4168.937465937998;4209.97895010526;4217.261979153636;4101.138760860208;4154.293770059082;4199.0552125771765;4222.387753775701;4274.995376086617;4163.104932439525;4185.162967406531;4185.686073544481;4205.390218418327;4272.081502476967;4126.773027483488;4174.874753757392;4178.185253875496;4224.725392849455;4280.5291417944;3997.520297564311;4004.159126583421;4031.6169963853386;4145.937810932838;4226.809793559297;4715.646326525524;4775.617950563947;4782.30656554801;4789.784459699322;4808.134535783557;5021.17150670139;5041.9747901260625;5073.518015788494;5108.66793658411;5122.487751224885;4762.738049407316;4916.213405855046;4977.576905962997;4979.327790748239;5152.536271735525;4682.601978831837;4683.88290292742;4693.7653117344;4775.761211939389;4787.826982389267;5122.846314610568;5132.692038477703;5159.561437277798;5233.816816411408;5238.869028274324;4878.121938051114;4881.487443818348;4928.162212423897;4997.800087996439;5046.949530504648;4887.804487820447;4942.481039490802;4979.576735977483;5014.84955451337;5086.440491545927;4871.147549178951;4952.331435256195;5083.694978301313;5108.233764935289;5125.359330083739;5016.874578135503;5032.496750324938;5052.090623687698;5121.1550094234035;5132.615053870974;3281.9671803281663;3284.178955261185;3319.2199833039217;3343.1474973881504;3419.6580341965605;3049.2529330313773;3062.402831447891;3088.135322109813;3088.783785135064;3092.9793168254423;4636.211843986531;4722.527747225318;4724.338592597039;4732.053589282157;4756.048790241965;4789.185838407479;4839.249916262985;4947.727874966839;4974.9751251243515;5087.542121209109;4486.977565112154;4683.461401938753;4741.857744267678;4764.4282686077895;4773.856784296477;4123.195976784498;4234.597713217239;4242.512111107262;4251.256030194703;4255.106427650228;5033.5469807716745;5126;5138.254953031864;5147.97426012875;5196.45437229093;4916.7541622919425;4962.727050012284;5040.873978150576;5046.873828154326;5053.8736531587;5091.745412729423;5159.922601161061;5172.301739265151;5173.042987047408;5198.038362902875;4930.556249937523;4932.630052745986;4935.506449355035;4941.925871111865;4967.031428871335;4987.0524600325825;5032.943081952831;5040.823571174991;5043.99120175959;5054.519820617035;4697.882552936067;4736.147493451212;4927.778249978831;4944.629152813482;4948.084604348207;4886.731229782254;4930.630202734881;4937.35814344133;4939.382577177853;4960.727160006234;4801.063792560417;4820.060088282751;4823.27650852375;4915.3855768029;4920.803167873388;4815.253635686474;4861.173600487855;4922.753862306942;5020.322256495472;5035.0685123252315;4777.641676874317;4789.353437285922;4803.543663351976;4871.707697538159;4894 21:18:53 INFO - PROCESS | 816 | |1;ext.html;500.7195970256657;519.3119117169749;553.2834978702577;559.3120461831946;565.4684596479307;34596.4810527842;36052.819735901314;36558.08604784882;38615.227695446076;38753;35519.289614207766;36128.458073128924;36489.999999999935;36609.45085823715;36636.81681591588;6385.521085918552;6558.901616475757;7265.6367181640835;7659.846803063935;7733.45865789395;9205.631774729003;9399.483028433433;9540.33217674763;9748.658796942109;9773.364731292479;9567.856482152738;9652.034796520327;9686.46724430156;9700.417974921527;9701.417914925092;9299.349045566812;9469.14777670013;9479.431234125974;9486.525673716287;9499.24006079512;9346.626134954593;9403.717888463358;9407.905920940771;9412.529373531295;9416.435013899189;34512;34695.82652086723;34872;34971.47542786835;35019.649803501896;2852.343960888986;2878.294817769639;2936.7797415193945;3229.80621162731;3282.3271229397915;27226.863865680545;27783.86108069467;27986.3003424914;28036.579451308247;28095.719042809516;3324.1523411529906;3343.3981883261004;3363.9663603363897;3414.3854106260865;3484.390231709466;323953;328725;329647.090584581;331619;331816.9999999976;48744.7562762191;48851.51148488469;48932.75533622309;49258.50741492539;49303.753481233085;49915;50036;50211.74894125506;50223;50314.49685503171;12949;13292.867071329356;13336.599902002956;13353.46586136563;13371.398287077105;8725.432846864933;8932.330075244383;9777.560009799434;9926.851097233548;10041.447720375303;15094.39622415113;15104.697906041818;15118.395264189528;15210.847891520943;15222.391104355924;5430.42980487051;5434.021876062347;5438.864028399322;5468.699221542854;5480.177973303957;721.0554174032035;772.1159272632804;773.4585789947059;795.3041089047084;876.4741155306765;25718.74281257201;26208.60687089695;26458;26623.33441663974;26662.466750665266;810.9513429194267;852.552409984763;875.0811647769816;928.6053427293373;941.8634298026748;13491;13549.187048777105;13677.712962639598;13754.105983111065;13896.374663140183;287283;420826;436303;440203;445752;39447;39846.800765995984;44569.55430445719;44847.77576112164;44967.55032449699;994.6966175316453;1015.669907280137;1037.569408695383;1041.5937784264142;1044.060345688871;26064.73935260623;26299.342516437242;26598;26919.461610768067;27008.1897543074;41490;43474.782626086664;43905.78047109744;43906.56093439153;43936.34095488506;373918;439555;458061;470101.14443705924;470134;35696.464553031205;36129.638703612625;36692.63307366999;37174.25651486901;37214.255714886094;38371;40698.59301406948;40758.59241407548;41199.1760164801;41388.379174311805;32521.186970325933;33712.66287337095;35156;35482.29035419329;35612.64387356093;32696.673033269362;34847.825760871034;35784;36182.638173617925;36316.455253170694;31922;32374.676253238107;32977.505337419476;33103;33292.50061249132;29827.929252027643;30426.54360184643;31418.52872206873;32212;32250.35499289954;22982.310530684106;23890;24279;24282.87858560696;24645 21:18:53 INFO - PROCESS | 816 | |2;jquery.html;293.205413329876;314.4355881193258;315.77895473168786;317.45873286047276;320.75622526879573;12313.63059108227;12365.13444058915;12591.307478088702;12701;12852.93573532131;12048.51805927764;12419.813702794465;13035.674108147301;13041.152325098861;13056.869431305684;4763.999999999991;5037.017781532584;5117.948820511784;5121;5130.563902068328;4938.580220681247;5007.799688012475;5080.974595127019;5092.592592592584;5195.298634684326;3709.610490898457;5002.24966255061;5059.392872855263;5073.0361231366105;5074.162763144077;3917.9608203918024;5657.915131273035;5776.9422305776825;5787.784565241304;5984.97007514964;5139.280500729901;5145.228215767627;5155;5164.664296820694;5171.22431635254;20430.489237769027;20467.385978420672;20685.58628827415;21057.368278951657;21074.367768966957;1733.7239194117376;1757.0336315026684;1770.5927636643644;1770.9911450442794;1784.3843873863625;12918.160319579198;13037.86962130376;13152.934235328858;13186.274754888478;13459.259740714355;1560.3524537317;1702.7360759082237;1737.1574786228668;1749.623830876355;1765.9293628254854;80011.59994199991;80724;81855;81948.18051819465;82581.58709206476;8700.303975682056;8706.608202630898;8966.23786978101;9026.323025773094;9593;11422.31466112036;11735.823962640567;11739.941300293443;11853.111016673784;12079.939600301943;6795.524313298021;6886.24251332353;6991.440684745208;7032.6132062736015;7059.647017649097;6975.209916033627;6997.440204783605;7007.369336759716;7063.457155424043;7090.92909070913;5594;5696.803671229006;5730.656160630376;5737.08206686928;5774.335951365562;1551.1933794426834;1552.5031989763168;1559.22818204987;1574.795276614033;1590.9284082216327;294.8687833913888;296.9747571456407;306.75919403268773;700.5656492974389;819.9754007379788;8730.432521886058;9733.464659443798;9783.902160978441;9923.568725662259;10046.296759226783;348.8813803306888;349.3414912889222;353.20528810177103;358.3692700846529;362.1543695471052;5118.10433174193;5124.666896651705;5137.794488220429;5198.948010519922;5250.291210686585;441823;448648;456745;458402;460712;94309;95801;98716.23107840514;99995;102039;847.3814115695577;852.9872051919226;856.9357298202659;863.5984267315729;884.2969838978028;15897.841021589637;15912.283947222635;16091.678166436372;16335.673286533965;16560.75158872642;44034.33948490711;44602.77698611486;44621.55378446174;45168;45261.77369113133;57618.13572796327;58062.7096864513;61603.38396615977;61695.6915215421;61980;6262.342454042442;6572.638504882278;6574.408303252731;6687.966560167168;6695.832604184934;10634;12290.385480726107;12856.55002074923;12877.420516076612;13103.934480327918;9726.902730972883;9886.406815591088;9899;9922.652707155215;10094.394336339843;10584.312019718695;10650;10844.728881778019;10858;11005.834912476159;9626.229901607714;9960.252981026308;10068.848967265643;10109.49452527356;10584.89415105839;7491.775246742606;7531.962340188264;7536.962315188389;7543.622818858926;7558.470907036454;7057.0472986146215;9047.095290470901;9909.45497997617;9917.65288214909;10228.744281393025 21:18:53 INFO - PROCESS | 816 | |3;mootools.html;1092.923495355325;1096.0738176241073;1160.112513926846;1302.6743314171451;1395.76969799983;6234.9999999999945;6570.31011743766;6581.276059633443;6627.469802415807;6632.933670663292;5807.30312362516;5995.700214989244;6349.365063493648;6466.385693359135;6482.254540727818;1761.2074566445087;1770.3184274054518;1812.7734033245845;1817.5365281853176;1821.3807305516123;1782.2603619497893;1789.8120697326788;1797.9122630808304;1816.2553353125222;1820.8907465552043;1567.0754254989554;1629.445988363956;1740.4343588333843;1830.926762929488;1830.9725354119698;1819.172276614144;1838.8069252728537;1840.5398650337415;1852.2498388152685;1853.0364210610542;1822.261983896524;1829.048894574827;1837.825406586372;1841.9079046047643;1842.0973722875694;6193.1329613854095;6505.41451269388;6519.608823470559;6522.902156467657;6528.249251336109;3941.6649584785328;3994.081361286891;4032.0927791246736;4039.575844536311;4047.8583249586122;2603.1149409200775;2628.4348864994117;2658.720834312408;2765.806393552452;2771.251762024242;874.3049275825733;876.2683159561774;879.116487929633;880.8062226310266;907.5144797533354;8542.530160841152;8545.700900468453;8552;8574.928133983252;8592.441491303045;3384.2385463270566;3502.984134600937;3630.2557975614936;3650.379435495972;3652.7990960496895;7337.229590892986;7357.190709022003;7364.926350736531;7366.963165184247;7371.594562299125;3952.505936757905;4009.258287216874;4052.068024354415;4065.329220678555;4066;3747.0819649186124;3751.9869635198343;3757.267332870119;3760.4923335349927;3763.2661630982243;2903.274181454636;2947.6610189828016;2972.687867773896;3034.180771191766;3083.290843106053;968.9321747477609;971.5482300730195;978.5449765858767;979.6620166042704;989.475577943691;727.7562016724456;739.6412739821183;747.7532414303267;769.2461387839949;774.5972094510823;4472.843450479218;4478.955210447919;4543.522930092358;4630.583247507673;4722.268048452495;341.5969156395477;352.67201502602825;353.2475826489561;354.8119496666771;356.2429836597231;3162.8892988745743;3216.630087539916;3241.951370729441;3243.659415761358;3245.3184831185245;7678.347340476123;7739.535627862347;7834.647440865175;7897.60511974399;7928.6828526859435;7840.215978402114;7841.921580784233;7843.372530197685;7844.37245020397;7846.686132554748;6123.387661233841;6176.66028368435;6203.472704820141;6213.844653883689;6217.782377616762;4451.7996690148375;4472.865814025585;4505.6395488360195;4549.226631472724;4632.814687412466;7375.336219740249;7401.925980740124;7444.181140074696;7484.32641062307;7487.363574096263;7348.669309881175;7465;7541.924580754122;7580.583067931317;7593.240675932362;6257.217847769029;6289.96855015722;6355.586886852339;6381.425671689384;6382.712777925098;6261.843453913689;6381.3937675920715;6402.167718196604;6466.612003279818;6473.611583305017;4826.396700412449;5789.884202316014;5901.793437229677;5910.083936989773;5963.7912673056235;5655.462731040545;5810.767569297181;5881.882362352643;5924.022536281465;5930.466258036798;5410.594205434687;5457.454254574511;5460.972695136499;5471.425500322568;5504.119340905436;3931.8623848165175;3994.9201015980098;4057.3102572562143;4072.2873497138476;4137.8758637240935;3949.3878448840474;3974.9205015900097;4027.154297597537;4030.556638769792;4085.5710150434966 21:18:53 INFO - PROCESS | 816 | |4;prototype.html;256.2210878928059;256.24152508574616;263.865428631398;264.04416014029215;264.6969220242822;5004.424491183515;5141.383034035921;5174.172132458807;5180.145276029455;5220.765065572054;4974.37820272466;5035.874103147414;5059.873503162423;5061.924071138927;5116.948830511694;3738.74751958093;3742.906427339311;3745.382011968029;3747.6252374762444;3753.7184711146638;3600.189957259609;3749.9812500937455;3757.1734218471893;3760.1163726524246;3764.8305826237884;3613.765105268162;3622.1487950331652;3697.0942119180704;3724.1806802503406;3740.8777366789855;3707.1658876752786;3723.757955732896;3724.571674257441;3734.346489364364;3746.3818469952425;3193.5209718542405;3211.775175737699;3217.324361884007;3230.305484320859;3239.562659041023;4954.504549545053;4979.925301120486;4992.450830408652;4995.975020124876;5009.974950125263;4130.524989626201;4276.738362183163;4323.156984388045;4402.493713222956;4406.294992801137;5289.497497737709;5304.177852432879;5313.459096861905;5318.22885681574;5329;3467.878624248139;3503.7722548034294;3514.68367846895;3525.2068284636007;3528.6118526962014;5581.665100094007;5589.552835773128;5594.160875868612;5601.523870471015;5623.7469313880565;3395.624771967357;3547.6452354764315;3559.5728512578658;3563;3568.910777230538;3552.3073000764603;3607.47691584724;3611.2416392557334;3612.9277414451562;3623.311570801592;4254.553271906467;4264.616184543406;4267.317229243306;4268.935965960513;4287.09970906107;5352.973235133877;5369.4362091980565;5378.542823860016;5379.838604841861;5389.51494365509;1219.7926352520092;1225.718084840474;1228.3980849383738;1231.34123244064;1234.327291626066;764.5450956680766;777.5723352156291;841.7937605286621;854.3763052971366;862.3963225742006;4767.380240568704;4865.489123641966;4899.681520701143;4925.482824303468;4941.45643979162;5265.973670131625;5274.630775845731;5275.314209152785;5353.785848566092;5355.571554275649;4879.170541008037;4935.062338155739;4945.67853089548;4947.925781113287;4950.331705219749;2698.2175169201105;2869.8852045918347;2874.856257187132;2875.353045564773;2902.201894478993;5594.440555944373;5602.915956260659;5603.719814009365;5612.326520817528;5626.409227031185;5446.291982042389;5532.2891783026025;5549.361823390281;5571.275734154534;5606.187102870061;5469.753861076181;5618.634788738719;5648.406917273709;5757.445489717753;5773.451522105562;5565.721713914207;5580.581456390706;5586.553075753849;5595.524380427709;5602.9719851402115;5416.83749487516;5508.859949828559;5516.751746171351;5520.779168833202;5529.30883639545;5642.322921249476;5661.166170720246;5750.741216645345;5778.768849246151;5786.161006654096;3575.946360804642;3616.4575313703153;3616.788375894105;3619.9276014480088;3620.05878471594;5533.889322213453;5565.914646643865;5568.888622227614;5572.609917305912;5593.9440605595055;5402.945970540244;5416.373059066271;5431.728413579226;5436.281859070465;5449.591280654046;5252.947470525246;5381.300430943952;5395.86510337229;5397.622166448311;5430.755615997211;5275.419703832652;5277.709725965109;5281.313429254173;5289.20661900718;5323.228131920927;5236.769359200509;5332.5733941283825;5348.545373643284;5411.945880541144;5425.077736784677;5190.7801666609075;5332.706701131475;5339.839804805862;5342.8931421372135;5351.277577527139 21:18:53 INFO - PROCESS | 816 | |5;yui.html;555.0342404216661;563.1440210879466;596.2189531713456;608.2305883057932;628.403017133723;14082.57752267431;14831.110133392003;14844.40622375105;14912.732417744504;14922.3284952177;14579.343929523171;14946.953713240078;14976.25118744064;15066.39734410625;15151.848481515182;463.01840098990186;549.3517649173973;554.9944500554983;555.4112640600956;560.529155509372;474.5373261070446;536.2706718862344;559.3679142568911;565.9377468478464;566.2299272988732;589.1339730596017;594.456072693485;598.7485256192409;600.6066026752462;600.6366148480172;480.1165854827135;585.5842351930135;588.2206076948039;598.5570677698494;599.2269971736498;357.10188874979445;409.5433591545435;426.1774774684857;426.79300539238443;429.6670080687461;12906.419211135522;13233.602991910258;13272.469101235938;13299.268540230283;13389;12189.146759726822;12189.329586872718;12473.189242699285;12641.620751377473;12814.359282035954;12534.937325313314;12733.426995785212;12741.490340386374;12766.23402595838;12773.23360598367;4079.2657321682086;4104.856330028435;4109.219248342805;4152.314868046768;4186.776301416102;77140;80852.59573702153;81356.59321703413;82498.58750706208;83076.58461707713;9699.466529340812;9798.510074496247;9862.901370986197;9882.851757223647;9975.002499749968;17500.64998700019;17579.472615821545;17587.384441544742;17652.735208971877;17707.46877593674;6796.76211332601;6841.555298905555;6900.378965892994;6903.723851046002;6984.546004509691;3662.597114317423;3677.356462619031;3750.081230098643;3766.114962983697;3859.2667393195766;2677.705452400234;2732.3169207698074;2795.371041515643;2817.1689351641317;2819.9859000704864;499.5504046358234;503.0191127301776;509.6228790694859;510.10985829727366;510.9795608175706;492.42878261216816;507.3252574076543;509.0480800902336;550.8209831804679;651.1144843013498;8570.142985701379;8614.95692521546;8652.00574208461;8759.861218041613;8929.285657147413;542.6527022705471;556.4491153757832;576.4869266352949;620.8758396383284;622.2688341199073;4262.190183865056;4283.443152390169;4295.570442955742;4352.260115780325;4412.757298348557;79704.6014769934;80032;80667.59666201631;81376.59311703403;81540.59229703814;66723.66638166875;68370.65814670894;68791.3120868795;69652;71027.64486177536;65899;66114;66149.6692516544;66268.00597991033;66355.00467492992;7925.682972681028;8346.791330216793;8410.789730256793;8672.089430609694;8742;44962;45509.31736023896;45626.315605265285;46127.308090378;47089;55854.44145558492;58352.70823646025;59224.40775592361;59858;59911.700441497516;10171.389716617026;10376.377417354983;10440.008199221062;10515.842262365917;11683.88316116828;13115.681080920225;14234.430622774973;14462.566123016326;14662;15138.545843624708;11466.31202127875;12019.158658893793;13175.407106680032;13313.068085234328;13365.131266467648;12929.418176181905;13033.543825966046;13195.274259915797;13250.950192028367;14108.647283817576;13498.257595832323;13845.100068495516;13858.4456621734;13897.305134743021;14067.155970641794;10471.633492827714;11090.334579925231;11583.710407239549;11617.302961822317;12173.939130304292;11024.834627480755;11477.196596238184;11626.53493860236;11638;11656.18406711522 21:18:53 INFO - PROCESS | 816 | __end_tp_report 21:18:53 INFO - PROCESS | 816 | __start_cc_report 21:18:53 INFO - PROCESS | 816 | _x_x_mozilla_cycle_collect,2418 21:18:53 INFO - PROCESS | 816 | __end_cc_report 21:18:53 INFO - PROCESS | 816 | __startTimestamp1480483133520__endTimestamp 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | ------- Summary: start ------- 21:18:53 INFO - PROCESS | 816 | Number of tests: 6 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#0] dojo.html Cycles:165 Average:4621.17 Median:4820.20 stddev:590.36 (12.2%) stddev-sans-first:573.65 21:18:53 INFO - PROCESS | 816 | Values: 2751.2 2771.1 2806.5 2810.5 2818.7 4730.1 4731.9 4795.6 4820.2 4839.3 4632.7 4651.0 4709.2 4742.1 4832.3 4153.0 4165.8 4168.9 4210.0 4217.3 4101.1 4154.3 4199.1 4222.4 4275.0 4163.1 4185.2 4185.7 4205.4 4272.1 4126.8 4174.9 4178.2 4224.7 4280.5 3997.5 4004.2 4031.6 4145.9 4226.8 4715.6 4775.6 4782.3 4789.8 4808.1 5021.2 5042.0 5073.5 5108.7 5122.5 4762.7 4916.2 4977.6 4979.3 5152.5 4682.6 4683.9 4693.8 4775.8 4787.8 5122.8 5132.7 5159.6 5233.8 5238.9 4878.1 4881.5 4928.2 4997.8 5046.9 4887.8 4942.5 4979.6 5014.8 5086.4 4871.1 4952.3 5083.7 5108.2 5125.4 5016.9 5032.5 5052.1 5121.2 5132.6 3282.0 3284.2 3319.2 3343.1 3419.7 3049.3 3062.4 3088.1 3088.8 3093.0 4636.2 4722.5 4724.3 4732.1 4756.0 4789.2 4839.2 4947.7 4975.0 5087.5 4487.0 4683.5 4741.9 4764.4 4773.9 4123.2 4234.6 4242.5 4251.3 4255.1 5033.5 5126.0 5138.3 5148.0 5196.5 4916.8 4962.7 5040.9 5046.9 5053.9 5091.7 5159.9 5172.3 5173.0 5198.0 4930.6 4932.6 4935.5 4941.9 4967.0 4987.1 5032.9 5040.8 5044.0 5054.5 4697.9 4736.1 4927.8 4944.6 4948.1 4886.7 4930.6 4937.4 4939.4 4960.7 4801.1 4820.1 4823.3 4915.4 4920.8 4815.3 4861.2 4922.8 5020.3 5035.1 4777.6 4789.4 4803.5 4871.7 4894.0 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#1] ext.html Cycles:180 Average:52724.94 Median:36664.72 stddev:105387.84 (287.4%) stddev-sans-first:105610.53 21:18:53 INFO - PROCESS | 816 | Values: 500.7 519.3 553.3 559.3 565.5 34596.5 36052.8 36558.1 38615.2 38753.0 35519.3 36128.5 36490.0 36609.5 36636.8 6385.5 6558.9 7265.6 7659.8 7733.5 9205.6 9399.5 9540.3 9748.7 9773.4 9567.9 9652.0 9686.5 9700.4 9701.4 9299.3 9469.1 9479.4 9486.5 9499.2 9346.6 9403.7 9407.9 9412.5 9416.4 34512.0 34695.8 34872.0 34971.5 35019.6 2852.3 2878.3 2936.8 3229.8 3282.3 27226.9 27783.9 27986.3 28036.6 28095.7 3324.2 3343.4 3364.0 3414.4 3484.4 323953.0 328725.0 329647.1 331619.0 331817.0 48744.8 48851.5 48932.8 49258.5 49303.8 49915.0 50036.0 50211.7 50223.0 50314.5 12949.0 13292.9 13336.6 13353.5 13371.4 8725.4 8932.3 9777.6 9926.9 10041.4 15094.4 15104.7 15118.4 15210.8 15222.4 5430.4 5434.0 5438.9 5468.7 5480.2 721.1 772.1 773.5 795.3 876.5 25718.7 26208.6 26458.0 26623.3 26662.5 811.0 852.6 875.1 928.6 941.9 13491.0 13549.2 13677.7 13754.1 13896.4 287283.0 420826.0 436303.0 440203.0 445752.0 39447.0 39846.8 44569.6 44847.8 44967.6 994.7 1015.7 1037.6 1041.6 1044.1 26064.7 26299.3 26598.0 26919.5 27008.2 41490.0 43474.8 43905.8 43906.6 43936.3 373918.0 439555.0 458061.0 470101.1 470134.0 35696.5 36129.6 36692.6 37174.3 37214.3 38371.0 40698.6 40758.6 41199.2 41388.4 32521.2 33712.7 35156.0 35482.3 35612.6 32696.7 34847.8 35784.0 36182.6 36316.5 31922.0 32374.7 32977.5 33103.0 33292.5 29827.9 30426.5 31418.5 32212.0 32250.4 22982.3 23890.0 24279.0 24282.9 24645.0 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#2] jquery.html Cycles:180 Average:26870.45 Median:5022.41 stddev:75461.67 (1502.5%) stddev-sans-first:75646.97 21:18:53 INFO - PROCESS | 816 | Values: 293.2 314.4 315.8 317.5 320.8 12313.6 12365.1 12591.3 12701.0 12852.9 12048.5 12419.8 13035.7 13041.2 13056.9 4764.0 5037.0 5117.9 5121.0 5130.6 4938.6 5007.8 5081.0 5092.6 5195.3 3709.6 5002.2 5059.4 5073.0 5074.2 3918.0 5657.9 5776.9 5787.8 5985.0 5139.3 5145.2 5155.0 5164.7 5171.2 20430.5 20467.4 20685.6 21057.4 21074.4 1733.7 1757.0 1770.6 1771.0 1784.4 12918.2 13037.9 13152.9 13186.3 13459.3 1560.4 1702.7 1737.2 1749.6 1765.9 80011.6 80724.0 81855.0 81948.2 82581.6 8700.3 8706.6 8966.2 9026.3 9593.0 11422.3 11735.8 11739.9 11853.1 12079.9 6795.5 6886.2 6991.4 7032.6 7059.6 6975.2 6997.4 7007.4 7063.5 7090.9 5594.0 5696.8 5730.7 5737.1 5774.3 1551.2 1552.5 1559.2 1574.8 1590.9 294.9 297.0 306.8 700.6 820.0 8730.4 9733.5 9783.9 9923.6 10046.3 348.9 349.3 353.2 358.4 362.2 5118.1 5124.7 5137.8 5198.9 5250.3 441823.0 448648.0 456745.0 458402.0 460712.0 94309.0 95801.0 98716.2 99995.0 102039.0 847.4 853.0 856.9 863.6 884.3 15897.8 15912.3 16091.7 16335.7 16560.8 44034.3 44602.8 44621.6 45168.0 45261.8 57618.1 58062.7 61603.4 61695.7 61980.0 6262.3 6572.6 6574.4 6688.0 6695.8 10634.0 12290.4 12856.6 12877.4 13103.9 9726.9 9886.4 9899.0 9922.7 10094.4 10584.3 10650.0 10844.7 10858.0 11005.8 9626.2 9960.3 10068.8 10109.5 10584.9 7491.8 7532.0 7537.0 7543.6 7558.5 7057.0 9047.1 9909.5 9917.7 10228.7 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#3] mootools.html Cycles:180 Average:4338.71 Median:5118.50 stddev:2377.87 (46.5%) stddev-sans-first:2372.02 21:18:53 INFO - PROCESS | 816 | Values: 1092.9 1096.1 1160.1 1302.7 1395.8 6235.0 6570.3 6581.3 6627.5 6632.9 5807.3 5995.7 6349.4 6466.4 6482.3 1761.2 1770.3 1812.8 1817.5 1821.4 1782.3 1789.8 1797.9 1816.3 1820.9 1567.1 1629.4 1740.4 1830.9 1831.0 1819.2 1838.8 1840.5 1852.2 1853.0 1822.3 1829.0 1837.8 1841.9 1842.1 6193.1 6505.4 6519.6 6522.9 6528.2 3941.7 3994.1 4032.1 4039.6 4047.9 2603.1 2628.4 2658.7 2765.8 2771.3 874.3 876.3 879.1 880.8 907.5 8542.5 8545.7 8552.0 8574.9 8592.4 3384.2 3503.0 3630.3 3650.4 3652.8 7337.2 7357.2 7364.9 7367.0 7371.6 3952.5 4009.3 4052.1 4065.3 4066.0 3747.1 3752.0 3757.3 3760.5 3763.3 2903.3 2947.7 2972.7 3034.2 3083.3 968.9 971.5 978.5 979.7 989.5 727.8 739.6 747.8 769.2 774.6 4472.8 4479.0 4543.5 4630.6 4722.3 341.6 352.7 353.2 354.8 356.2 3162.9 3216.6 3242.0 3243.7 3245.3 7678.3 7739.5 7834.6 7897.6 7928.7 7840.2 7841.9 7843.4 7844.4 7846.7 6123.4 6176.7 6203.5 6213.8 6217.8 4451.8 4472.9 4505.6 4549.2 4632.8 7375.3 7401.9 7444.2 7484.3 7487.4 7348.7 7465.0 7541.9 7580.6 7593.2 6257.2 6290.0 6355.6 6381.4 6382.7 6261.8 6381.4 6402.2 6466.6 6473.6 4826.4 5789.9 5901.8 5910.1 5963.8 5655.5 5810.8 5881.9 5924.0 5930.5 5410.6 5457.5 5461.0 5471.4 5504.1 3931.9 3994.9 4057.3 4072.3 4137.9 3949.4 3974.9 4027.2 4030.6 4085.6 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#4] prototype.html Cycles:180 Average:4405.07 Median:5129.17 stddev:1388.86 (27.1%) stddev-sans-first:1357.39 21:18:53 INFO - PROCESS | 816 | Values: 256.2 256.2 263.9 264.0 264.7 5004.4 5141.4 5174.2 5180.1 5220.8 4974.4 5035.9 5059.9 5061.9 5116.9 3738.7 3742.9 3745.4 3747.6 3753.7 3600.2 3750.0 3757.2 3760.1 3764.8 3613.8 3622.1 3697.1 3724.2 3740.9 3707.2 3723.8 3724.6 3734.3 3746.4 3193.5 3211.8 3217.3 3230.3 3239.6 4954.5 4979.9 4992.5 4996.0 5010.0 4130.5 4276.7 4323.2 4402.5 4406.3 5289.5 5304.2 5313.5 5318.2 5329.0 3467.9 3503.8 3514.7 3525.2 3528.6 5581.7 5589.6 5594.2 5601.5 5623.7 3395.6 3547.6 3559.6 3563.0 3568.9 3552.3 3607.5 3611.2 3612.9 3623.3 4254.6 4264.6 4267.3 4268.9 4287.1 5353.0 5369.4 5378.5 5379.8 5389.5 1219.8 1225.7 1228.4 1231.3 1234.3 764.5 777.6 841.8 854.4 862.4 4767.4 4865.5 4899.7 4925.5 4941.5 5266.0 5274.6 5275.3 5353.8 5355.6 4879.2 4935.1 4945.7 4947.9 4950.3 2698.2 2869.9 2874.9 2875.4 2902.2 5594.4 5602.9 5603.7 5612.3 5626.4 5446.3 5532.3 5549.4 5571.3 5606.2 5469.8 5618.6 5648.4 5757.4 5773.5 5565.7 5580.6 5586.6 5595.5 5603.0 5416.8 5508.9 5516.8 5520.8 5529.3 5642.3 5661.2 5750.7 5778.8 5786.2 3575.9 3616.5 3616.8 3619.9 3620.1 5533.9 5565.9 5568.9 5572.6 5593.9 5402.9 5416.4 5431.7 5436.3 5449.6 5252.9 5381.3 5395.9 5397.6 5430.8 5275.4 5277.7 5281.3 5289.2 5323.2 5236.8 5332.6 5348.5 5411.9 5425.1 5190.8 5332.7 5339.8 5342.9 5351.3 21:18:53 INFO - PROCESS | 816 | 21:18:53 INFO - PROCESS | 816 | [#5] yui.html Cycles:180 Average:17450.25 Median:45235.66 stddev:23329.03 (51.6%) stddev-sans-first:23359.98 21:18:53 INFO - PROCESS | 816 | Values: 555.0 563.1 596.2 608.2 628.4 14082.6 14831.1 14844.4 14912.7 14922.3 14579.3 14947.0 14976.3 15066.4 15151.8 463.0 549.4 555.0 555.4 560.5 474.5 536.3 559.4 565.9 566.2 589.1 594.5 598.7 600.6 600.6 480.1 585.6 588.2 598.6 599.2 357.1 409.5 426.2 426.8 429.7 12906.4 13233.6 13272.5 13299.3 13389.0 12189.1 12189.3 12473.2 12641.6 12814.4 12534.9 12733.4 12741.5 12766.2 12773.2 4079.3 4104.9 4109.2 4152.3 4186.8 77140.0 80852.6 81356.6 82498.6 83076.6 9699.5 9798.5 9862.9 9882.9 9975.0 17500.6 17579.5 17587.4 17652.7 17707.5 6796.8 6841.6 6900.4 6903.7 6984.5 3662.6 3677.4 3750.1 3766.1 3859.3 2677.7 2732.3 2795.4 2817.2 2820.0 499.6 503.0 509.6 510.1 511.0 492.4 507.3 509.0 550.8 651.1 8570.1 8615.0 8652.0 8759.9 8929.3 542.7 556.4 576.5 620.9 622.3 4262.2 4283.4 4295.6 4352.3 4412.8 79704.6 80032.0 80667.6 81376.6 81540.6 66723.7 68370.7 68791.3 69652.0 71027.6 65899.0 66114.0 66149.7 66268.0 66355.0 7925.7 8346.8 8410.8 8672.1 8742.0 44962.0 45509.3 45626.3 46127.3 47089.0 55854.4 58352.7 59224.4 59858.0 59911.7 10171.4 10376.4 10440.0 10515.8 11683.9 13115.7 14234.4 14462.6 14662.0 15138.5 11466.3 12019.2 13175.4 13313.1 13365.1 12929.4 13033.5 13195.3 13251.0 14108.6 13498.3 13845.1 13858.4 13897.3 14067.2 10471.6 11090.3 11583.7 11617.3 12173.9 11024.8 11477.2 11626.5 11638.0 11656.2 21:18:53 INFO - PROCESS | 816 | -------- Summary: end -------- 21:18:53 INFO - PROCESS | 816 | 21:18:54 INFO - TEST-INFO | 816: exit 0 21:18:54 INFO - TEST-OK | dromaeo_css | took 1122275ms 21:18:54 INFO - TEST-START | kraken 21:18:54 INFO - operating with platform_type : w7_ 21:18:54 INFO - Initialising browser for kraken test... 21:18:54 INFO - TEST-INFO | started process 1084 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpr3g3q9\profile http://localhost:49615/getInfo.html) 21:18:59 INFO - PROCESS | 1084 | __metrics Screen width/height:1600/1200 21:18:59 INFO - PROCESS | 1084 | colorDepth:24 21:18:59 INFO - PROCESS | 1084 | Browser inner width/height: 1010/674 21:18:59 INFO - PROCESS | 1084 | __metrics 21:19:00 INFO - PROCESS | 1084 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:19:00 INFO - PROCESS | 1084 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:19:00 INFO - PROCESS | 1084 | console.error: 21:19:00 INFO - PROCESS | 1084 | Message: Error: Connection closed before committing the transaction. 21:19:00 INFO - PROCESS | 1084 | Stack: 21:19:00 INFO - PROCESS | 1084 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 21:19:00 INFO - PROCESS | 1084 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 21:19:00 INFO - PROCESS | 1084 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 21:19:00 INFO - PROCESS | 1084 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 21:19:00 INFO - PROCESS | 1084 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 21:19:00 INFO - PROCESS | 1084 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 21:19:00 INFO - PROCESS | 1084 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 21:19:00 INFO - PROCESS | 1084 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 21:19:00 INFO - PROCESS | 1084 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 21:19:00 INFO - PROCESS | 1084 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 21:19:00 INFO - PROCESS | 1084 | Barrier/this.client.addBlocker/promise13.2% 21:19:58 INFO - TinderboxPrint: I/O read bytes / time
231,312,384 / 2,551,430 21:19:58 INFO - TinderboxPrint: I/O write bytes / time
479,413,760 / 3,435,840 21:19:58 INFO - TinderboxPrint: CPU idle
8,260.1 (86.8%) 21:19:58 INFO - TinderboxPrint: CPU user
1,217.2 (12.8%) 21:19:58 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 0; Write bytes: 48457728; Read time: 0; Write time: 54030 21:19:58 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 231312384; Write bytes: 408526336; Read time: 2551430; Write time: 3367230 21:19:58 INFO - Running post-run listener: _upload_blobber_files 21:19:58 INFO - Blob upload gear active. 21:19:58 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 21:19:58 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:19:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 21:19:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 21:19:59 INFO - (blobuploader) - INFO - Open directory for files ... 21:19:59 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 21:19:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:19:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:20:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:02 INFO - (blobuploader) - INFO - Done attempting. 21:20:02 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 21:20:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:03 INFO - (blobuploader) - INFO - Done attempting. 21:20:03 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 21:20:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:20:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:20:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:20:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:20:11 INFO - (blobuploader) - INFO - Done attempting. 21:20:11 INFO - (blobuploader) - INFO - Iteration through files over. 21:20:11 INFO - Return code: 0 21:20:11 INFO - rmtree: C:\slave\test\build\uploaded_files.json 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 21:20:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d5c315b007771a9ed953ab3d1ed4c3551110f2bbcc002de0a379a0cb60d27a9aef8d87224604a5eb7a9b782f8459b0fc5b66a09ed54112ed028422de665ccc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8889c118e64b06dc3056140d381533b8f9b56603fa2dd5c09a9942aab1ddbd9c25813f24f0daac1b02ffb0c3149dbe33b0c821ca6f6a424ac94d3fae92a6b541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943aa3503883d1a38fa0c748b9a4bf05e6731b61594fb63262b1ab707761450a1e9633361817ca6ad421373efb153bf1e61bc97ef53ff17dce56791bc97e2fe0"} 21:20:11 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 21:20:11 INFO - Writing to file C:\slave\test\properties\blobber_files 21:20:11 INFO - Contents: 21:20:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d5c315b007771a9ed953ab3d1ed4c3551110f2bbcc002de0a379a0cb60d27a9aef8d87224604a5eb7a9b782f8459b0fc5b66a09ed54112ed028422de665ccc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8889c118e64b06dc3056140d381533b8f9b56603fa2dd5c09a9942aab1ddbd9c25813f24f0daac1b02ffb0c3149dbe33b0c821ca6f6a424ac94d3fae92a6b541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943aa3503883d1a38fa0c748b9a4bf05e6731b61594fb63262b1ab707761450a1e9633361817ca6ad421373efb153bf1e61bc97ef53ff17dce56791bc97e2fe0"} 21:20:11 INFO - Running post-run listener: copy_logs_to_upload_dir 21:20:11 INFO - Copying logs to upload dir... 21:20:11 INFO - mkdir: C:\slave\test\build\upload\logs 21:20:11 INFO - Copying logs to upload dir... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... 21:20:11 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1445.115000 ========= master_lag: 1.48 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2016-11-29 21:20:13.149788) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.150682) ========= '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-089 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-089 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-089 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d5c315b007771a9ed953ab3d1ed4c3551110f2bbcc002de0a379a0cb60d27a9aef8d87224604a5eb7a9b782f8459b0fc5b66a09ed54112ed028422de665ccc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8889c118e64b06dc3056140d381533b8f9b56603fa2dd5c09a9942aab1ddbd9c25813f24f0daac1b02ffb0c3149dbe33b0c821ca6f6a424ac94d3fae92a6b541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943aa3503883d1a38fa0c748b9a4bf05e6731b61594fb63262b1ab707761450a1e9633361817ca6ad421373efb153bf1e61bc97ef53ff17dce56791bc97e2fe0"} build_url:https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/W0rAGP1JQ1aSkVUIpyDbFg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d5c315b007771a9ed953ab3d1ed4c3551110f2bbcc002de0a379a0cb60d27a9aef8d87224604a5eb7a9b782f8459b0fc5b66a09ed54112ed028422de665ccc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8889c118e64b06dc3056140d381533b8f9b56603fa2dd5c09a9942aab1ddbd9c25813f24f0daac1b02ffb0c3149dbe33b0c821ca6f6a424ac94d3fae92a6b541", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/943aa3503883d1a38fa0c748b9a4bf05e6731b61594fb63262b1ab707761450a1e9633361817ca6ad421373efb153bf1e61bc97ef53ff17dce56791bc97e2fe0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.270293) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.270668) ========= '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-089 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-089 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-089 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.382297) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.382634) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-29 21:20:13.871770) ========= ========= Total master_lag: 2.36 =========