builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-136 starttime: 1476365050.88 results: success (0) buildid: 20161013055723 builduid: 5794da261f054110a2b9c08afed990a7 revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:10.885001) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:10.885465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:10.885743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:11.018465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 06:24:11.018843) ========= '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-136 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-136 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-136 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 --06:24:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 12.99 MB/s 06:24:11 (12.99 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.195000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 06:24:12.261462) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-13 06:24:12.261838) ========= '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-136 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-136 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-136 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.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-10-13 06:24:28.066120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 06:24:28.066492) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-136 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-136 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-136 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-10-13 06:24:28,154 truncating revision to first 12 chars 2016-10-13 06:24:28,154 Setting DEBUG logging. 2016-10-13 06:24:28,154 attempt 1/10 2016-10-13 06:24:28,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f03e2740d604?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 06:24:29,122 unpacking tar archive at: fx-team-f03e2740d604/testing/mozharness/ 2016-10-13 06:24:29,321 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.842000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 06:24:29.947259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:29.947579) ========= script_repo_revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:29.948103) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:29.948372) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 06:24:29.958362) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 44 secs) (at 2016-10-13 06:24:29.958691) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' 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', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-136 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-136 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-136 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 06:24:30 INFO - MultiFileLogger online at 20161013 06:24:30 in C:\slave\test 06:24:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:24:30 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:24:30 INFO - {'append_to_log': False, 06:24:30 INFO - 'base_work_dir': 'C:\\slave\\test', 06:24:30 INFO - 'blob_upload_branch': 'Fx-Team', 06:24:30 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:24:30 INFO - 'branch': 'Fx-Team', 06:24:30 INFO - 'buildbot_json_path': 'buildprops.json', 06:24:30 INFO - 'config_files': ('talos/windows_config.py',), 06:24:30 INFO - 'default_actions': ('clobber', 06:24:30 INFO - 'read-buildbot-config', 06:24:30 INFO - 'download-and-extract', 06:24:30 INFO - 'populate-webroot', 06:24:30 INFO - 'create-virtualenv', 06:24:30 INFO - 'install', 06:24:30 INFO - 'run-tests'), 06:24:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:24:30 INFO - 'download_minidump_stackwalk': True, 06:24:30 INFO - 'download_symbols': 'ondemand', 06:24:30 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:24:30 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:24:30 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:24:30 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:24:30 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:24:30 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:24:30 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:24:30 INFO - 'C:/mozilla-build/tooltool.py'), 06:24:30 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:24:30 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:24:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:24:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:24:30 INFO - 'installer_path': 'installer.exe', 06:24:30 INFO - 'log_level': 'info', 06:24:30 INFO - 'log_name': 'talos', 06:24:30 INFO - 'log_to_console': True, 06:24:30 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:24:30 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:24:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:24:30 INFO - 'opt_config_files': (), 06:24:30 INFO - 'pip_index': False, 06:24:30 INFO - 'sps_profile': False, 06:24:30 INFO - 'sps_profile_interval': 0, 06:24:30 INFO - 'suite': 'dromaeojs', 06:24:30 INFO - 'system_bits': '32', 06:24:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:24:30 INFO - 'title': 'T-W732-IX-136', 06:24:30 INFO - 'use_talos_json': True, 06:24:30 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:24:30 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:24:30 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:24:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:24:30 INFO - 'work_dir': 'build'} 06:24:30 INFO - [mozharness: 2016-10-13 13:24:30.257000Z] Running clobber step. 06:24:30 INFO - Running pre-action listener: _resource_record_pre_action 06:24:30 INFO - Running main action method: clobber 06:24:30 INFO - rmtree: C:\slave\test\build 06:24:30 INFO - Using _rmtree_windows ... 06:24:30 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 06:26:27 INFO - Running post-action listener: _resource_record_post_action 06:26:27 INFO - [mozharness: 2016-10-13 13:26:27.887000Z] Finished clobber step (success) 06:26:27 INFO - [mozharness: 2016-10-13 13:26:27.887000Z] Running read-buildbot-config step. 06:26:27 INFO - Running pre-action listener: _resource_record_pre_action 06:26:27 INFO - Running main action method: read_buildbot_config 06:26:27 INFO - Using buildbot properties: 06:26:27 INFO - { 06:26:27 INFO - "project": "", 06:26:27 INFO - "product": "firefox", 06:26:27 INFO - "script_repo_revision": "production", 06:26:27 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 06:26:27 INFO - "repository": "", 06:26:27 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 06:26:27 INFO - "buildid": "20161013055723", 06:26:27 INFO - "stage_platform": "win32-pgo", 06:26:27 INFO - "basedir": "C:\\slave\\test", 06:26:27 INFO - "buildnumber": 241, 06:26:27 INFO - "platform": "win7_ix", 06:26:27 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 06:26:27 INFO - "slavebuilddir": "test", 06:26:27 INFO - "branch": "fx-team", 06:26:27 INFO - "revision": "f03e2740d604d339ed553dad62a3fc54c317f8fa", 06:26:27 INFO - "slavename": "t-w732-ix-136", 06:26:27 INFO - "builduid": "5794da261f054110a2b9c08afed990a7", 06:26:27 INFO - "repo_path": "integration/fx-team" 06:26:27 INFO - } 06:26:27 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.test_packages.json. 06:26:27 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip. 06:26:27 INFO - Running post-action listener: _resource_record_post_action 06:26:27 INFO - [mozharness: 2016-10-13 13:26:27.891000Z] Finished read-buildbot-config step (success) 06:26:27 INFO - [mozharness: 2016-10-13 13:26:27.891000Z] Running download-and-extract step. 06:26:27 INFO - Running pre-action listener: _resource_record_pre_action 06:26:27 INFO - Running main action method: download_and_extract 06:26:27 INFO - mkdir: C:\slave\test\build\tests 06:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:27 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.test_packages.json 06:26:27 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:26:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 06:26:28 INFO - Downloaded 1218 bytes. 06:26:28 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:26:28 INFO - Using the following test package requirements: 06:26:28 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:26:28 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 06:26:28 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:26:28 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'jsshell-win32.zip'], 06:26:28 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 06:26:28 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:26:28 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 06:26:28 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 06:26:28 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 06:26:28 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:26:28 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 06:26:28 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:26:28 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip 06:26:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:26:28 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:26:28 INFO - Http code: 200 06:26:28 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 06:26:28 INFO - x-amz-cf-id: uHKmgG0eJlfwSdaOolvMGazYbc9G9w2rXwaWDdOoUooq2kM_O3N54A== 06:26:28 INFO - x-amz-replication-status: COMPLETED 06:26:28 INFO - x-amz-version-id: RasQzysgZ4YT41Sb0YHqb2R9aGIJ60au 06:26:29 INFO - Expected file size: 17277968 06:26:29 INFO - Obtained file size: 17277968 06:26:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 06:26:58 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip 06:26:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:26:58 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:26:58 INFO - Http code: 200 06:26:58 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 06:26:58 INFO - x-amz-cf-id: xH1FOBvSrmSUlHd4i8Zr3EK1bGyBwqqlp7MzeCq6cjZcJa-KxU8UAg== 06:26:58 INFO - x-amz-replication-status: COMPLETED 06:26:58 INFO - x-amz-version-id: RasQzysgZ4YT41Sb0YHqb2R9aGIJ60au 06:26:59 INFO - Expected file size: 17277968 06:26:59 INFO - Obtained file size: 17277968 06:27:06 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.talos.tests.zip 06:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 06:27:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 06:27:06 INFO - Http code: 200 06:27:06 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 06:27:06 INFO - x-amz-cf-id: NO0eonNx0BxzKIKs731Sro_iAroeh17L3Xy4yimccnYXhBxzvcPfog== 06:27:06 INFO - x-amz-replication-status: COMPLETED 06:27:06 INFO - x-amz-version-id: JUe4VZBzV7tApY8LxDQVARIJpvWcgWxu 06:27:06 INFO - Expected file size: 14409006 06:27:06 INFO - Obtained file size: 14409006 06:27:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:19 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip 06:27:19 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 06:27:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 06:27:21 INFO - Downloaded 54697043 bytes. 06:27:21 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip 06:27:21 INFO - mkdir: C:\slave\test\properties 06:27:21 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 06:27:21 INFO - Writing to file C:\slave\test\properties\build_url 06:27:21 INFO - Contents: 06:27:21 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip 06:27:22 INFO - Running post-action listener: _resource_record_post_action 06:27:22 INFO - Running post-action listener: set_extra_try_arguments 06:27:22 INFO - [mozharness: 2016-10-13 13:27:22.746000Z] Finished download-and-extract step (success) 06:27:22 INFO - [mozharness: 2016-10-13 13:27:22.746000Z] Running populate-webroot step. 06:27:22 INFO - Running pre-action listener: _resource_record_pre_action 06:27:22 INFO - Running main action method: populate_webroot 06:27:22 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:27:22 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:27:22 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:27:22 INFO - u'--tppagecycles', 06:27:22 INFO - u'7'], 06:27:22 INFO - u'tests': [u'tsvgm']}}, 06:27:22 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:27:22 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 06:27:22 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 06:27:22 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 06:27:22 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:27:22 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:27:22 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'damp', u'tps']}, 06:27:22 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'tests': [u'damp', u'tps']}, 06:27:22 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'dromaeo_dom']}, 06:27:22 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:27:22 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'basic_compositor_video']}, 06:27:22 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 06:27:22 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'other-e10s': {u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'a11yr', 06:27:22 INFO - u'ts_paint', 06:27:22 INFO - u'tpaint', 06:27:22 INFO - u'sessionrestore', 06:27:22 INFO - u'sessionrestore_no_auto_restore', 06:27:22 INFO - u'tabpaint']}, 06:27:22 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'tsvgx', 06:27:22 INFO - u'tsvgr_opacity', 06:27:22 INFO - u'tart', 06:27:22 INFO - u'tscrollx', 06:27:22 INFO - u'cart']}, 06:27:22 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 06:27:22 INFO - u'tsvgr_opacity', 06:27:22 INFO - u'tart', 06:27:22 INFO - u'tscrollx', 06:27:22 INFO - u'cart']}, 06:27:22 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'talos_options': [u'--disable-e10s'], 06:27:22 INFO - u'tests': [u'tp5o']}, 06:27:22 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'tests': [u'tp5o']}, 06:27:22 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'talos_options': [u'--disable-e10s', 06:27:22 INFO - u'--xperf_path', 06:27:22 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:22 INFO - u'tests': [u'tp5n']}, 06:27:22 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:27:22 INFO - u'talos_options': [u'--xperf_path', 06:27:22 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:27:22 INFO - u'tests': [u'tp5n']}}, 06:27:22 INFO - u'talos.zip': {u'path': u'', 06:27:22 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:27:22 INFO - Running post-action listener: _resource_record_post_action 06:27:22 INFO - [mozharness: 2016-10-13 13:27:22.829000Z] Finished populate-webroot step (success) 06:27:22 INFO - [mozharness: 2016-10-13 13:27:22.830000Z] Running create-virtualenv step. 06:27:22 INFO - Running pre-action listener: _resource_record_pre_action 06:27:22 INFO - Running main action method: create_virtualenv 06:27:22 INFO - Creating virtualenv C:\slave\test\build/venv 06:27:22 INFO - mkdir: C:\slave\test\build\venv\Scripts 06:27:23 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 06:27:23 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'! 06:27:23 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 06:27:23 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 06:27:29 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 06:27:33 INFO - Installing distribute......................................................................................................................................................................................done. 06:27:34 INFO - Return code: 0 06:27:34 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 06:27:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 06:27:35 INFO - Reading from file tmpfile_stdout 06:27:35 INFO - Output received: 06:27:35 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 06:27:35 INFO - Using _rmtree_windows ... 06:27:35 INFO - Using _rmtree_windows ... 06:27:35 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 06:27: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')]} 06:27:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27: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')]} 06:27:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27: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', 'pip>=1.5'] in C:\slave\test\build 06:27: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 pip>=1.5 06:27:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:35 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:35 INFO - 'DCLOCATION': 'SCL3', 06:27:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:35 INFO - 'HOMEDRIVE': 'C:', 06:27:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:35 INFO - 'KTS_VERSION': '1.19c', 06:27:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:35 INFO - 'MOZ_NO_REMOTE': '1', 06:27:35 INFO - 'NO_EM_RESTART': '1', 06:27:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:35 INFO - 'OS': 'Windows_NT', 06:27:35 INFO - 'OURDRIVE': 'C:', 06:27: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;', 06:27:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:35 INFO - 'PROCESSOR_LEVEL': '6', 06:27:35 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:35 INFO - 'PROMPT': '$P$G', 06:27:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:35 INFO - 'PWD': 'C:\\slave\\test', 06:27:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:35 INFO - 'SYSTEMDRIVE': 'C:', 06:27:35 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:35 INFO - 'TEST1': 'testie', 06:27:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:35 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:35 INFO - 'USERNAME': 'cltbld', 06:27:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:35 INFO - 'WINDIR': 'C:\\windows', 06:27:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:36 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:27:36 INFO - Downloading/unpacking pip>=1.5 06:27:40 INFO - Running setup.py egg_info for package pip 06:27:40 INFO - warning: no files found matching 'pip\cacert.pem' 06:27:40 INFO - warning: no files found matching '*.html' under directory 'docs' 06:27:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:27:40 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:27:40 INFO - Installing collected packages: pip 06:27:40 INFO - Found existing installation: pip 0.8.2 06:27:40 INFO - Uninstalling pip: 06:27:40 INFO - Successfully uninstalled pip 06:27:40 INFO - Running setup.py install for pip 06:27:40 INFO - warning: no files found matching 'pip\cacert.pem' 06:27:40 INFO - warning: no files found matching '*.html' under directory 'docs' 06:27:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:27:40 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:27:40 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 06:27:40 INFO - Successfully installed pip 06:27:40 INFO - Cleaning up... 06:27:40 INFO - Return code: 0 06:27:40 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 06:27: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')]} 06:27:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27: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')]} 06:27:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27: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', '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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27: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', 'psutil>=3.1.1'] in C:\slave\test\build 06:27: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 psutil>=3.1.1 06:27:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:40 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:40 INFO - 'DCLOCATION': 'SCL3', 06:27:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:40 INFO - 'HOMEDRIVE': 'C:', 06:27:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:40 INFO - 'KTS_VERSION': '1.19c', 06:27:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:40 INFO - 'MOZ_NO_REMOTE': '1', 06:27:40 INFO - 'NO_EM_RESTART': '1', 06:27:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:40 INFO - 'OS': 'Windows_NT', 06:27:40 INFO - 'OURDRIVE': 'C:', 06:27: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;', 06:27:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:40 INFO - 'PROCESSOR_LEVEL': '6', 06:27:40 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:40 INFO - 'PROMPT': '$P$G', 06:27:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:40 INFO - 'PWD': 'C:\\slave\\test', 06:27:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:40 INFO - 'SYSTEMDRIVE': 'C:', 06:27:40 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:40 INFO - 'TEST1': 'testie', 06:27:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:40 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:40 INFO - 'USERNAME': 'cltbld', 06:27:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:40 INFO - 'WINDIR': 'C:\\windows', 06:27:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:42 INFO - Downloading/unpacking psutil>=3.1.1 06:27: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 06:27: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 06:27:43 INFO - Installing collected packages: psutil 06:27:43 INFO - Successfully installed psutil 06:27:43 INFO - Cleaning up... 06:27:43 INFO - Return code: 0 06:27:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 06:27: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')]} 06:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27: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')]} 06:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build 06:27: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 mozsystemmonitor==0.3 06:27:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:43 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:43 INFO - 'DCLOCATION': 'SCL3', 06:27:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:43 INFO - 'HOMEDRIVE': 'C:', 06:27:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:43 INFO - 'KTS_VERSION': '1.19c', 06:27:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:43 INFO - 'MOZ_NO_REMOTE': '1', 06:27:43 INFO - 'NO_EM_RESTART': '1', 06:27:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:43 INFO - 'OS': 'Windows_NT', 06:27:43 INFO - 'OURDRIVE': 'C:', 06:27: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;', 06:27:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:43 INFO - 'PROCESSOR_LEVEL': '6', 06:27:43 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:43 INFO - 'PROMPT': '$P$G', 06:27:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:43 INFO - 'PWD': 'C:\\slave\\test', 06:27:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:43 INFO - 'SYSTEMDRIVE': 'C:', 06:27:43 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:43 INFO - 'TEST1': 'testie', 06:27:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:43 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:43 INFO - 'USERNAME': 'cltbld', 06:27:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:43 INFO - 'WINDIR': 'C:\\windows', 06:27:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:27: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 06:27: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 06:27:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:27:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:27:45 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) 06:27:45 INFO - Installing collected packages: mozsystemmonitor 06:27:45 INFO - Running setup.py install for mozsystemmonitor 06:27:45 INFO - Successfully installed mozsystemmonitor 06:27:45 INFO - Cleaning up... 06:27:45 INFO - Return code: 0 06:27:45 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 06:27:45 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')]} 06:27:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:45 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')]} 06:27:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:45 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27:45 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 06:27:45 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 06:27:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:45 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:45 INFO - 'DCLOCATION': 'SCL3', 06:27:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:45 INFO - 'HOMEDRIVE': 'C:', 06:27:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:45 INFO - 'KTS_VERSION': '1.19c', 06:27:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:45 INFO - 'MOZ_NO_REMOTE': '1', 06:27:45 INFO - 'NO_EM_RESTART': '1', 06:27:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:45 INFO - 'OS': 'Windows_NT', 06:27:45 INFO - 'OURDRIVE': 'C:', 06:27:45 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;', 06:27:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:45 INFO - 'PROCESSOR_LEVEL': '6', 06:27:45 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:45 INFO - 'PROMPT': '$P$G', 06:27:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:45 INFO - 'PWD': 'C:\\slave\\test', 06:27:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:45 INFO - 'SYSTEMDRIVE': 'C:', 06:27:45 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:45 INFO - 'TEST1': 'testie', 06:27:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:45 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:45 INFO - 'USERNAME': 'cltbld', 06:27:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:45 INFO - 'WINDIR': 'C:\\windows', 06:27:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:46 INFO - Downloading/unpacking jsonschema==2.5.1 06:27:46 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 06:27:46 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 06:27:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:27:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:27:47 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 06:27:47 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 06:27:47 INFO - Downloading functools32-3.2.3-2.tar.gz 06:27:47 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 06:27:47 INFO - warning: no files found matching '*.txt' 06:27:47 INFO - no previously-included directories found matching 'build' 06:27:47 INFO - no previously-included directories found matching 'dist' 06:27:47 INFO - no previously-included directories found matching '.git*' 06:27:47 INFO - Installing collected packages: jsonschema, functools32 06:27:47 INFO - Running setup.py install for functools32 06:27:47 INFO - warning: no files found matching '*.txt' 06:27:47 INFO - no previously-included directories found matching 'build' 06:27:47 INFO - no previously-included directories found matching 'dist' 06:27:47 INFO - no previously-included directories found matching '.git*' 06:27:47 INFO - Successfully installed jsonschema functools32 06:27:47 INFO - Cleaning up... 06:27:48 INFO - Return code: 0 06:27:48 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 06:27: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')]} 06:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27: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')]} 06:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:48 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27:48 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 06:27:48 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 06:27:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:48 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:48 INFO - 'DCLOCATION': 'SCL3', 06:27:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:48 INFO - 'HOMEDRIVE': 'C:', 06:27:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:48 INFO - 'KTS_VERSION': '1.19c', 06:27:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:48 INFO - 'MOZ_NO_REMOTE': '1', 06:27:48 INFO - 'NO_EM_RESTART': '1', 06:27:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:48 INFO - 'OS': 'Windows_NT', 06:27:48 INFO - 'OURDRIVE': 'C:', 06:27: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;', 06:27:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:48 INFO - 'PROCESSOR_LEVEL': '6', 06:27:48 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:48 INFO - 'PROMPT': '$P$G', 06:27:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:48 INFO - 'PWD': 'C:\\slave\\test', 06:27:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:48 INFO - 'SYSTEMDRIVE': 'C:', 06:27:48 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:48 INFO - 'TEST1': 'testie', 06:27:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:48 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:48 INFO - 'USERNAME': 'cltbld', 06:27:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:48 INFO - 'WINDIR': 'C:\\windows', 06:27:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 06:27:48 INFO - Cleaning up... 06:27:48 INFO - Return code: 0 06:27:48 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 06:27: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')]} 06:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27: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')]} 06:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:48 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27:48 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 06:27:48 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 06:27:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:48 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:48 INFO - 'DCLOCATION': 'SCL3', 06:27:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:48 INFO - 'HOMEDRIVE': 'C:', 06:27:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:48 INFO - 'KTS_VERSION': '1.19c', 06:27:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:48 INFO - 'MOZ_NO_REMOTE': '1', 06:27:48 INFO - 'NO_EM_RESTART': '1', 06:27:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:48 INFO - 'OS': 'Windows_NT', 06:27:48 INFO - 'OURDRIVE': 'C:', 06:27: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;', 06:27:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:48 INFO - 'PROCESSOR_LEVEL': '6', 06:27:48 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:48 INFO - 'PROMPT': '$P$G', 06:27:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:48 INFO - 'PWD': 'C:\\slave\\test', 06:27:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:48 INFO - 'SYSTEMDRIVE': 'C:', 06:27:48 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:48 INFO - 'TEST1': 'testie', 06:27:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:48 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:48 INFO - 'USERNAME': 'cltbld', 06:27:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:48 INFO - 'WINDIR': 'C:\\windows', 06:27:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:48 INFO - Downloading/unpacking blobuploader==1.2.4 06:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:49 INFO - Downloading blobuploader-1.2.4.tar.gz 06:27:49 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:27:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 06:27:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:27:50 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 06:27:50 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 06:27:50 INFO - Downloading docopt-0.6.1.tar.gz 06:27:50 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 06:27:51 INFO - Installing collected packages: blobuploader, requests, docopt 06:27:51 INFO - Running setup.py install for blobuploader 06:27:51 INFO - Running setup.py install for requests 06:27:51 INFO - Running setup.py install for docopt 06:27:52 INFO - Successfully installed blobuploader requests docopt 06:27:52 INFO - Cleaning up... 06:27:53 INFO - Return code: 0 06:27:53 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:53 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:27:53 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 06:27:53 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 06:27:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:27:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:27:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:27:53 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:27:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:27:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:27:53 INFO - 'DCLOCATION': 'SCL3', 06:27:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:27:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:27:53 INFO - 'HOMEDRIVE': 'C:', 06:27:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:27:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:27:53 INFO - 'KTS_VERSION': '1.19c', 06:27:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:27:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:27:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:27:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:53 INFO - 'MOZ_NO_REMOTE': '1', 06:27:53 INFO - 'NO_EM_RESTART': '1', 06:27:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:27:53 INFO - 'OS': 'Windows_NT', 06:27:53 INFO - 'OURDRIVE': 'C:', 06:27:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:27:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:27:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:27:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:27:53 INFO - 'PROCESSOR_LEVEL': '6', 06:27:53 INFO - 'PROCESSOR_REVISION': '1e05', 06:27:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:27:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:27:53 INFO - 'PROMPT': '$P$G', 06:27:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:27:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:27:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:27:53 INFO - 'PWD': 'C:\\slave\\test', 06:27:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:27:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:27:53 INFO - 'SYSTEMDRIVE': 'C:', 06:27:53 INFO - 'SYSTEMROOT': 'C:\\windows', 06:27:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:53 INFO - 'TEST1': 'testie', 06:27:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:27:53 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:27:53 INFO - 'USERNAME': 'cltbld', 06:27:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:27:53 INFO - 'WINDIR': 'C:\\windows', 06:27:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:27:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:27:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-otmv0d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:27:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:27:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eyzuny-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:27:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:27:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lffjz0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:27:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:27:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-66o2o_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:27:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:27:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kxsaut-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:27:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:27:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qukegy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:27:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:27:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ljci9a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:27:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:27:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7n0prr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-awn2gr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-50hg6w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-onecdu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ainqvl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-88ntlm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j77lzl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:27:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:27:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-metm3z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:27:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:27:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-61qyk0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:27:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:27:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zy6ejh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:27:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:27:56 INFO - Running setup.py install for manifestparser 06:27:56 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 06:27:56 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 06:27:56 INFO - Running setup.py install for mozcrash 06:27:57 INFO - Running setup.py install for mozdebug 06:27:57 INFO - Running setup.py install for mozdevice 06:27:57 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 06:27:57 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 06:27:57 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 06:27:57 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 06:27:58 INFO - Running setup.py install for mozfile 06:27:58 INFO - Running setup.py install for mozhttpd 06:27:58 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 06:27:58 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Running setup.py install for mozinfo 06:27:59 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Running setup.py install for mozInstall 06:27:59 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 06:27:59 INFO - Running setup.py install for mozleak 06:28:00 INFO - Running setup.py install for mozlog 06:28:00 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 06:28:00 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 06:28:00 INFO - Running setup.py install for moznetwork 06:28:01 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 06:28:01 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 06:28:01 INFO - Running setup.py install for mozprocess 06:28:01 INFO - Running setup.py install for mozprofile 06:28:02 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Running setup.py install for mozrunner 06:28:02 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 06:28:02 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 06:28:03 INFO - Running setup.py install for mozscreenshot 06:28:03 INFO - Running setup.py install for moztest 06:28:05 INFO - Running setup.py install for mozversion 06:28:05 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 06:28:05 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 06:28:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:28:06 INFO - Cleaning up... 06:28:06 INFO - Return code: 0 06:28:06 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:28: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')]} 06:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28: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')]} 06:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:06 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:28:06 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 06:28:06 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 06:28:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:28:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:28:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:28:06 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:28:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:28:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:28:06 INFO - 'DCLOCATION': 'SCL3', 06:28:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:28:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:28:06 INFO - 'HOMEDRIVE': 'C:', 06:28:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:28:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:28:06 INFO - 'KTS_VERSION': '1.19c', 06:28:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:28:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:28:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:28:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:06 INFO - 'MOZ_NO_REMOTE': '1', 06:28:06 INFO - 'NO_EM_RESTART': '1', 06:28:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:28:06 INFO - 'OS': 'Windows_NT', 06:28:06 INFO - 'OURDRIVE': 'C:', 06:28: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;', 06:28:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:28:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:28:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:28:06 INFO - 'PROCESSOR_LEVEL': '6', 06:28:06 INFO - 'PROCESSOR_REVISION': '1e05', 06:28:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:28:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:28:06 INFO - 'PROMPT': '$P$G', 06:28:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:28:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:28:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:28:06 INFO - 'PWD': 'C:\\slave\\test', 06:28:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:28:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:28:06 INFO - 'SYSTEMDRIVE': 'C:', 06:28:06 INFO - 'SYSTEMROOT': 'C:\\windows', 06:28:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:06 INFO - 'TEST1': 'testie', 06:28:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:06 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:28:06 INFO - 'USERNAME': 'cltbld', 06:28:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:28:06 INFO - 'WINDIR': 'C:\\windows', 06:28:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:28:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:28:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sgknqc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:28:06 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)) 06:28:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:28:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iw38mr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:28:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:28:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3gvisl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:28:06 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)) 06:28:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:28:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lmilx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-opv2sc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ldwb5q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rcmexs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fchihm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkrckq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uzbvun-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fcsu83-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:28:07 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)) 06:28:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:28:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iurwqy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:28:08 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)) 06:28:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:28:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vnczd1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:28:08 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)) 06:28:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:28:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_bgitb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 06:28:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:28:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aufqdh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:28:08 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)) 06:28:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:28:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbxiaz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:28:08 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)) 06:28:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:28:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cqsod9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:28:08 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)) 06:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 06:28:08 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)) 06:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 06:28:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:28:08 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 06:28:08 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 06:28:09 INFO - Downloading blessings-1.6.tar.gz 06:28:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 06:28:10 INFO - Installing collected packages: blessings 06:28:10 INFO - Running setup.py install for blessings 06:28:10 INFO - Successfully installed blessings 06:28:10 INFO - Cleaning up... 06:28:13 INFO - Return code: 0 06:28:13 INFO - Done creating virtualenv C:\slave\test\build/venv. 06:28:13 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:28:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:28:13 INFO - Reading from file tmpfile_stdout 06:28:13 INFO - Using _rmtree_windows ... 06:28:13 INFO - Using _rmtree_windows ... 06:28:13 INFO - Current package versions: 06:28:13 INFO - blessings == 1.6 06:28:13 INFO - blobuploader == 1.2.4 06:28:13 INFO - docopt == 0.6.1 06:28:13 INFO - functools32 == 3.2.3-2 06:28:13 INFO - jsonschema == 2.5.1 06:28:13 INFO - manifestparser == 1.1 06:28:13 INFO - mozInstall == 1.12 06:28:13 INFO - mozcrash == 0.17 06:28:13 INFO - mozdebug == 0.1 06:28:13 INFO - mozdevice == 0.48 06:28:13 INFO - mozfile == 1.2 06:28:13 INFO - mozhttpd == 0.7 06:28:13 INFO - mozinfo == 0.9 06:28:13 INFO - mozleak == 0.1 06:28:13 INFO - mozlog == 3.4 06:28:13 INFO - moznetwork == 0.27 06:28:13 INFO - mozprocess == 0.23 06:28:13 INFO - mozprofile == 0.28 06:28:13 INFO - mozrunner == 6.12 06:28:13 INFO - mozscreenshot == 0.1 06:28:13 INFO - mozsystemmonitor == 0.3 06:28:13 INFO - moztest == 0.7 06:28:13 INFO - mozversion == 1.4 06:28:13 INFO - psutil == 3.1.1 06:28:13 INFO - requests == 1.2.3 06:28:13 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:28:13 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')]} 06:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:13 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')]} 06:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:13 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:28:13 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 06:28:13 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 06:28:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:28:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:28:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:28:13 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:28:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:28:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:28:13 INFO - 'DCLOCATION': 'SCL3', 06:28:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:28:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:28:13 INFO - 'HOMEDRIVE': 'C:', 06:28:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:28:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:28:13 INFO - 'KTS_VERSION': '1.19c', 06:28:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:28:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:28:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:28:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:13 INFO - 'MOZ_NO_REMOTE': '1', 06:28:13 INFO - 'NO_EM_RESTART': '1', 06:28:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:28:13 INFO - 'OS': 'Windows_NT', 06:28:13 INFO - 'OURDRIVE': 'C:', 06:28:13 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;', 06:28:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:28:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:28:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:28:13 INFO - 'PROCESSOR_LEVEL': '6', 06:28:13 INFO - 'PROCESSOR_REVISION': '1e05', 06:28:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:28:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:28:13 INFO - 'PROMPT': '$P$G', 06:28:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:28:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:28:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:28:13 INFO - 'PWD': 'C:\\slave\\test', 06:28:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:28:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:28:13 INFO - 'SYSTEMDRIVE': 'C:', 06:28:13 INFO - 'SYSTEMROOT': 'C:\\windows', 06:28:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:13 INFO - 'TEST1': 'testie', 06:28:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:13 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:28:13 INFO - 'USERNAME': 'cltbld', 06:28:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:28:13 INFO - 'WINDIR': 'C:\\windows', 06:28:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:28:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 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)) 06:28:14 INFO - Cleaning up... 06:28:14 INFO - Return code: 0 06:28:14 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 06:28:14 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')]} 06:28:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:14 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')]} 06:28:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:14 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 0x01861640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01905E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-136', 'USERDOMAIN': 'T-W732-IX-136', '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-136', '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 06:28:14 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 06:28:14 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 06:28:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:28:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:28:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:28:14 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:28:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:28:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:28:14 INFO - 'DCLOCATION': 'SCL3', 06:28:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:28:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:28:14 INFO - 'HOMEDRIVE': 'C:', 06:28:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:28:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:28:14 INFO - 'KTS_VERSION': '1.19c', 06:28:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:28:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:28:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:28:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:28:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:14 INFO - 'MOZ_NO_REMOTE': '1', 06:28:14 INFO - 'NO_EM_RESTART': '1', 06:28:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:28:14 INFO - 'OS': 'Windows_NT', 06:28:14 INFO - 'OURDRIVE': 'C:', 06:28:14 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;', 06:28:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:28:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:28:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:28:14 INFO - 'PROCESSOR_LEVEL': '6', 06:28:14 INFO - 'PROCESSOR_REVISION': '1e05', 06:28:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:28:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:28:14 INFO - 'PROMPT': '$P$G', 06:28:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:28:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:28:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:28:14 INFO - 'PWD': 'C:\\slave\\test', 06:28:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:28:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:28:14 INFO - 'SYSTEMDRIVE': 'C:', 06:28:14 INFO - 'SYSTEMROOT': 'C:\\windows', 06:28:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:14 INFO - 'TEST1': 'testie', 06:28:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:14 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:28:14 INFO - 'USERNAME': 'cltbld', 06:28:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:28:14 INFO - 'WINDIR': 'C:\\windows', 06:28:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:28:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:28:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 06:28:14 INFO - Cleaning up... 06:28:14 INFO - Return code: 0 06:28:14 INFO - Running post-action listener: _resource_record_post_action 06:28:14 INFO - Running post-action listener: _start_resource_monitoring 06:28:14 INFO - Starting resource monitoring. 06:28:14 INFO - [mozharness: 2016-10-13 13:28:14.544000Z] Finished create-virtualenv step (success) 06:28:14 INFO - [mozharness: 2016-10-13 13:28:14.545000Z] Running install step. 06:28:14 INFO - Running pre-action listener: _resource_record_pre_action 06:28:14 INFO - Running main action method: install 06:28:14 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:28:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:28:14 INFO - Reading from file tmpfile_stdout 06:28:14 INFO - Using _rmtree_windows ... 06:28:14 INFO - Using _rmtree_windows ... 06:28:14 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:14 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 06:28:14 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 06:28:15 INFO - Reading from file tmpfile_stdout 06:28:15 INFO - Output received: 06:28:15 INFO - Usage: mozinstall-script.py [options] installer 06:28:15 INFO - Options: 06:28:15 INFO - -h, --help show this help message and exit 06:28:15 INFO - -d DEST, --destination=DEST 06:28:15 INFO - Directory to install application into. [default: 06:28:15 INFO - "C:\slave\test"] 06:28:15 INFO - --app=APP Application being installed. [default: firefox] 06:28:15 INFO - Using _rmtree_windows ... 06:28:15 INFO - Using _rmtree_windows ... 06:28:15 INFO - mkdir: C:\slave\test\build\application 06:28:15 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 06:28:15 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 06:28:17 INFO - Reading from file tmpfile_stdout 06:28:17 INFO - Output received: 06:28:17 INFO - C:\slave\test\build\application\firefox\firefox.exe 06:28:17 INFO - Using _rmtree_windows ... 06:28:17 INFO - Using _rmtree_windows ... 06:28:17 INFO - Running post-action listener: _resource_record_post_action 06:28:17 INFO - [mozharness: 2016-10-13 13:28:17.210000Z] Finished install step (success) 06:28:17 INFO - [mozharness: 2016-10-13 13:28:17.210000Z] Running run-tests step. 06:28:17 INFO - Running pre-action listener: _resource_record_pre_action 06:28:17 INFO - Running main action method: run_tests 06:28:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 06:28:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 06:28:17 INFO - Python 2.7.3 06:28:17 INFO - Return code: 0 06:28:17 INFO - grabbing minidump binary from tooltool 06:28: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')]} 06:28:17 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 0x01912E90>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019D4520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 06:28:17 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 06:28:17 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 06:28:17 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 06:28:17 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:28:19 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp6hywwg 06:28:19 INFO - INFO - File integrity verified, renaming tmp6hywwg to win32-minidump_stackwalk.exe 06:28:19 INFO - Return code: 0 06:28:19 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 06:28:19 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 06:28:19 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 06:28:19 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 06:28:19 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 06:28:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-136', '--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 06:28:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-136 --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 06:28:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:28:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:28:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:28:19 INFO - 'COMPUTERNAME': 'T-W732-IX-136', 06:28:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:28:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:28:19 INFO - 'DCLOCATION': 'SCL3', 06:28:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:28:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:28:19 INFO - 'HOMEDRIVE': 'C:', 06:28:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:28:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:28:19 INFO - 'KTS_VERSION': '1.19c', 06:28:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:28:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-136', 06:28:19 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:28:19 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 06:28:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:28:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:28:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:19 INFO - 'MOZ_NO_REMOTE': '1', 06:28:19 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:28:19 INFO - 'NO_EM_RESTART': '1', 06:28:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:28:19 INFO - 'OS': 'Windows_NT', 06:28:19 INFO - 'OURDRIVE': 'C:', 06:28:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:28:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:28:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:28:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:28:19 INFO - 'PROCESSOR_LEVEL': '6', 06:28:19 INFO - 'PROCESSOR_REVISION': '1e05', 06:28:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:28:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:28:19 INFO - 'PROMPT': '$P$G', 06:28:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:28:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:28:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:28:19 INFO - 'PWD': 'C:\\slave\\test', 06:28:19 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 06:28:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:28:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:28:19 INFO - 'SYSTEMDRIVE': 'C:', 06:28:19 INFO - 'SYSTEMROOT': 'C:\\windows', 06:28:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:19 INFO - 'TEST1': 'testie', 06:28:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:28:19 INFO - 'USERDOMAIN': 'T-W732-IX-136', 06:28:19 INFO - 'USERNAME': 'cltbld', 06:28:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:28:19 INFO - 'WINDIR': 'C:\\windows', 06:28:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:28:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:28:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:28:19 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-136', '--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 06:28:20 INFO - mozversion application_buildid: 20161013033014 06:28:20 INFO - mozversion application_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 06:28:20 INFO - mozversion application_display_name: Nightly 06:28:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:28:20 INFO - mozversion application_name: Firefox 06:28:20 INFO - mozversion application_remotingname: firefox 06:28:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:28:20 INFO - mozversion application_vendor: Mozilla 06:28:20 INFO - mozversion application_version: 52.0a1 06:28:20 INFO - mozversion platform_buildid: 20161013033014 06:28:20 INFO - mozversion platform_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 06:28:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:28:20 INFO - mozversion platform_version: 52.0a1 06:28:20 INFO - using testdate: 1476365300 06:28:20 INFO - actual date: 1476365300 06:28:20 INFO - starting webserver on 'localhost:49598' 06:28:20 INFO - SUITE-START | Running 2 tests 06:28:20 INFO - TEST-START | dromaeo_css 06:28:20 INFO - operating with platform_type : w7_ 06:28:20 INFO - Initialising browser for dromaeo_css test... 06:28:20 INFO - TEST-INFO | started process 740 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzbhri9\profile http://localhost:49598/getInfo.html) 06:28:27 INFO - PROCESS | 740 | __metrics Screen width/height:1600/1200 06:28:27 INFO - PROCESS | 740 | colorDepth:24 06:28:27 INFO - PROCESS | 740 | Browser inner width/height: 1010/674 06:28:27 INFO - PROCESS | 740 | __metrics 06:28:28 INFO - PROCESS | 740 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:28:28 INFO - PROCESS | 740 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:28:29 INFO - TEST-INFO | 740: exit 0 06:28:29 INFO - Browser initialized. 06:28:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:28:29 INFO - TEST-INFO | started process 1728 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzbhri9\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:30 INFO - PROCESS | 1728 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 06:28:41 INFO - PROCESS | 1728 | RSS: Main: 125927424 06:28:41 INFO - PROCESS | 1728 | 06:29:32 INFO - PROCESS | 1728 | 1476365372216 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] 06:31:42 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49598/tests/dromaeo/cssquery-ext.html) 06:31:44 INFO - PROCESS | 1728 | RSS: Main: 271441920 06:31:44 INFO - PROCESS | 1728 | 06:34:47 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-ext.html (next: http://localhost:49598/tests/dromaeo/cssquery-jquery.html) 06:34:47 INFO - PROCESS | 1728 | RSS: Main: 196562944 06:34:47 INFO - PROCESS | 1728 | 06:37:49 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49598/tests/dromaeo/cssquery-mootools.html) 06:37:50 INFO - PROCESS | 1728 | RSS: Main: 156909568 06:37:50 INFO - PROCESS | 1728 | 06:40:52 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49598/tests/dromaeo/cssquery-prototype.html) 06:40:53 INFO - PROCESS | 1728 | RSS: Main: 150409216 06:40:53 INFO - PROCESS | 1728 | 06:43:55 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49598/tests/dromaeo/cssquery-yui.html) 06:43:56 INFO - PROCESS | 1728 | RSS: Main: 133955584 06:43:56 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | Cycle 1(1): loaded http://localhost:49598/tests/dromaeo/cssquery-yui.html (next: http://localhost:49598/tests/dromaeo/cssquery-dojo.html) 06:46:59 INFO - PROCESS | 1728 | RSS: Main: 280977408 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | __start_tp_report 06:46:59 INFO - PROCESS | 1728 | _x_x_mozilla_page_load 06:46:59 INFO - PROCESS | 1728 | _x_x_mozilla_page_load_details 06:46:59 INFO - PROCESS | 1728 | |i|pagename|runs| 06:46:59 INFO - PROCESS | 1728 | |0;dojo.html;2567.1785028790796;2606.2051074422293;2612.0466029899094;2679.611456338831;2684;4536.115457485792;4574.817007319703;4628.490866004741;4640.62874970002;4667.743274119922;4523.592876641102;4563.954360456394;4597.034622729222;4611.884702882429;4670.813167473306;4004.579519150491;4025.9798701006453;4052.5947405259385;4055.655269302113;4076.5108187017527;4004.179143275636;4015.3976903464422;4021.7989100545;4083.9387409188885;4128.339465685491;4036.1524079943247;4114.609112134357;4125.319322311815;4141.233871733739;4155.688323375759;3935.724499285051;3936.307387823235;4068.0033391818897;4099.631033207026;4102.405151253081;4023.3964905264443;4029.315016447211;4076.3070278052505;4102.200070986159;4117.5882411758885;4450.065486247891;4458.888527786831;4570.10882877839;4583.243764778807;4586.88532786683;4700.2009658358165;4813.109574728686;4827.106985207748;4894.975525122351;4913.410390753097;4777.28340748887;4836.685615434985;4842.44311904132;4852.927206091911;4862.927056094161;4507.166174257773;4539.4098767160385;4584.931226031612;4589.816407343736;4604.286335617984;4949.7030178189425;4953.2570114483115;5035.84892453227;5045.772940217626;5057.570106540985;4643.164230438486;4663.510331415153;4709.058188362273;4729.69256998294;4845.515448455127;4719.811207551659;4779.02030083832;4795.136875362398;4803.711777293373;4811.711297322172;4725.929111063337;4771.379720636295;4784.569388755029;4790.305405716152;4797.664163508591;4791.736454495036;4798.688085274446;4802;4807.278908163804;4811.711297322172;3255.544223808669;3265.2979609384097;3289.9506507402407;3297.258116923673;3299.2411745298236;2910.3306239564595;2978.4192082544128;2989.4469523137855;3018.4717674406893;3032.1964679359985;4492.303692927601;4499.89752510637;4576.290674945389;4590.173768721662;4596.287575425815;4844.442889067803;4848.224284114524;4868.9756551217015;4923.77842997066;4946.258061290835;4440.578145076212;4455.910881782346;4465.866024019285;4488.910221795546;4589.35748995141;4033.032072302626;4046.251443482965;4132.40080188371;4139.130782535702;4140.834366625301;4961;4977.676451030671;5096.159133742891;5099.643024988215;5108.310378098983;4763.7141771493825;4780.521947805191;4826.517348265145;4940.481249468898;4962.280469331999;4775.856724298276;4883.3651625288485;4895.363602731622;4970.130227210296;4977.751112444291;4687.9765601171775;4693.765311734468;4705.411823522059;4716.740579268173;4747.121782470254;4810.783514741775;4846;4846.29728689331;4849.442314133919;4850.320955066223;4699.083678682624;4706.741129237925;4712.458067322302;4714.38712967312;4720.834770783006;4648.488666246642;4662.696924699883;4741.857744267678;4742.146413645577;4745.33565300865;4618.6536009800075;4624.884377890579;4639.744814035261;4640.373549570765;4642.350070989996;4664.86005419838;4726.858194254178;4750.501197374226;4761.40482439695;4806.279058141306;4660.347551342883;4675.368825208553;4754.976225118852;4757.809687612456;4770.451398089264 06:46:59 INFO - PROCESS | 1728 | |1;ext.html;524.0436203927832;560.352792524634;561.0546229603117;569.3964397738395;571.4656795895837;28353.574696379546;28952.565711514344;30486.3902721946;30496.23759406013;30506.69493305072;30100.397992040147;30872.53691194634;41392.17215655685;41876.37185442221;42347.57652423475;8366.707165249218;8431;8456.534890581015;8531.914680853206;8537.95731021344;8349.373796965221;8451.53516556589;8476.23713865755;8477;8487.193716596914;6893.965530172367;7052.82367940803;7053.929460705404;7213.567185968833;8472.576371181416;6940.167179938415;6981.650917454107;9935.403875767477;10138.03688649577;10159.933207013231;9988.90011099887;10169.084782369548;10172.796544069077;10180.287379883412;10196.694099177037;38428.42357364642;38998.80500597479;39044;39108.21783564341;39122.21755564873;3187.96812031879;3313.718333941618;3409.028426898322;3598.6581274778855;3727.9627203727887;30411.391772164432;30724.692753072406;30951.535726964114;31733.889313873904;31799.20501987446;3456.5106222414224;3537.7169826413824;3591.712662986955;3597.244578638463;3611.3499570077374;271688;310620;312320.99999999773;315115;315855;50713.74643126761;50829.49170508247;51137.744311278206;51149.488505115216;51188;25329;26577.601335979976;26669.19992400231;52933.47066529285;53033;13307.600771976857;13516;13612.795808062887;13620.65948351299;13652.931735341259;10232.334898231591;10278.845817312746;10297.17622590191;10307.04166208418;10307.381557106597;17042.914785425994;17087.31650733957;17119.22963466647;17133.742993855103;17148.314067437164;5423.783048678088;6608.107905432801;6633.734650613921;6677.165354330708;6688.966555167193;812.9105798362175;873.3275377958936;892.6167133752896;900.6307413960376;911.3939230411835;27549;28378.43243135167;28603.85698071538;28692.13923582296;28781.7121828779;929.3448119076036;934.72425634438;962.3985009369144;967.811276801017;973.0464145137804;14361.712765744627;14489.78265326021;14961.829031761852;14966.102033878002;15149.394024238907;450789;452072;457007;458809;459229;46523.30215046777;49584.256236155765;49774.253386199234;49794.75102624536;49846.25230621471;1022.3405903192399;1029.480112543176;1129.249049382168;1155.5782139519015;1179.0626451970875;27785.722142778315;28112;29007;29148.27129321784;29299.267518312212;42292;42923.78538107414;43118.78440607777;43139.352909707006;43332.78333608312;379212;392202;392314;394797;396966;21329.78670213278;21985.560288794455;22451.550968980857;22468.88765556162;22610.886945565166;43148.13703725971;43752;43867.78066109649;43903.121937560434;43947.56052439562;36181.81909090438;36281.63718362783;36552.63447365492;36834.26331473409;38009.42985855159;36752.26495470129;36777.81611091927;36930.815345923096;37528.43707344447;37649.046850081446;35332.47001295034;35878;35951;35971.244600863734;36018;31330.68669313278;31487.842560787965;33308.333833322715;33711.15722106967;34436.31127377489;25012.249632511055;25194.118205862706;25607.103751368613;25639.615405769302;25951.22146335613 06:46:59 INFO - PROCESS | 1728 | |2;jquery.html;286.7233120039164;306.84504325315714;311.25609792595714;312.9859156337965;315.49993260681816;13373.531926382579;14021.929890350546;17496.03771792551;17629.559261018465;18000.639987200248;17308.913455432707;17643.911780441078;17725.82274177258;17764;17967.82032179678;5222.268882356473;5464.9453505465035;5478.342598888139;5484.8354549363385;5490.450954904498;5169.147090730025;5427.674339539641;5440.91838622421;5449.182622606601;5454.945450545503;5283.181106928413;5380.408155102936;5382.91925621116;5394.32570928634;5396.244525766396;5181.974090129563;5255.1329030709885;5281.735913204363;5303.284056652341;5370.3287089113865;4447.8443254486265;4491.326301054835;4593.104344652794;4593.150267200545;4597.218472859622;16969.812113152086;20129.295474658316;20888.955552222327;20956.895215523826;22529.436764080867;1842.917068731897;1856.610111876494;1868.691665875129;2001.1195074167465;2106.31544747956;8269.255766981001;8707.346948978851;8795.824083518293;11295.548178072868;13612.251326177056;1691.4502786594282;1757.8066412694593;1790.6239689665185;1798.4514723009477;1829.122021429708;77539.22460775377;77541;81116.59441702813;81304;86710.56644716799;8635.179657932485;8692.174243446863;8946.507942063248;9037.457752534869;9084.81830363389;11136.443177841076;11471.082313414907;11612.88387116135;11613.883861161281;11629.127815413878;6712.966435167793;6781.254062053269;6873.793786186422;6897.862042759117;6938.167419909643;6828.658567071627;6830.795076147723;6850.760223392257;6952.791416257521;6958.826029349307;3678.889633311005;3686.6497682720633;3691.283553147799;3732.4401339798706;3760.1351689111657;1518.4837155367006;1574.9921250393675;1581.3121292237913;1586.6588683432885;1586.9285882135332;674.7638326585655;845.0619812008665;885.6280362247871;892.455602082729;945.7351941456403;17487.30050797979;18061.7290740639;18104.728429073573;18541.81458185428;18698.34555790541;826.2829129437854;828.2545708861952;844.8352571248547;855.1448551448551;856.6787454704486;9565.378250413702;9983.450910199863;10366.896331036744;10679.57281708723;10865.185111116698;370570;379287;379963;385124;386543;58741.70629146913;76405;89462;93025;97256;979.6228452045871;997.6308765756717;1024.656739992111;1026.6919924022764;1031.8142734307896;15739.842601573837;16859.578510537336;16916.49250522486;16973.236204370587;17534.73697894558;44503.332450012625;45321.77339113283;46112;46209.76895115503;46757;60668;61413.69293153506;62023.6898815503;62649;62940.37059629345;7174.677139528838;7296.19741828409;7309.963450182715;7371.778846634609;7381.188069312479;10937.179711521509;12884.48462061507;12916.612501624966;13068.085234033526;13284.335783210607;10281.897181028393;10544.52549635253;10761.461926903467;11179.273347232403;11361.886381136082;10539.42033188182;10610.098141657736;10793.56825726962;10872.61945831892;10961.83557246626;10599.311044782064;11101.111911046935;11128.888711112786;11139.162038263596;11374.488148033193;7169.318914703095;7288.08898887639;7309.269073092648;7352.375048120755;7384.372328351936;7115;10624.36253824773;10689.412082335546;10848.500589375288;10975.670729878117 06:46:59 INFO - PROCESS | 1728 | |3;mootools.html;1206.7405507815818;1221.8655947845734;1286.601153642371;1545.7063157999978;1548.8993215441003;6250.812475625738;6421.42207201353;6597.901031484532;7177.89233161503;7192.316729910656;6450.516211284161;6526.608403495794;6723.630200338992;6826.317368263171;6837.213720422153;1799.3882080092767;1813.3743858368907;1828.9359872404468;1838.586318078435;1849.3527265457117;1784.9196786144655;1792.4801807475817;1806.3226290141197;1806.3587426463548;1832.660957722819;1594.7607858821211;1621.3109428492903;1779.937702180424;1822.7903791063998;1832.5327041604373;1831.1668190973076;1846.510674671213;1847.187237615445;1848.8983105929235;1852.907354632263;1726.853217476516;1735.201807168704;1744.433059255747;1763.7530745695612;1772.53027947594;6730.360615741546;7007.929920700779;7010.474214433887;7035.190953040364;7036.964815175891;4064.5732198119363;4169.749815011079;4172.290710579178;4174.853880114181;4182.184474027566;2704.9323766905786;2774.667039955218;2814.5918841768034;2816.5634326679397;2819.1260709180015;798.9480683755595;834.4367551902502;838.1785849867165;838.2413915406567;845.6406027438376;8681.696140635047;8818.11818818113;8827.382083254175;8842.734717958407;8859.999999999936;3608.1340478285024;3714.795686237283;3732.664060234592;3735.4396840474146;3763.5483741951143;7004.614746189009;7223.602701851449;7255;7263.382612477999;7279.67241474135;4176.0395109125075;4184.058586817941;4188.225178342016;4189.916201675949;4191.161767646422;3962.603739626072;3967.4048892665755;3974.5429275633096;3977.582353852804;3988.4017397390626;2753.435545713124;2833.164216556121;2883.3656595548946;2886.235147685865;2888.7111288870947;815.3273549321715;825.921537453953;826.1284345016065;835;853.5262929074423;756.4402342266683;764.3312101910828;789.4671097009495;790.3282210121466;799.9280963508926;4223.852165174204;4339.652827773771;4397.978010109993;4450.866474005785;4547.340635607885;323.150115197035;323.30973371850797;329.9950500742491;331.0036789264284;338.21365325618035;2947.3957838643028;3049.7407720344017;3098.860551275198;3102.9224269393444;3138.8430578470984;8004.839903201903;8034.317083048007;8278.71024514139;8334.166583341617;8388.245057944814;8321.292690121407;8345.707900223462;8365.790855228546;8367.95816020916;8370.748877533684;6533.705983230766;6677.265500794909;6678.131842860397;6707.765228216989;6709.698063587248;4495.078508905601;4506.549345065468;4516.09678064395;4533.750643714628;4656.301554766676;7757.495762775401;7908.6441110149035;7908.881366779618;7913.564753938589;7918.802029949297;7570.091589009134;7964.123946365779;8024.39817013738;8026.83946321082;8031.196880311922;5875.618084824473;6037.939620603738;6038.184845045863;6038.9396106038375;6052.001419765689;6800.52396332252;6827.931720682928;6835.521513494008;6847.828804279933;6851.246362900177;6221.47117495018;6250.062490626442;6307.117003619589;6354.714037868214;6371.362863713777;6106.6336019838955;6165.537584681257;6182.412670796267;6270.090836828543;6271.184745982993;5803.7968671096305;5817.7963771267805;5831.212786273968;5847.44449277318;5852.297724273115;3737.8504859805303;3779.4141908004303;3863.420486926871;3868.883933482;3914.5693973662305;3926.1166237596312;3951.407288906687;3977.622125898035;4005.719599627998;4024.1146947671464 06:46:59 INFO - PROCESS | 1728 | |4;prototype.html;238.81849794156435;253.62843434368656;254.54182471551204;256.6573624211677;257.15524502010896;4827.179379505484;4900.313956046157;5138.357705286834;5141.226276934452;5177.508136727014;5006.399232092145;5061.721605311706;5072.822451214199;5073.974630126853;5075.441701412841;3730.253949210155;3756;3757.6805971492454;3760.9435858462007;3769.604191559888;3696.3531382008177;3741.9438708419398;3750.7562008469363;3758.9060273493246;3759.5864454910075;3717.182219911615;3722.274156539476;3724.627537246281;3729.49651797008;3730.570984336809;3674.4672022556847;3714.5141943222657;3730.5150330457136;3738.36447803874;3741.8503259869435;3213;3216.887408940676;3220.3559288142465;3220.5974253218346;3223.09753269085;4979.10376132296;5027.547520723116;5031.496850314976;5032.4212715537815;5044.083248365598;3958;4233.301505251629;4254.638355739766;4256.425382573344;4263.658907287409;5235.371755389339;5241.973790131063;5251.86870328241;5266.8419947401635;5278.1291086970605;3304.3391321735508;3406.744494162948;3423.2297733010123;3456.87900923469;3474.947875781865;5471.945280547184;5519.819671229882;5531.861703457445;5539.172073215829;5540.889182216373;3529.3706188888927;3533.5229743984746;3536.2220311531423;3537.4516949872295;3538.7876727396438;3566.5720113586535;3569.8572057117944;3571.2143328467696;3576.499290099388;3580.462930560385;4226.562968590694;4247.915041699149;4251.6386107180615;4256.978715106405;4269.743815371087;5195.246689230041;5273.156294992783;5318.282031925718;5318.760655770499;5328.36059672834;1223.3883058470765;1229.33001514175;1229.5880879905153;1230.864604893461;1238.717926945607;730.8063363208754;746.5408773604195;768.9000429944072;769.7036640893297;770.8689522781141;4443.822247110079;4484.798184081724;4492.483364413069;4532.297493888453;4537.795799189044;4918.2376731606655;4922.483139270396;4931.654784165074;4937.259411088366;4940.431850337185;4506.932396014063;4514.6388288936805;4798.424189097331;4906.699724572991;4976.776045077981;2894.985525072403;2909.806979138543;2912.723291287324;2920.2553348895894;2931.8827246909887;5677.744501497443;5694.4590263924865;5699.287589051369;5701.600887937804;5717.285339332583;5300.70846103468;5569.777208911598;5613.6703268143265;5631.155326701028;5649.6327738696855;5698.17376480408;5701.857453563611;5704.543636509069;5720.74256658443;5722.227499287543;5467.234587157881;5504.4770746778995;5514.228008078792;5523.701930046355;5535.833924982257;5445.918311225414;5452.372977107683;5463.562914966714;5468.781248750005;5469.671819690831;5452.7546260417585;5581.49766521015;5598.608097433141;5651.15232715096;5712.171735098471;3449.793012419263;3463.8960831175104;3538.115471132217;3541.0970202598173;3547.6629720176543;5506.559475242051;5511.53151982086;5515.227868098389;5517.5585953124455;5521.282233309644;5227.18978558324;5300.946990530046;5356.892862142658;5363.892722145457;5370.919436208382;5234.633575649668;5315.760790764348;5316.341829085457;5355.116405793001;5357.01790337562;5207;5208.661437006583;5210.739463026909;5236.345456817898;5239.502247286502;5329.120695085423;5335.386430560535;5360.303160589098;5361.839144825662;5373.301470808769;5139.820106296262;5244.108501554669;5261.237120617566;5270.631055826056;5297.708626025606 06:46:59 INFO - PROCESS | 1728 | |5;yui.html;614.2230078950128;628.9528285378598;652.0219670494256;671.5131529641008;714.5962531169889;13822.17066975981;14653.487127950524;14713;14713.48502802402;14718.190499522521;13954.651133721662;14131;14134.222617756019;14185.43258269668;14233.430662773477;528.8651393894552;625.9718312675941;645.644895307579;652.3443938841458;652.9804105876831;535.6143576624823;646.767163821024;659.2155335151178;667.3360006793248;668.1614573709987;671.2247354305784;688.7451642892112;692.840646651268;696.5646470955653;696.7596179318152;550.9090999984998;661.0810972747885;681.1281559603715;686.8248596607883;695.0686080651951;405.43644334375244;491.1552130335817;494.9678270912379;501.1455468426324;508.7303729023623;13876.653083672887;13962.58112256634;13963.371648275852;13984.091034082752;13993.230372329517;12219.145117264192;12386.256824590464;12400.193987390881;12454.626361209088;13072.542461013818;12921.353932303347;13125.803112953314;13162.60512184636;13411.262380569065;13502.729945401037;4245.724027938174;4256.851010214658;4294.1197054604045;4349.804258808361;4382.758948257844;75070;80030;80231;80884.59557702174;81739.5913020437;10140.44227567476;10154.746131346776;10269.332493388056;10319.277650564389;10540.156787456985;19555.120019598868;19562.70655940162;19591.804081959282;19644;19646.607067858564;6829.624370659562;6950.860982780316;7060;7110.217876033632;7237.855242895218;3692.6491983262085;3820.388011268052;3823.885283441501;3830.712696547714;3898.8440462381186;2823.957640635392;2874.3101655602504;2922.1379692990618;2937.4418860416454;2953.394554116401;530.3397270398378;534.8743045384332;538.1389776357796;542.9972850135724;545.8171512543342;633.2148136311033;649.3960616626488;652.7323797243107;669.8794217040979;687.2371667449128;8590.598685944751;8885.600147993357;8887.333449991276;9074.31942604294;9351.205147562398;578.6354596604197;601.3385276196236;618.523736722721;632.5414074795736;729.879569870976;4323.54602766705;4556.498785133633;4573.131105090022;4599.517050709695;4782.282657601318;104007;109624;116206.41896790462;118945.81054189347;119530.4023479877;98030;98842.5057874706;99585;102166;103494;86103.13896861076;89781;90276.54861725781;90436.5478172618;91065.54467227754;8591.484510929364;8914.465132092095;8973.596188171417;9046;9114.36199466031;48812.2678159835;50122.49877501178;51595.74468085088;52159.73920130527;52453.21320180124;53133.405997820126;63018;64910.67544662247;66941.66529167323;69134;12405.565805196775;12834.29411382383;12899.806502902651;13149;13417.865821341662;14876.7768483475;15157.393704251706;15180.544583662508;15269.236538173269;15408.768868466757;13724.93137534306;13959.790603141164;14316.92623053296;14522;14794.926025369805;13980.161190328612;14015.719685606435;14122.646933826736;14382.928085359506;14470;13412.798808017693;14078.577642670736;14364.784528232294;14385.424583016562;14642.462541433422;11282.234070465747;11429.542818287175;11689.415529223676;11800.2329848563;11845.822312665145;11187.776244474902;11271.985521303122;11414;11428;11467.770644586895 06:46:59 INFO - PROCESS | 1728 | __end_tp_report 06:46:59 INFO - PROCESS | 1728 | __start_cc_report 06:46:59 INFO - PROCESS | 1728 | _x_x_mozilla_cycle_collect,1931 06:46:59 INFO - PROCESS | 1728 | __end_cc_report 06:46:59 INFO - PROCESS | 1728 | __startTimestamp1476366419348__endTimestamp 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | ------- Summary: start ------- 06:46:59 INFO - PROCESS | 1728 | Number of tests: 6 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#0] dojo.html Cycles:165 Average:4458.99 Median:4663.51 stddev:561.28 (12.0%) stddev-sans-first:543.03 06:46:59 INFO - PROCESS | 1728 | Values: 2567.2 2606.2 2612.0 2679.6 2684.0 4536.1 4574.8 4628.5 4640.6 4667.7 4523.6 4564.0 4597.0 4611.9 4670.8 4004.6 4026.0 4052.6 4055.7 4076.5 4004.2 4015.4 4021.8 4083.9 4128.3 4036.2 4114.6 4125.3 4141.2 4155.7 3935.7 3936.3 4068.0 4099.6 4102.4 4023.4 4029.3 4076.3 4102.2 4117.6 4450.1 4458.9 4570.1 4583.2 4586.9 4700.2 4813.1 4827.1 4895.0 4913.4 4777.3 4836.7 4842.4 4852.9 4862.9 4507.2 4539.4 4584.9 4589.8 4604.3 4949.7 4953.3 5035.8 5045.8 5057.6 4643.2 4663.5 4709.1 4729.7 4845.5 4719.8 4779.0 4795.1 4803.7 4811.7 4725.9 4771.4 4784.6 4790.3 4797.7 4791.7 4798.7 4802.0 4807.3 4811.7 3255.5 3265.3 3290.0 3297.3 3299.2 2910.3 2978.4 2989.4 3018.5 3032.2 4492.3 4499.9 4576.3 4590.2 4596.3 4844.4 4848.2 4869.0 4923.8 4946.3 4440.6 4455.9 4465.9 4488.9 4589.4 4033.0 4046.3 4132.4 4139.1 4140.8 4961.0 4977.7 5096.2 5099.6 5108.3 4763.7 4780.5 4826.5 4940.5 4962.3 4775.9 4883.4 4895.4 4970.1 4977.8 4688.0 4693.8 4705.4 4716.7 4747.1 4810.8 4846.0 4846.3 4849.4 4850.3 4699.1 4706.7 4712.5 4714.4 4720.8 4648.5 4662.7 4741.9 4742.1 4745.3 4618.7 4624.9 4639.7 4640.4 4642.4 4664.9 4726.9 4750.5 4761.4 4806.3 4660.3 4675.4 4755.0 4757.8 4770.5 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#1] ext.html Cycles:180 Average:52105.42 Median:36231.73 stddev:103084.21 (284.5%) stddev-sans-first:103300.63 06:46:59 INFO - PROCESS | 1728 | Values: 524.0 560.4 561.1 569.4 571.5 28353.6 28952.6 30486.4 30496.2 30506.7 30100.4 30872.5 41392.2 41876.4 42347.6 8366.7 8431.0 8456.5 8531.9 8538.0 8349.4 8451.5 8476.2 8477.0 8487.2 6894.0 7052.8 7053.9 7213.6 8472.6 6940.2 6981.7 9935.4 10138.0 10159.9 9988.9 10169.1 10172.8 10180.3 10196.7 38428.4 38998.8 39044.0 39108.2 39122.2 3188.0 3313.7 3409.0 3598.7 3728.0 30411.4 30724.7 30951.5 31733.9 31799.2 3456.5 3537.7 3591.7 3597.2 3611.3 271688.0 310620.0 312321.0 315115.0 315855.0 50713.7 50829.5 51137.7 51149.5 51188.0 25329.0 26577.6 26669.2 52933.5 53033.0 13307.6 13516.0 13612.8 13620.7 13652.9 10232.3 10278.8 10297.2 10307.0 10307.4 17042.9 17087.3 17119.2 17133.7 17148.3 5423.8 6608.1 6633.7 6677.2 6689.0 812.9 873.3 892.6 900.6 911.4 27549.0 28378.4 28603.9 28692.1 28781.7 929.3 934.7 962.4 967.8 973.0 14361.7 14489.8 14961.8 14966.1 15149.4 450789.0 452072.0 457007.0 458809.0 459229.0 46523.3 49584.3 49774.3 49794.8 49846.3 1022.3 1029.5 1129.2 1155.6 1179.1 27785.7 28112.0 29007.0 29148.3 29299.3 42292.0 42923.8 43118.8 43139.4 43332.8 379212.0 392202.0 392314.0 394797.0 396966.0 21329.8 21985.6 22451.6 22468.9 22610.9 43148.1 43752.0 43867.8 43903.1 43947.6 36181.8 36281.6 36552.6 36834.3 38009.4 36752.3 36777.8 36930.8 37528.4 37649.0 35332.5 35878.0 35951.0 35971.2 36018.0 31330.7 31487.8 33308.3 33711.2 34436.3 25012.2 25194.1 25607.1 25639.6 25951.2 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#2] jquery.html Cycles:180 Average:25093.29 Median:385833.50 stddev:63545.30 (16.5%) stddev-sans-first:63696.26 06:46:59 INFO - PROCESS | 1728 | Values: 286.7 306.8 311.3 313.0 315.5 13373.5 14021.9 17496.0 17629.6 18000.6 17308.9 17643.9 17725.8 17764.0 17967.8 5222.3 5464.9 5478.3 5484.8 5490.5 5169.1 5427.7 5440.9 5449.2 5454.9 5283.2 5380.4 5382.9 5394.3 5396.2 5182.0 5255.1 5281.7 5303.3 5370.3 4447.8 4491.3 4593.1 4593.2 4597.2 16969.8 20129.3 20889.0 20956.9 22529.4 1842.9 1856.6 1868.7 2001.1 2106.3 8269.3 8707.3 8795.8 11295.5 13612.3 1691.5 1757.8 1790.6 1798.5 1829.1 77539.2 77541.0 81116.6 81304.0 86710.6 8635.2 8692.2 8946.5 9037.5 9084.8 11136.4 11471.1 11612.9 11613.9 11629.1 6713.0 6781.3 6873.8 6897.9 6938.2 6828.7 6830.8 6850.8 6952.8 6958.8 3678.9 3686.6 3691.3 3732.4 3760.1 1518.5 1575.0 1581.3 1586.7 1586.9 674.8 845.1 885.6 892.5 945.7 17487.3 18061.7 18104.7 18541.8 18698.3 826.3 828.3 844.8 855.1 856.7 9565.4 9983.5 10366.9 10679.6 10865.2 370570.0 379287.0 379963.0 385124.0 386543.0 58741.7 76405.0 89462.0 93025.0 97256.0 979.6 997.6 1024.7 1026.7 1031.8 15739.8 16859.6 16916.5 16973.2 17534.7 44503.3 45321.8 46112.0 46209.8 46757.0 60668.0 61413.7 62023.7 62649.0 62940.4 7174.7 7296.2 7310.0 7371.8 7381.2 10937.2 12884.5 12916.6 13068.1 13284.3 10281.9 10544.5 10761.5 11179.3 11361.9 10539.4 10610.1 10793.6 10872.6 10961.8 10599.3 11101.1 11128.9 11139.2 11374.5 7169.3 7288.1 7309.3 7352.4 7384.4 7115.0 10624.4 10689.4 10848.5 10975.7 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#3] mootools.html Cycles:180 Average:4475.95 Median:5810.80 stddev:2525.13 (43.5%) stddev-sans-first:2520.26 06:46:59 INFO - PROCESS | 1728 | Values: 1206.7 1221.9 1286.6 1545.7 1548.9 6250.8 6421.4 6597.9 7177.9 7192.3 6450.5 6526.6 6723.6 6826.3 6837.2 1799.4 1813.4 1828.9 1838.6 1849.4 1784.9 1792.5 1806.3 1806.4 1832.7 1594.8 1621.3 1779.9 1822.8 1832.5 1831.2 1846.5 1847.2 1848.9 1852.9 1726.9 1735.2 1744.4 1763.8 1772.5 6730.4 7007.9 7010.5 7035.2 7037.0 4064.6 4169.7 4172.3 4174.9 4182.2 2704.9 2774.7 2814.6 2816.6 2819.1 798.9 834.4 838.2 838.2 845.6 8681.7 8818.1 8827.4 8842.7 8860.0 3608.1 3714.8 3732.7 3735.4 3763.5 7004.6 7223.6 7255.0 7263.4 7279.7 4176.0 4184.1 4188.2 4189.9 4191.2 3962.6 3967.4 3974.5 3977.6 3988.4 2753.4 2833.2 2883.4 2886.2 2888.7 815.3 825.9 826.1 835.0 853.5 756.4 764.3 789.5 790.3 799.9 4223.9 4339.7 4398.0 4450.9 4547.3 323.2 323.3 330.0 331.0 338.2 2947.4 3049.7 3098.9 3102.9 3138.8 8004.8 8034.3 8278.7 8334.2 8388.2 8321.3 8345.7 8365.8 8368.0 8370.7 6533.7 6677.3 6678.1 6707.8 6709.7 4495.1 4506.5 4516.1 4533.8 4656.3 7757.5 7908.6 7908.9 7913.6 7918.8 7570.1 7964.1 8024.4 8026.8 8031.2 5875.6 6037.9 6038.2 6038.9 6052.0 6800.5 6827.9 6835.5 6847.8 6851.2 6221.5 6250.1 6307.1 6354.7 6371.4 6106.6 6165.5 6182.4 6270.1 6271.2 5803.8 5817.8 5831.2 5847.4 5852.3 3737.9 3779.4 3863.4 3868.9 3914.6 3926.1 3951.4 3977.6 4005.7 4024.1 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#4] prototype.html Cycles:180 Average:4350.96 Median:5031.96 stddev:1371.53 (27.3%) stddev-sans-first:1340.19 06:46:59 INFO - PROCESS | 1728 | Values: 238.8 253.6 254.5 256.7 257.2 4827.2 4900.3 5138.4 5141.2 5177.5 5006.4 5061.7 5072.8 5074.0 5075.4 3730.3 3756.0 3757.7 3760.9 3769.6 3696.4 3741.9 3750.8 3758.9 3759.6 3717.2 3722.3 3724.6 3729.5 3730.6 3674.5 3714.5 3730.5 3738.4 3741.9 3213.0 3216.9 3220.4 3220.6 3223.1 4979.1 5027.5 5031.5 5032.4 5044.1 3958.0 4233.3 4254.6 4256.4 4263.7 5235.4 5242.0 5251.9 5266.8 5278.1 3304.3 3406.7 3423.2 3456.9 3474.9 5471.9 5519.8 5531.9 5539.2 5540.9 3529.4 3533.5 3536.2 3537.5 3538.8 3566.6 3569.9 3571.2 3576.5 3580.5 4226.6 4247.9 4251.6 4257.0 4269.7 5195.2 5273.2 5318.3 5318.8 5328.4 1223.4 1229.3 1229.6 1230.9 1238.7 730.8 746.5 768.9 769.7 770.9 4443.8 4484.8 4492.5 4532.3 4537.8 4918.2 4922.5 4931.7 4937.3 4940.4 4506.9 4514.6 4798.4 4906.7 4976.8 2895.0 2909.8 2912.7 2920.3 2931.9 5677.7 5694.5 5699.3 5701.6 5717.3 5300.7 5569.8 5613.7 5631.2 5649.6 5698.2 5701.9 5704.5 5720.7 5722.2 5467.2 5504.5 5514.2 5523.7 5535.8 5445.9 5452.4 5463.6 5468.8 5469.7 5452.8 5581.5 5598.6 5651.2 5712.2 3449.8 3463.9 3538.1 3541.1 3547.7 5506.6 5511.5 5515.2 5517.6 5521.3 5227.2 5300.9 5356.9 5363.9 5370.9 5234.6 5315.8 5316.3 5355.1 5357.0 5207.0 5208.7 5210.7 5236.3 5239.5 5329.1 5335.4 5360.3 5361.8 5373.3 5139.8 5244.1 5261.2 5270.6 5297.7 06:46:59 INFO - PROCESS | 1728 | 06:46:59 INFO - PROCESS | 1728 | [#5] yui.html Cycles:180 Average:20435.73 Median:4308.83 stddev:29896.94 (693.9%) stddev-sans-first:29943.76 06:46:59 INFO - PROCESS | 1728 | Values: 614.2 629.0 652.0 671.5 714.6 13822.2 14653.5 14713.0 14713.5 14718.2 13954.7 14131.0 14134.2 14185.4 14233.4 528.9 626.0 645.6 652.3 653.0 535.6 646.8 659.2 667.3 668.2 671.2 688.7 692.8 696.6 696.8 550.9 661.1 681.1 686.8 695.1 405.4 491.2 495.0 501.1 508.7 13876.7 13962.6 13963.4 13984.1 13993.2 12219.1 12386.3 12400.2 12454.6 13072.5 12921.4 13125.8 13162.6 13411.3 13502.7 4245.7 4256.9 4294.1 4349.8 4382.8 75070.0 80030.0 80231.0 80884.6 81739.6 10140.4 10154.7 10269.3 10319.3 10540.2 19555.1 19562.7 19591.8 19644.0 19646.6 6829.6 6950.9 7060.0 7110.2 7237.9 3692.6 3820.4 3823.9 3830.7 3898.8 2824.0 2874.3 2922.1 2937.4 2953.4 530.3 534.9 538.1 543.0 545.8 633.2 649.4 652.7 669.9 687.2 8590.6 8885.6 8887.3 9074.3 9351.2 578.6 601.3 618.5 632.5 729.9 4323.5 4556.5 4573.1 4599.5 4782.3 104007.0 109624.0 116206.4 118945.8 119530.4 98030.0 98842.5 99585.0 102166.0 103494.0 86103.1 89781.0 90276.5 90436.5 91065.5 8591.5 8914.5 8973.6 9046.0 9114.4 48812.3 50122.5 51595.7 52159.7 52453.2 53133.4 63018.0 64910.7 66941.7 69134.0 12405.6 12834.3 12899.8 13149.0 13417.9 14876.8 15157.4 15180.5 15269.2 15408.8 13724.9 13959.8 14316.9 14522.0 14794.9 13980.2 14015.7 14122.6 14382.9 14470.0 13412.8 14078.6 14364.8 14385.4 14642.5 11282.2 11429.5 11689.4 11800.2 11845.8 11187.8 11272.0 11414.0 11428.0 11467.8 06:46:59 INFO - PROCESS | 1728 | -------- Summary: end -------- 06:46:59 INFO - PROCESS | 1728 | 06:47:00 INFO - TEST-INFO | 1728: exit 0 06:47:00 INFO - TEST-OK | dromaeo_css | took 1120560ms 06:47:00 INFO - TEST-START | kraken 06:47:00 INFO - operating with platform_type : w7_ 06:47:00 INFO - Initialising browser for kraken test... 06:47:00 INFO - TEST-INFO | started process 1496 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpadnwsb\profile http://localhost:49598/getInfo.html) 06:47:05 INFO - PROCESS | 1496 | __metrics Screen width/height:1600/1200 06:47:05 INFO - PROCESS | 1496 | colorDepth:24 06:47:05 INFO - PROCESS | 1496 | Browser inner width/height: 1010/674 06:47:05 INFO - PROCESS | 1496 | __metrics 06:47:05 INFO - PROCESS | 1496 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:47:05 INFO - PROCESS | 1496 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:47:05 INFO - PROCESS | 1496 | console.error: 06:47:05 INFO - PROCESS | 1496 | Message: Error: Connection closed before committing the transaction. 06:47:05 INFO - PROCESS | 1496 | Stack: 06:47:05 INFO - PROCESS | 1496 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 06:47:05 INFO - PROCESS | 1496 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:47:05 INFO - PROCESS | 1496 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:47:05 INFO - PROCESS | 1496 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:47:05 INFO - PROCESS | 1496 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:47:05 INFO - PROCESS | 1496 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 06:47:05 INFO - PROCESS | 1496 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:47:05 INFO - PROCESS | 1496 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 06:47:05 INFO - PROCESS | 1496 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 06:47:05 INFO - PROCESS | 1496 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 06:47:05 INFO - PROCESS | 1496 | Barrier/this.client.addBlocker/promise13.1% 06:48:03 INFO - TinderboxPrint: I/O read bytes / time
197,887,488 / 2,185,020 06:48:03 INFO - TinderboxPrint: I/O write bytes / time
489,750,016 / 3,766,770 06:48:03 INFO - TinderboxPrint: CPU idle
8,257.4 (86.9%) 06:48:03 INFO - TinderboxPrint: CPU user
1,209.0 (12.7%) 06:48:03 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 36611072; Read time: 0; Write time: 391460 06:48:03 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 197887488; Write bytes: 416587264; Read time: 2185020; Write time: 3259720 06:48:04 INFO - Running post-run listener: _upload_blobber_files 06:48:04 INFO - Blob upload gear active. 06:48:04 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 06:48:04 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 06:48:04 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', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 06:48:04 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 Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 06:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 06:48:04 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 06:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:05 INFO - (blobuploader) - INFO - Done attempting. 06:48:05 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 06:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:06 INFO - (blobuploader) - INFO - Done attempting. 06:48:06 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 06:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:48:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:48:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:48:14 INFO - (blobuploader) - INFO - Done attempting. 06:48:14 INFO - (blobuploader) - INFO - Iteration through files over. 06:48:14 INFO - Return code: 0 06:48:14 INFO - rmtree: C:\slave\test\build\uploaded_files.json 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 06:48:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ed2a8a39af4275e1b920f6ee4358318969590ded2afe34342e275ba718e6b28d6df2df9f2e60ae0298bf89c00db3f071207fcdd27de3f3d4a83e17c118bf94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9b8161ed27e79c63c15f6f8507654cb469a4dadfe1763db4899b0a60909196c0e628ee510194d62d20ea4e832be9cde480f13fd63e4c61a5b9334c1761e9f1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/840b6928ce662290cdbdc47b1fe1014ba2a0c03ea91e9ba792f7c709a9b7fd9d6af181618fdb3382e73be2e5fe1d252d62fd21eece5ddce0b0fa6ca122ceb5ec"} 06:48:14 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 06:48:14 INFO - Writing to file C:\slave\test\properties\blobber_files 06:48:14 INFO - Contents: 06:48:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ed2a8a39af4275e1b920f6ee4358318969590ded2afe34342e275ba718e6b28d6df2df9f2e60ae0298bf89c00db3f071207fcdd27de3f3d4a83e17c118bf94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9b8161ed27e79c63c15f6f8507654cb469a4dadfe1763db4899b0a60909196c0e628ee510194d62d20ea4e832be9cde480f13fd63e4c61a5b9334c1761e9f1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/840b6928ce662290cdbdc47b1fe1014ba2a0c03ea91e9ba792f7c709a9b7fd9d6af181618fdb3382e73be2e5fe1d252d62fd21eece5ddce0b0fa6ca122ceb5ec"} 06:48:14 INFO - Running post-run listener: copy_logs_to_upload_dir 06:48:14 INFO - Copying logs to upload dir... 06:48:14 INFO - mkdir: C:\slave\test\build\upload\logs 06:48:14 INFO - Copying logs to upload dir... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... 06:48:14 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1424.656000 ========= master_lag: -0.23 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 44 secs) (at 2016-10-13 06:48:14.380123) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 06:48:14.380961) ========= '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-136 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-136 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-136 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/Fx-Team/sha512/7ed2a8a39af4275e1b920f6ee4358318969590ded2afe34342e275ba718e6b28d6df2df9f2e60ae0298bf89c00db3f071207fcdd27de3f3d4a83e17c118bf94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9b8161ed27e79c63c15f6f8507654cb469a4dadfe1763db4899b0a60909196c0e628ee510194d62d20ea4e832be9cde480f13fd63e4c61a5b9334c1761e9f1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/840b6928ce662290cdbdc47b1fe1014ba2a0c03ea91e9ba792f7c709a9b7fd9d6af181618fdb3382e73be2e5fe1d252d62fd21eece5ddce0b0fa6ca122ceb5ec"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-win32-pgo/1476354614/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ed2a8a39af4275e1b920f6ee4358318969590ded2afe34342e275ba718e6b28d6df2df9f2e60ae0298bf89c00db3f071207fcdd27de3f3d4a83e17c118bf94f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9b8161ed27e79c63c15f6f8507654cb469a4dadfe1763db4899b0a60909196c0e628ee510194d62d20ea4e832be9cde480f13fd63e4c61a5b9334c1761e9f1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/840b6928ce662290cdbdc47b1fe1014ba2a0c03ea91e9ba792f7c709a9b7fd9d6af181618fdb3382e73be2e5fe1d252d62fd21eece5ddce0b0fa6ca122ceb5ec"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 06:48:14.496336) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 06:48:14.496667) ========= '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-136 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-136 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-136 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-10-13 06:48:14.608059) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-13 06:48:14.608371) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-13 06:48:15.130165) ========= ========= Total master_lag: -0.13 =========