builder: autoland_win7_ix_test-dromaeojs-e10s slave: t-w732-ix-100 starttime: 1472031109.45 results: success (0) buildid: 20160823233815 builduid: f9297c484620454fb39dac9189e3daf9 revision: 65b00e9620d16d97576cc90f0a4e0129c0e241be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 02:31:49.450332) ========= master: http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 02:31:49.450800) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 02:31:49.451075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 02:31:49.665078) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 02:31:49.665562) ========= '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-100 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-100 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-100 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 --02:31:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 8.87 MB/s 02:31:50 (8.87 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.212000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 02:31:50.908122) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-08-24 02:31:50.908463) ========= '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-100 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-100 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-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=14.506000 ========= master_lag: 0.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-08-24 02:32:05.415883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 02:32:05.416244) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 65b00e9620d16d97576cc90f0a4e0129c0e241be --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 65b00e9620d16d97576cc90f0a4e0129c0e241be --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 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-100 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-100 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-08-24 02:32:05,519 truncating revision to first 12 chars 2016-08-24 02:32:05,519 Setting DEBUG logging. 2016-08-24 02:32:05,519 attempt 1/10 2016-08-24 02:32:05,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/65b00e9620d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 02:32:05,982 unpacking tar archive at: autoland-65b00e9620d1/testing/mozharness/ 2016-08-24 02:32:06,174 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.195000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-24 02:32:06.650993) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 02:32:06.651313) ========= script_repo_revision: 65b00e9620d16d97576cc90f0a4e0129c0e241be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 02:32:06.651749) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 02:32:06.652023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 02:32:06.665274) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-08-24 02:32:06.665690) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 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-100 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-100 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 02:32:06 INFO - MultiFileLogger online at 20160824 02:32:06 in C:\slave\test 02:32:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 02:32:06 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 02:32:06 INFO - {'append_to_log': False, 02:32:06 INFO - 'base_work_dir': 'C:\\slave\\test', 02:32:06 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 02:32:06 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 02:32:06 INFO - 'branch': 'Autoland-Non-PGO', 02:32:06 INFO - 'buildbot_json_path': 'buildprops.json', 02:32:06 INFO - 'config_files': ('talos/windows_config.py',), 02:32:06 INFO - 'default_actions': ('clobber', 02:32:06 INFO - 'read-buildbot-config', 02:32:06 INFO - 'download-and-extract', 02:32:06 INFO - 'populate-webroot', 02:32:06 INFO - 'create-virtualenv', 02:32:06 INFO - 'install', 02:32:06 INFO - 'run-tests'), 02:32:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:32:06 INFO - 'download_minidump_stackwalk': True, 02:32:06 INFO - 'download_symbols': 'ondemand', 02:32:06 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 02:32:06 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 02:32:06 INFO - 'hg': 'c:/mozilla-build/hg/hg', 02:32:06 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 02:32:06 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 02:32:06 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 02:32:06 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 02:32:06 INFO - 'C:/mozilla-build/tooltool.py'), 02:32:06 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 02:32:06 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 02:32:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:32:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:32:06 INFO - 'installer_path': 'installer.exe', 02:32:06 INFO - 'log_level': 'info', 02:32:06 INFO - 'log_name': 'talos', 02:32:06 INFO - 'log_to_console': True, 02:32:06 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 02:32:06 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 02:32:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 02:32:06 INFO - 'opt_config_files': (), 02:32:06 INFO - 'pip_index': False, 02:32:06 INFO - 'sps_profile': False, 02:32:06 INFO - 'sps_profile_interval': 0, 02:32:06 INFO - 'suite': 'dromaeojs-e10s', 02:32:06 INFO - 'system_bits': '32', 02:32:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:32:06 INFO - 'title': 'T-W732-IX-100', 02:32:06 INFO - 'use_talos_json': True, 02:32:06 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 02:32:06 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 02:32:06 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 02:32:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:32:06 INFO - 'work_dir': 'build'} 02:32:06 INFO - [mozharness: 2016-08-24 09:32:06.904000Z] Running clobber step. 02:32:06 INFO - Running pre-action listener: _resource_record_pre_action 02:32:06 INFO - Running main action method: clobber 02:32:06 INFO - rmtree: C:\slave\test\build 02:32:06 INFO - Using _rmtree_windows ... 02:32:06 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 02:33:53 INFO - Running post-action listener: _resource_record_post_action 02:33:53 INFO - [mozharness: 2016-08-24 09:33:53.514000Z] Finished clobber step (success) 02:33:53 INFO - [mozharness: 2016-08-24 09:33:53.514000Z] Running read-buildbot-config step. 02:33:53 INFO - Running pre-action listener: _resource_record_pre_action 02:33:53 INFO - Running main action method: read_buildbot_config 02:33:53 INFO - Using buildbot properties: 02:33:53 INFO - { 02:33:53 INFO - "project": "", 02:33:53 INFO - "product": "firefox", 02:33:53 INFO - "script_repo_revision": "production", 02:33:53 INFO - "builddir": "autoland_win7_ix_test-dromaeojs-e10s", 02:33:53 INFO - "repository": "", 02:33:53 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs-e10s", 02:33:53 INFO - "buildid": "20160823233815", 02:33:53 INFO - "pgo_build": "False", 02:33:53 INFO - "basedir": "C:\\slave\\test", 02:33:53 INFO - "buildnumber": 115, 02:33:53 INFO - "slavename": "t-w732-ix-100", 02:33:53 INFO - "master": "http://buildbot-master127.bb.releng.scl3.mozilla.com:8201/", 02:33:53 INFO - "platform": "win7_ix", 02:33:53 INFO - "scheduler": "tests-autoland-win32-talos-7-3600", 02:33:53 INFO - "branch": "autoland", 02:33:53 INFO - "repo_path": "integration/autoland", 02:33:53 INFO - "revision": "65b00e9620d16d97576cc90f0a4e0129c0e241be", 02:33:53 INFO - "stage_platform": "win32", 02:33:53 INFO - "builduid": "f9297c484620454fb39dac9189e3daf9", 02:33:53 INFO - "slavebuilddir": "test" 02:33:53 INFO - } 02:33:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip. 02:33:53 INFO - Running post-action listener: _resource_record_post_action 02:33:53 INFO - [mozharness: 2016-08-24 09:33:53.517000Z] Finished read-buildbot-config step (success) 02:33:53 INFO - [mozharness: 2016-08-24 09:33:53.517000Z] Running download-and-extract step. 02:33:53 INFO - Running pre-action listener: _resource_record_pre_action 02:33:53 INFO - Running main action method: download_and_extract 02:33:53 INFO - mkdir: C:\slave\test\build\tests 02:33: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')]} 02:33:53 INFO - trying https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.test_packages.json 02:33:53 INFO - Downloading https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-51.0a1.en-US.win32.test_packages.json 02:33:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-51.0a1.en-US.win32.test_packages.json'}, attempt #1 02:33:54 INFO - Downloaded 1274 bytes. 02:33:54 INFO - Reading from file C:\slave\test\build\firefox-51.0a1.en-US.win32.test_packages.json 02:33:54 INFO - Using the following test package requirements: 02:33:54 INFO - {u'common': [u'firefox-51.0a1.en-US.win32.common.tests.zip'], 02:33:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.cppunittest.tests.zip'], 02:33:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.gtest.tests.zip'], 02:33:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'jsshell-win32.zip'], 02:33:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.mochitest.tests.zip'], 02:33:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.win32.common.tests.zip'], 02:33:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.reftest.tests.zip'], 02:33:54 INFO - u'talos': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.talos.tests.zip'], 02:33:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.web-platform.tests.zip'], 02:33:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.win32.common.tests.zip', 02:33:54 INFO - u'firefox-51.0a1.en-US.win32.xpcshell.tests.zip']} 02:33:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:33:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:33:54 INFO - trying https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip 02:33:54 INFO - Downloading https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-51.0a1.en-US.win32.common.tests.zip 02:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-51.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:33:55 INFO - Downloaded 17197178 bytes. 02:33:55 INFO - Using ZipFile to extract C:\slave\test\build\firefox-51.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\tests 02:34:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.win32.common.tests.zip', u'firefox-51.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 02:34: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')]} 02:34:19 INFO - trying https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip 02:34:19 INFO - Downloading https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-51.0a1.en-US.win32.common.tests.zip 02:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-51.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:34:20 INFO - Downloaded 17197178 bytes. 02:34:20 INFO - Using ZipFile to extract C:\slave\test\build\firefox-51.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\tests 02:34:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:30 INFO - trying https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.talos.tests.zip 02:34:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\firefox-51.0a1.en-US.win32.talos.tests.zip 02:34:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-51.0a1.en-US.win32.talos.tests.zip'}, attempt #1 02:34:31 INFO - Downloaded 14413981 bytes. 02:34:31 INFO - Using ZipFile to extract C:\slave\test\build\firefox-51.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\tests 02:34:31 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')]} 02:34:31 INFO - trying https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip 02:34:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip to C:\slave\test\build\firefox-51.0a1.en-US.win32.zip 02:34:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-51.0a1.en-US.win32.zip'}, attempt #1 02:34:33 INFO - Downloaded 53601730 bytes. 02:34:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip 02:34:33 INFO - mkdir: C:\slave\test\properties 02:34:33 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 02:34:33 INFO - Writing to file C:\slave\test\properties\build_url 02:34:33 INFO - Contents: 02:34:33 INFO - build_url:https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip 02:34:33 INFO - Running post-action listener: _resource_record_post_action 02:34:33 INFO - Running post-action listener: set_extra_try_arguments 02:34:33 INFO - [mozharness: 2016-08-24 09:34:33.770000Z] Finished download-and-extract step (success) 02:34:33 INFO - [mozharness: 2016-08-24 09:34:33.770000Z] Running populate-webroot step. 02:34:33 INFO - Running pre-action listener: _resource_record_pre_action 02:34:33 INFO - Running main action method: populate_webroot 02:34:33 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:34:33 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:34:33 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:34:33 INFO - u'--tppagecycles', 02:34:33 INFO - u'7'], 02:34:33 INFO - u'tests': [u'tsvgm']}}, 02:34:33 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:34:33 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 02:34:33 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 02:34:33 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 02:34:33 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:34:33 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:34:33 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'damp', u'tps']}, 02:34:33 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'tests': [u'damp', u'tps']}, 02:34:33 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'dromaeo_dom']}, 02:34:33 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:34:33 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'basic_compositor_video']}, 02:34:33 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 02:34:33 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'other-e10s': {u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'a11yr', 02:34:33 INFO - u'ts_paint', 02:34:33 INFO - u'tpaint', 02:34:33 INFO - u'sessionrestore', 02:34:33 INFO - u'sessionrestore_no_auto_restore', 02:34:33 INFO - u'tabpaint']}, 02:34:33 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'tsvgx', 02:34:33 INFO - u'tsvgr_opacity', 02:34:33 INFO - u'tart', 02:34:33 INFO - u'tscrollx', 02:34:33 INFO - u'cart']}, 02:34:33 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 02:34:33 INFO - u'tsvgr_opacity', 02:34:33 INFO - u'tart', 02:34:33 INFO - u'tscrollx', 02:34:33 INFO - u'cart']}, 02:34:33 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'talos_options': [u'--disable-e10s'], 02:34:33 INFO - u'tests': [u'tp5o']}, 02:34:33 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'tests': [u'tp5o']}, 02:34:33 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'talos_options': [u'--disable-e10s', 02:34:33 INFO - u'--xperf_path', 02:34:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:34:33 INFO - u'tests': [u'tp5n']}, 02:34:33 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:34:33 INFO - u'talos_options': [u'--xperf_path', 02:34:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:34:33 INFO - u'tests': [u'tp5n']}}, 02:34:33 INFO - u'talos.zip': {u'path': u'', 02:34:33 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:34:33 INFO - Running post-action listener: _resource_record_post_action 02:34:33 INFO - [mozharness: 2016-08-24 09:34:33.858000Z] Finished populate-webroot step (success) 02:34:33 INFO - [mozharness: 2016-08-24 09:34:33.858000Z] Running create-virtualenv step. 02:34:33 INFO - Running pre-action listener: _resource_record_pre_action 02:34:33 INFO - Running main action method: create_virtualenv 02:34:33 INFO - Creating virtualenv C:\slave\test\build/venv 02:34:33 INFO - mkdir: C:\slave\test\build\venv\Scripts 02:34:33 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 02:34:33 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'! 02:34:33 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 02:34:33 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 02:34:46 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 02:34:51 INFO - Installing distribute......................................................................................................................................................................................done. 02:34:52 INFO - Return code: 0 02:34:52 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 02:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:34:52 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:34:52 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 02:34:52 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 02:34:52 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:34:52 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:34:52 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:34:52 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:34:52 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:34:52 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:34:52 INFO - 'DCLOCATION': 'SCL3', 02:34:52 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:34:52 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:34:52 INFO - 'HOMEDRIVE': 'C:', 02:34:52 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:34:52 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:34:52 INFO - 'KTS_VERSION': '1.19c', 02:34:52 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:34:52 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:34:52 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:34:52 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:34:52 INFO - 'MOZ_NO_REMOTE': '1', 02:34:52 INFO - 'NO_EM_RESTART': '1', 02:34:52 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:34:52 INFO - 'OS': 'Windows_NT', 02:34:52 INFO - 'OURDRIVE': 'C:', 02:34:52 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;', 02:34:52 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:34:52 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:34:52 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:34:52 INFO - 'PROCESSOR_LEVEL': '6', 02:34:52 INFO - 'PROCESSOR_REVISION': '1e05', 02:34:52 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:34:52 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:34:52 INFO - 'PROMPT': '$P$G', 02:34:52 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:34:52 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:34:52 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:34:52 INFO - 'PWD': 'C:\\slave\\test', 02:34:52 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:34:52 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:34:52 INFO - 'SYSTEMDRIVE': 'C:', 02:34:52 INFO - 'SYSTEMROOT': 'C:\\windows', 02:34:52 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:34:52 INFO - 'TEST1': 'testie', 02:34:52 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:34:52 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:34:52 INFO - 'USERNAME': 'cltbld', 02:34:52 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:34:52 INFO - 'WINDIR': 'C:\\windows', 02:34:52 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:34:52 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:34:57 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:34:57 INFO - Downloading/unpacking pip>=1.5 02:35:01 INFO - Running setup.py egg_info for package pip 02:35:01 INFO - warning: no files found matching 'pip\cacert.pem' 02:35:01 INFO - warning: no files found matching '*.html' under directory 'docs' 02:35:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:35:01 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:35:01 INFO - Installing collected packages: pip 02:35:01 INFO - Found existing installation: pip 0.8.2 02:35:01 INFO - Uninstalling pip: 02:35:01 INFO - Successfully uninstalled pip 02:35:01 INFO - Running setup.py install for pip 02:35:01 INFO - warning: no files found matching 'pip\cacert.pem' 02:35:01 INFO - warning: no files found matching '*.html' under directory 'docs' 02:35:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:35:01 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:35:01 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 02:35:01 INFO - Successfully installed pip 02:35:01 INFO - Cleaning up... 02:35:01 INFO - Return code: 0 02:35:01 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 02:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:01 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:02 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 02:35:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:35:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:02 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:02 INFO - 'DCLOCATION': 'SCL3', 02:35:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:02 INFO - 'HOMEDRIVE': 'C:', 02:35:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:02 INFO - 'KTS_VERSION': '1.19c', 02:35:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:02 INFO - 'MOZ_NO_REMOTE': '1', 02:35:02 INFO - 'NO_EM_RESTART': '1', 02:35:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:02 INFO - 'OS': 'Windows_NT', 02:35:02 INFO - 'OURDRIVE': 'C:', 02:35:02 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:35:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:02 INFO - 'PROCESSOR_LEVEL': '6', 02:35:02 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:02 INFO - 'PROMPT': '$P$G', 02:35:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:02 INFO - 'PWD': 'C:\\slave\\test', 02:35:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:02 INFO - 'SYSTEMDRIVE': 'C:', 02:35:02 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:02 INFO - 'TEST1': 'testie', 02:35:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:02 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:02 INFO - 'USERNAME': 'cltbld', 02:35:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:02 INFO - 'WINDIR': 'C:\\windows', 02:35:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:04 INFO - Downloading/unpacking psutil>=3.1.1 02:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:05 INFO - Installing collected packages: psutil 02:35:05 INFO - Successfully installed psutil 02:35:05 INFO - Cleaning up... 02:35:05 INFO - Return code: 0 02:35:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 02:35:05 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')]} 02:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:05 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')]} 02:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:05 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:05 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 02:35:05 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 02:35:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:05 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:05 INFO - 'DCLOCATION': 'SCL3', 02:35:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:05 INFO - 'HOMEDRIVE': 'C:', 02:35:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:05 INFO - 'KTS_VERSION': '1.19c', 02:35:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:05 INFO - 'MOZ_NO_REMOTE': '1', 02:35:05 INFO - 'NO_EM_RESTART': '1', 02:35:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:05 INFO - 'OS': 'Windows_NT', 02:35:05 INFO - 'OURDRIVE': 'C:', 02:35:05 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;', 02:35:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:05 INFO - 'PROCESSOR_LEVEL': '6', 02:35:05 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:05 INFO - 'PROMPT': '$P$G', 02:35:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:05 INFO - 'PWD': 'C:\\slave\\test', 02:35:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:05 INFO - 'SYSTEMDRIVE': 'C:', 02:35:05 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:05 INFO - 'TEST1': 'testie', 02:35:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:05 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:05 INFO - 'USERNAME': 'cltbld', 02:35:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:05 INFO - 'WINDIR': 'C:\\windows', 02:35:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:35:05 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 02:35:05 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 02:35:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:35:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:35:06 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) 02:35:06 INFO - Installing collected packages: mozsystemmonitor 02:35:06 INFO - Running setup.py install for mozsystemmonitor 02:35:07 INFO - Successfully installed mozsystemmonitor 02:35:07 INFO - Cleaning up... 02:35:07 INFO - Return code: 0 02:35:07 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 02:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:07 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 02:35:07 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:35:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:07 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:07 INFO - 'DCLOCATION': 'SCL3', 02:35:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:07 INFO - 'HOMEDRIVE': 'C:', 02:35:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:07 INFO - 'KTS_VERSION': '1.19c', 02:35:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:07 INFO - 'MOZ_NO_REMOTE': '1', 02:35:07 INFO - 'NO_EM_RESTART': '1', 02:35:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:07 INFO - 'OS': 'Windows_NT', 02:35:07 INFO - 'OURDRIVE': 'C:', 02:35:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:35:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:07 INFO - 'PROCESSOR_LEVEL': '6', 02:35:07 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:07 INFO - 'PROMPT': '$P$G', 02:35:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:07 INFO - 'PWD': 'C:\\slave\\test', 02:35:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:07 INFO - 'SYSTEMDRIVE': 'C:', 02:35:07 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:07 INFO - 'TEST1': 'testie', 02:35:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:07 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:07 INFO - 'USERNAME': 'cltbld', 02:35:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:07 INFO - 'WINDIR': 'C:\\windows', 02:35:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:07 INFO - Downloading/unpacking jsonschema==2.5.1 02:35:07 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 02:35:07 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 02:35:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:35:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:35: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 02:35: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 02:35:08 INFO - Downloading functools32-3.2.3-2.tar.gz 02:35:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 02:35:08 INFO - warning: no files found matching '*.txt' 02:35:08 INFO - no previously-included directories found matching 'build' 02:35:08 INFO - no previously-included directories found matching 'dist' 02:35:08 INFO - no previously-included directories found matching '.git*' 02:35:08 INFO - Installing collected packages: jsonschema, functools32 02:35:08 INFO - Running setup.py install for functools32 02:35:09 INFO - warning: no files found matching '*.txt' 02:35:09 INFO - no previously-included directories found matching 'build' 02:35:09 INFO - no previously-included directories found matching 'dist' 02:35:09 INFO - no previously-included directories found matching '.git*' 02:35:09 INFO - Successfully installed jsonschema functools32 02:35:09 INFO - Cleaning up... 02:35:10 INFO - Return code: 0 02:35:10 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 02:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:10 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:10 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 02:35:10 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 02:35:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:10 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:10 INFO - 'DCLOCATION': 'SCL3', 02:35:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:10 INFO - 'HOMEDRIVE': 'C:', 02:35:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:10 INFO - 'KTS_VERSION': '1.19c', 02:35:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:10 INFO - 'MOZ_NO_REMOTE': '1', 02:35:10 INFO - 'NO_EM_RESTART': '1', 02:35:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:10 INFO - 'OS': 'Windows_NT', 02:35:10 INFO - 'OURDRIVE': 'C:', 02:35:10 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;', 02:35:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:10 INFO - 'PROCESSOR_LEVEL': '6', 02:35:10 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:10 INFO - 'PROMPT': '$P$G', 02:35:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:10 INFO - 'PWD': 'C:\\slave\\test', 02:35:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:10 INFO - 'SYSTEMDRIVE': 'C:', 02:35:10 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:10 INFO - 'TEST1': 'testie', 02:35:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:10 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:10 INFO - 'USERNAME': 'cltbld', 02:35:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:10 INFO - 'WINDIR': 'C:\\windows', 02:35:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 02:35:10 INFO - Cleaning up... 02:35:10 INFO - Return code: 0 02:35:10 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 02:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:10 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:10 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 02:35:10 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 02:35:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:10 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:10 INFO - 'DCLOCATION': 'SCL3', 02:35:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:10 INFO - 'HOMEDRIVE': 'C:', 02:35:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:10 INFO - 'KTS_VERSION': '1.19c', 02:35:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:10 INFO - 'MOZ_NO_REMOTE': '1', 02:35:10 INFO - 'NO_EM_RESTART': '1', 02:35:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:10 INFO - 'OS': 'Windows_NT', 02:35:10 INFO - 'OURDRIVE': 'C:', 02:35:10 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;', 02:35:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:10 INFO - 'PROCESSOR_LEVEL': '6', 02:35:10 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:10 INFO - 'PROMPT': '$P$G', 02:35:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:10 INFO - 'PWD': 'C:\\slave\\test', 02:35:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:10 INFO - 'SYSTEMDRIVE': 'C:', 02:35:10 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:10 INFO - 'TEST1': 'testie', 02:35:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:10 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:10 INFO - 'USERNAME': 'cltbld', 02:35:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:10 INFO - 'WINDIR': 'C:\\windows', 02:35:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:35:10 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 02:35:10 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 02:35:11 INFO - Downloading blobuploader-1.2.4.tar.gz 02:35:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:35:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:35:11 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 02:35:11 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 02:35:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 02:35:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:35:12 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 02:35:12 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 02:35:12 INFO - Downloading docopt-0.6.1.tar.gz 02:35:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 02:35:12 INFO - Installing collected packages: blobuploader, requests, docopt 02:35:12 INFO - Running setup.py install for blobuploader 02:35:12 INFO - Running setup.py install for requests 02:35:13 INFO - Running setup.py install for docopt 02:35:13 INFO - Successfully installed blobuploader requests docopt 02:35:13 INFO - Cleaning up... 02:35:14 INFO - Return code: 0 02:35:14 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:35: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')]} 02:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35: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')]} 02:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:14 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:14 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 02:35:14 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 02:35:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:14 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:14 INFO - 'DCLOCATION': 'SCL3', 02:35:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:14 INFO - 'HOMEDRIVE': 'C:', 02:35:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:14 INFO - 'KTS_VERSION': '1.19c', 02:35:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:14 INFO - 'MOZ_NO_REMOTE': '1', 02:35:14 INFO - 'NO_EM_RESTART': '1', 02:35:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:14 INFO - 'OS': 'Windows_NT', 02:35:14 INFO - 'OURDRIVE': 'C:', 02:35: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;', 02:35:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:14 INFO - 'PROCESSOR_LEVEL': '6', 02:35:14 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:14 INFO - 'PROMPT': '$P$G', 02:35:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:14 INFO - 'PWD': 'C:\\slave\\test', 02:35:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:14 INFO - 'SYSTEMDRIVE': 'C:', 02:35:14 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:14 INFO - 'TEST1': 'testie', 02:35:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:14 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:14 INFO - 'USERNAME': 'cltbld', 02:35:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:14 INFO - 'WINDIR': 'C:\\windows', 02:35:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a19r2m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qvs2kc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dr5xiy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sxh5ln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gzlp77-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-es79z_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:35:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:35:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-efp_jd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jv8lbw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gjvosn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-49lfin-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vlmscz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v9g7bo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ri7vds-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hnwxpe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2wj7ec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o4_j2b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zrhp02-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:35:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:35:17 INFO - Running setup.py install for manifestparser 02:35:17 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 02:35:17 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 02:35:17 INFO - Running setup.py install for mozcrash 02:35:18 INFO - Running setup.py install for mozdebug 02:35:18 INFO - Running setup.py install for mozdevice 02:35:18 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 02:35:18 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 02:35:18 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 02:35:18 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 02:35:19 INFO - Running setup.py install for mozfile 02:35:19 INFO - Running setup.py install for mozhttpd 02:35:19 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 02:35:19 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 02:35:19 INFO - Running setup.py install for mozinfo 02:35:19 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 02:35:19 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Running setup.py install for mozInstall 02:35:20 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 02:35:20 INFO - Running setup.py install for mozleak 02:35:20 INFO - Running setup.py install for mozlog 02:35:21 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 02:35:21 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 02:35:21 INFO - Running setup.py install for moznetwork 02:35:21 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 02:35:21 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 02:35:21 INFO - Running setup.py install for mozprocess 02:35:22 INFO - Running setup.py install for mozprofile 02:35:22 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 02:35:22 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 02:35:22 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 02:35:22 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 02:35:22 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 02:35:22 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 02:35:23 INFO - Running setup.py install for mozrunner 02:35:23 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 02:35:23 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 02:35:23 INFO - Running setup.py install for mozscreenshot 02:35:24 INFO - Running setup.py install for moztest 02:35:24 INFO - Running setup.py install for mozversion 02:35:24 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 02:35:24 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 02:35:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:35:24 INFO - Cleaning up... 02:35:24 INFO - Return code: 0 02:35:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:35:24 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')]} 02:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:24 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')]} 02:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:24 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:24 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 02:35:24 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 02:35:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:24 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:24 INFO - 'DCLOCATION': 'SCL3', 02:35:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:24 INFO - 'HOMEDRIVE': 'C:', 02:35:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:24 INFO - 'KTS_VERSION': '1.19c', 02:35:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:24 INFO - 'MOZ_NO_REMOTE': '1', 02:35:24 INFO - 'NO_EM_RESTART': '1', 02:35:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:24 INFO - 'OS': 'Windows_NT', 02:35:24 INFO - 'OURDRIVE': 'C:', 02:35:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:35:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:24 INFO - 'PROCESSOR_LEVEL': '6', 02:35:24 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:24 INFO - 'PROMPT': '$P$G', 02:35:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:24 INFO - 'PWD': 'C:\\slave\\test', 02:35:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:24 INFO - 'SYSTEMDRIVE': 'C:', 02:35:24 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:24 INFO - 'TEST1': 'testie', 02:35:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:24 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:24 INFO - 'USERNAME': 'cltbld', 02:35:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:24 INFO - 'WINDIR': 'C:\\windows', 02:35:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:35:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h7pdpa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:35:25 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)) 02:35:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:35:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iyl9c5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:35:25 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)) 02:35:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:35:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-miyo3h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:35:25 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)) 02:35:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:35:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kjgeir-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:35:25 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)) 02:35:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:35:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3ai9ul-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t0ukbo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nsugrz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0n7lbq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oryr3x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-opopew-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:35:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wttgfy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qjjndn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:35:26 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)) 02:35:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:35:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qybann-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:35:27 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)) 02:35:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:35:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dbw5vd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:35:27 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)) 02:35:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:35:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c9quhz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:35:27 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)) 02:35:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:35:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oyd4wl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:35:27 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)) 02:35:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:35:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zxczj1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:35:27 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)) 02:35:27 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)) 02:35:27 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)) 02:35:27 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)) 02:35:27 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)) 02:35:27 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)) 02:35:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 02:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:35:28 INFO - Downloading blessings-1.6.tar.gz 02:35:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 02:35:28 INFO - Installing collected packages: blessings 02:35:28 INFO - Running setup.py install for blessings 02:35:29 INFO - Successfully installed blessings 02:35:29 INFO - Cleaning up... 02:35:31 INFO - Return code: 0 02:35:31 INFO - Done creating virtualenv C:\slave\test\build/venv. 02:35:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:35:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:35:32 INFO - Reading from file tmpfile_stdout 02:35:32 INFO - Using _rmtree_windows ... 02:35:32 INFO - Using _rmtree_windows ... 02:35:32 INFO - Current package versions: 02:35:32 INFO - blessings == 1.6 02:35:32 INFO - blobuploader == 1.2.4 02:35:32 INFO - docopt == 0.6.1 02:35:32 INFO - functools32 == 3.2.3-2 02:35:32 INFO - jsonschema == 2.5.1 02:35:32 INFO - manifestparser == 1.1 02:35:32 INFO - mozInstall == 1.12 02:35:32 INFO - mozcrash == 0.17 02:35:32 INFO - mozdebug == 0.1 02:35:32 INFO - mozdevice == 0.48 02:35:32 INFO - mozfile == 1.2 02:35:32 INFO - mozhttpd == 0.7 02:35:32 INFO - mozinfo == 0.9 02:35:32 INFO - mozleak == 0.1 02:35:32 INFO - mozlog == 3.3 02:35:32 INFO - moznetwork == 0.27 02:35:32 INFO - mozprocess == 0.23 02:35:32 INFO - mozprofile == 0.28 02:35:32 INFO - mozrunner == 6.12 02:35:32 INFO - mozscreenshot == 0.1 02:35:32 INFO - mozsystemmonitor == 0.3 02:35:32 INFO - moztest == 0.7 02:35:32 INFO - mozversion == 1.4 02:35:32 INFO - psutil == 3.1.1 02:35:32 INFO - requests == 1.2.3 02:35:32 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:32 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 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:32 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 02:35:32 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 02:35:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:32 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:32 INFO - 'DCLOCATION': 'SCL3', 02:35:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:32 INFO - 'HOMEDRIVE': 'C:', 02:35:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:32 INFO - 'KTS_VERSION': '1.19c', 02:35:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:32 INFO - 'MOZ_NO_REMOTE': '1', 02:35:32 INFO - 'NO_EM_RESTART': '1', 02:35:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:32 INFO - 'OS': 'Windows_NT', 02:35:32 INFO - 'OURDRIVE': 'C:', 02:35:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:35:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:32 INFO - 'PROCESSOR_LEVEL': '6', 02:35:32 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:32 INFO - 'PROMPT': '$P$G', 02:35:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:32 INFO - 'PWD': 'C:\\slave\\test', 02:35:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:32 INFO - 'SYSTEMDRIVE': 'C:', 02:35:32 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:32 INFO - 'TEST1': 'testie', 02:35:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:32 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:32 INFO - 'USERNAME': 'cltbld', 02:35:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:32 INFO - 'WINDIR': 'C:\\windows', 02:35:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 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)) 02:35:32 INFO - Cleaning up... 02:35:32 INFO - Return code: 0 02:35:32 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 02:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:35:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:35:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:35:32 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0189F640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01945E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', '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-100', '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 02:35:32 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 02:35:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:35:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:32 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:32 INFO - 'DCLOCATION': 'SCL3', 02:35:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:32 INFO - 'HOMEDRIVE': 'C:', 02:35:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:32 INFO - 'KTS_VERSION': '1.19c', 02:35:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:32 INFO - 'MOZ_NO_REMOTE': '1', 02:35:32 INFO - 'NO_EM_RESTART': '1', 02:35:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:32 INFO - 'OS': 'Windows_NT', 02:35:32 INFO - 'OURDRIVE': 'C:', 02:35:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:35:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:32 INFO - 'PROCESSOR_LEVEL': '6', 02:35:32 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:32 INFO - 'PROMPT': '$P$G', 02:35:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:32 INFO - 'PWD': 'C:\\slave\\test', 02:35:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:32 INFO - 'SYSTEMDRIVE': 'C:', 02:35:32 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:32 INFO - 'TEST1': 'testie', 02:35:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:32 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:32 INFO - 'USERNAME': 'cltbld', 02:35:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:32 INFO - 'WINDIR': 'C:\\windows', 02:35:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:35:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 02:35:32 INFO - Cleaning up... 02:35:32 INFO - Return code: 0 02:35:32 INFO - Running post-action listener: _resource_record_post_action 02:35:32 INFO - Running post-action listener: _start_resource_monitoring 02:35:33 INFO - Starting resource monitoring. 02:35:33 INFO - [mozharness: 2016-08-24 09:35:33.071000Z] Finished create-virtualenv step (success) 02:35:33 INFO - [mozharness: 2016-08-24 09:35:33.072000Z] Running install step. 02:35:33 INFO - Running pre-action listener: _resource_record_pre_action 02:35:33 INFO - Running main action method: install 02:35:33 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:35:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:35:33 INFO - Reading from file tmpfile_stdout 02:35:33 INFO - Using _rmtree_windows ... 02:35:33 INFO - Using _rmtree_windows ... 02:35:33 INFO - Detecting whether we're running mozinstall >=1.0... 02:35:33 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 02:35:33 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 02:35:33 INFO - Reading from file tmpfile_stdout 02:35:33 INFO - Output received: 02:35:33 INFO - Usage: mozinstall-script.py [options] installer 02:35:33 INFO - Options: 02:35:33 INFO - -h, --help show this help message and exit 02:35:33 INFO - -d DEST, --destination=DEST 02:35:33 INFO - Directory to install application into. [default: 02:35:33 INFO - "C:\slave\test"] 02:35:33 INFO - --app=APP Application being installed. [default: firefox] 02:35:33 INFO - Using _rmtree_windows ... 02:35:33 INFO - Using _rmtree_windows ... 02:35:33 INFO - mkdir: C:\slave\test\build\application 02:35:33 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-51.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 02:35:33 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-51.0a1.en-US.win32.zip --destination C:\slave\test\build\application 02:35:35 INFO - Reading from file tmpfile_stdout 02:35:35 INFO - Output received: 02:35:35 INFO - C:\slave\test\build\application\firefox\firefox.exe 02:35:35 INFO - Using _rmtree_windows ... 02:35:35 INFO - Using _rmtree_windows ... 02:35:35 INFO - Running post-action listener: _resource_record_post_action 02:35:35 INFO - [mozharness: 2016-08-24 09:35:35.873000Z] Finished install step (success) 02:35:35 INFO - [mozharness: 2016-08-24 09:35:35.873000Z] Running run-tests step. 02:35:35 INFO - Running pre-action listener: _resource_record_pre_action 02:35:35 INFO - Running main action method: run_tests 02:35:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 02:35:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 02:35:35 INFO - Python 2.7.3 02:35:35 INFO - Return code: 0 02:35:35 INFO - grabbing minidump binary from tooltool 02:35: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')]} 02:35:35 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 0x012C8BF0>, '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 0x012EA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F015C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 02:35:35 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 02:35:35 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 02:35:35 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 02:35:35 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:35:36 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpbv7qfn 02:35:36 INFO - INFO - File integrity verified, renaming tmpbv7qfn to win32-minidump_stackwalk.exe 02:35:36 INFO - Return code: 0 02:35:36 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 02:35:36 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 02:35:36 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 02:35:36 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 02:35:36 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 02:35:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build 02:35:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-100 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log 02:35:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:35:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:35:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:35:36 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 02:35:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:35:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:35:36 INFO - 'DCLOCATION': 'SCL3', 02:35:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:35:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:35:36 INFO - 'HOMEDRIVE': 'C:', 02:35:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:35:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:35:36 INFO - 'KTS_VERSION': '1.19c', 02:35:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:35:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 02:35:36 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:35:36 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 02:35:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:35:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:35:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:35:36 INFO - 'MOZ_NO_REMOTE': '1', 02:35:36 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:35:36 INFO - 'NO_EM_RESTART': '1', 02:35:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:35:36 INFO - 'OS': 'Windows_NT', 02:35:36 INFO - 'OURDRIVE': 'C:', 02:35:36 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;', 02:35:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:35:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:35:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:35:36 INFO - 'PROCESSOR_LEVEL': '6', 02:35:36 INFO - 'PROCESSOR_REVISION': '1e05', 02:35:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:35:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:35:36 INFO - 'PROMPT': '$P$G', 02:35:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:35:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:35:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:35:36 INFO - 'PWD': 'C:\\slave\\test', 02:35:36 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 02:35:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:35:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:35:36 INFO - 'SYSTEMDRIVE': 'C:', 02:35:36 INFO - 'SYSTEMROOT': 'C:\\windows', 02:35:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:36 INFO - 'TEST1': 'testie', 02:35:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:35:36 INFO - 'USERDOMAIN': 'T-W732-IX-100', 02:35:36 INFO - 'USERNAME': 'cltbld', 02:35:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:35:36 INFO - 'WINDIR': 'C:\\windows', 02:35:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:35:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:35:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:35:36 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600 02:35:37 INFO - mozversion application_buildid: 20160823233815 02:35:37 INFO - mozversion application_changeset: 65b00e9620d16d97576cc90f0a4e0129c0e241be 02:35:37 INFO - mozversion application_display_name: Nightly 02:35:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:35:37 INFO - mozversion application_name: Firefox 02:35:37 INFO - mozversion application_remotingname: firefox 02:35:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:35:37 INFO - mozversion application_vendor: Mozilla 02:35:37 INFO - mozversion application_version: 51.0a1 02:35:37 INFO - mozversion platform_buildid: 20160823233815 02:35:37 INFO - mozversion platform_changeset: 65b00e9620d16d97576cc90f0a4e0129c0e241be 02:35:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:35:37 INFO - mozversion platform_version: 51.0a1 02:35:37 INFO - using testdate: 1472031337 02:35:37 INFO - actual date: 1472031337 02:35:37 INFO - starting webserver on 'localhost:49684' 02:35:37 INFO - SUITE-START | Running 2 tests 02:35:37 INFO - TEST-START | dromaeo_css 02:35:37 INFO - operating with platform_type : w7_ 02:35:37 INFO - Initialising browser for dromaeo_css test... 02:35:37 INFO - TEST-INFO | started process 1668 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpp9hiza\profile http://localhost:49684/getInfo.html) 02:35:46 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 02:35:46 INFO - PROCESS | 1668 | colorDepth:24 02:35:46 INFO - PROCESS | 1668 | Browser inner width/height: 1008/673 02:35:46 INFO - PROCESS | 1668 | __metrics 02:35:47 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 02:35:48 INFO - TEST-INFO | 1668: exit 0 02:35:48 INFO - Browser initialized. 02:35:48 INFO - Running cycle 1/1 for dromaeo_css test... 02:35:48 INFO - TEST-INFO | started process 3176 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpp9hiza\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:35:48 INFO - PROCESS | 3176 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 02:35:59 INFO - PROCESS | 3176 | RSS: Main: 151838720 02:35:59 INFO - PROCESS | 3176 | 02:36:49 INFO - PROCESS | 3176 | 1472031409312 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] 02:39:00 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49684/tests/dromaeo/cssquery-ext.html) 02:39:01 INFO - PROCESS | 3176 | RSS: Main: 129990656 02:39:01 INFO - PROCESS | 3176 | 02:42:03 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-ext.html (next: http://localhost:49684/tests/dromaeo/cssquery-jquery.html) 02:42:04 INFO - PROCESS | 3176 | RSS: Main: 86204416 02:42:04 INFO - PROCESS | 3176 | 02:45:06 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49684/tests/dromaeo/cssquery-mootools.html) 02:45:06 INFO - PROCESS | 3176 | RSS: Main: 82309120 02:45:06 INFO - PROCESS | 3176 | 02:48:08 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49684/tests/dromaeo/cssquery-prototype.html) 02:48:09 INFO - PROCESS | 3176 | RSS: Main: 79151104 02:48:09 INFO - PROCESS | 3176 | 02:51:11 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49684/tests/dromaeo/cssquery-yui.html) 02:51:12 INFO - PROCESS | 3176 | RSS: Main: 86069248 02:51:12 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-yui.html (next: http://localhost:49684/tests/dromaeo/cssquery-dojo.html) 02:54:14 INFO - PROCESS | 3176 | RSS: Main: 76472320 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | __start_tp_report 02:54:14 INFO - PROCESS | 3176 | _x_x_mozilla_page_load 02:54:14 INFO - PROCESS | 3176 | _x_x_mozilla_page_load_details 02:54:14 INFO - PROCESS | 3176 | |i|pagename|runs| 02:54:14 INFO - PROCESS | 3176 | |0;dojo.html;2541.8856151473183;2565.499727553127;2581.3933725574498;2584.896604135835;2602.2843717977553;4277.978610106953;4382.386465894773;4383.342498625208;4407.955920440799;4474.395956545865;4338;4353.325234588644;4369.431973843396;4441.5336389679105;4444.377787109807;3687.1703866630064;3835.520559930009;3917.9804100979454;3963.1479231965122;3967.5834037425943;3899.8690379789828;3907.2771537265553;3950.2494526040105;3956.3472027115636;3971.1462035662325;3795.658390744846;3815.9046023849487;3819.8090095495113;3866.7486613370043;3888.572257051722;3751.118487155516;3816.637419445148;3845.2693988142164;3850;3885.7668539887695;3795.772253664761;3818.9236215275537;3880.1075752577067;3883.689304855605;3884.533855937277;4258.5954334338185;4268.509121451042;4301.505326887417;4355.324924636687;4381.868543943687;4474.463064432256;4530.275155975029;4571.657125715551;4587.357769912215;4596.333531637927;4546.590806827401;4554.521775213588;4568.748718820463;4600.401947746805;4637.837675681335;4271.636910862611;4292.205941900758;4342.5874541918465;4347.195768782785;4368.912621747547;4612.215923293048;4710.340552322609;4721.7403042832975;4729.503402142795;4733.9763301183275;4449.488308844459;4468.821247150143;4471.441069866267;4492.258777301774;4561.270196768501;4496.842610508616;4509.819607215741;4574.748388838646;4584.885377865513;4590.770461476913;4491.169133710274;4537.160625284333;4568;4576.519465456079;4617.561331673553;4444.400005999149;4528.547145285511;4597.448306203277;4601.976990115095;4608.700434471749;3245.9350812983607;3259.4133056049664;3269.346130773855;3286.2113092857544;3297.7526685498683;2987.0889378739257;3006.864691088906;3036.362363903561;3042.117785842081;3044.862981165853;4240.363945408152;4254.31930891056;4338.154059958278;4413.536578659258;4426.048399594103;4567.611753001032;4632.4672662644225;4633.976830115827;4670.602998745145;4682.74244916526;4334.263175260214;4346.847860324937;4440.2007638625355;4443.866683999485;4444.244478438609;3865.942010869839;3920.31394505966;3948.9605103949166;3985.1830374773103;3998.3802510610903;4680.648951328596;4698.577128058423;4724.881877953078;4746.335513028179;4787.186178349759;4533.455985281787;4553.704009239389;4620.283856002325;4636;4649.465311489222;4576.427946506687;4646.512116227747;4695.34265202865;4791.329213910125;4798.616110711065;4471.686981911235;4502.549745025472;4533.0707205023555;4542.454905411239;4567.109413664436;4481.775911204361;4489.371487991619;4580.290055041474;4597.540245975376;4620.890986163229;4272.423222864873;4354.172707185625;4362.062156636339;4464.218761716752;4473.776311184492;4343.52221255668;4414.3157810539415;4415.448068991376;4454.510003899633;4501.3022981437925;4283.850065247701;4390.51704312519;4391.934120988251;4416.558344165558;4425.203463376623;4407.338899165151;4413.492448368479;4448.5551444855255;4541.659375546781;4572.428446444194;4340.175366680321;4395.362672412546;4409.536998615227;4419.646428285665;4448.932256258539 02:54:14 INFO - PROCESS | 3176 | |1;ext.html;548.7393488093156;555.0148486436575;556.6493109341114;561.0882316236114;657.855271840195;37931;41079.178416431656;41419.79290103549;41462.792686036526;41546.58453415461;41466.17067658645;41643.37534936978;41778.37332440008;41787.58212417875;41817.79091104548;8678;8731.126887311282;8838.381313308071;8848.380613357067;8868.201861832435;8740.257078148365;8750.168733970295;8763.51800650964;8792.120787921222;8814.471131732085;8780.473171609692;8796.73609791704;8899.866502002475;8914.50970196639;8929.285657147446;8814.559272036373;8829.426087304337;8833;8854.203121719012;8905.910940890573;8665.133486651348;8678.696245631372;8709.56452177395;8746.606402711894;8748.30013598917;38405.231895362216;38419.42370864439;38614;38798.612013879785;38869.61130388716;3011.5633233181065;3024.956390045431;3181.6341120771176;3194.233383987826;3569.8750543731103;30230.455265300785;30392.8480357599;30429.695703042908;30737;31482.527762083588;3708.4993525873933;3721.813909304551;3725.105974566112;3730.7575007624687;3736.2901048800913;241714;241826;242786.78606607032;243550;244211;48737.268940965914;48745.51254487481;49041.05753654792;49087;49237;29767.10698679043;30032.099037028926;54520.820522050344;54713;54917;13798.310084495735;14129.364178611988;14143.858561414254;14153.5046273382;14162.71674566503;10665.146788256921;10888.61889833852;10925.836112458319;10928.289661171999;10947.233693641369;18202.635947281244;18219.271229150687;18271.54321141982;18284.17721202549;18321.267149313877;7425.443091768139;7499.437542184358;7559.395248380117;7562.319391254813;7580.317771400601;824.6577670266892;846.4751853850529;899.7345782994032;921.3182245138684;921.3366376209119;27418.5887211692;28349.928171595828;28386.444808813663;28559.42881142407;28624.427511449656;966.6761634852403;969.5394687523511;976.6972238606055;982.1749730226644;1028.876534815812;15049.473268435551;15255.466058687893;15435.45975890861;15485.380584776483;15589.610259743597;357963;358744;359992;360579;363731;50882;50978.74510627423;51383;51529.22706159411;51564.22653660123;1180.3449085757486;1203.4223572684984;1204.753025629744;1219.359836086062;1250.1623911978995;26524.867375662998;26647.53725486232;26661.200163995112;26727.599086014114;26869.193924182306;42806.57193428151;43243.48647797312;43406.78296608497;43414;43628;278055;279381;279827;280731;281371;24246.272611821674;24550.87724561437;24641.137560185307;24676.876615616806;24754.381140471633;42883.7855810719;43206.56793432151;43585.346219806095;43848;43957.78021110002;36793;36826.079348016516;37354;38197.045073872265;38279;37256.25487490181;37645.81177094097;37663.246735065695;38092.42861357137;38768.80615596904;35585;35826;35890.820545897106;36117;36380.454293185096;33226.833865830515;33537;33734.83132584322;33762.662373375955;34709.47935781016;26320.34199145037;26452.338691532867;26536;26580.8670956644;26744.59883101716 02:54:14 INFO - PROCESS | 3176 | |2;jquery.html;333.35994889811565;333.8113965609432;336.15121817411034;341.5900918897324;350.7474618274842;17514.29942802288;18736.344227952042;18762.530936726587;18840.528986775338;19461.41615751526;8389.91610083899;9675.951620241889;9997.450140242283;10081.69754907352;10312.639057632965;5152.098382783008;5271.024860400819;5271.341082364705;5280.709560974145;5551.861203469905;5812.360640329561;5887.587868849182;5942.405759424045;5952.375000624937;5955.940440595604;5825.446582574648;5918.585699001072;5934.139549765281;5951;5962.582619216657;5902.88194236115;5910.172575839364;5934.198883150806;5948.31594366649;5949.791757288495;5638.718064096779;5749.540036797088;5769.3076830780155;5773.047447171211;5798.304203495565;11426;13646.795298070538;21795.67306490404;22225.44436389103;22308;2019.81821636052;2032.2886989553685;2033.9389818305474;2045.6931460280928;2089.9968015352565;9144.222741067017;9233.307501937314;11228.382438965853;13622.182669039888;14520.273986300748;1828.3692126216433;1838.7701537307837;1842.3431028831876;1848.5286252005787;1851.1947302923136;70569.64715176391;70676.6466167671;71037.64481177613;71347;73269.90095148524;9916.504174791231;10001.24990625706;10106.29255952091;10211.234157438223;10240.385576865412;13208.471661133639;13289.734205316034;13298.468061277634;13304.46782128704;13328.933355333354;6873.931260687329;6880.4839637026425;6941.7223311068;6951.409130223887;6956.965215173892;5750.453706897922;6996.230414654486;7026.3676269135;7254.911763235451;7286.562806231643;3838.865639702597;3861.6331448512346;3982.6216509431556;4078.6940979426663;4144.854930077433;1629.3971230644738;1754.789425268976;1821.6083542038525;1830.8993005384832;1837.5222442165134;902.0753727429437;931.7251410833685;934.0752654871628;934.5747684803398;959.9856002159974;18178.27286908509;18402.907985459988;18427.90786046088;18804.435866924014;19042.143103560375;347.15640992388234;361.8118578339249;362.37490329182475;919.8114386550741;968.704545113747;5223.373195216598;5411.810586629449;5558;5563.666180029211;5696.658200507983;333430;334321;338815;342144;342817;50693.23960140601;54185.458145418044;79319.20680793119;82125;84473.15526844776;350.7842676753778;355.8967899309223;357.2800806375156;358.26734328298693;358.3138290174287;9873.901260987299;10252.128569071712;10281.845772313272;10289.9485502572;10332.741681458023;41679.374809377274;43978.56021439873;43991.78004109959;44628.77685611551;44881.1023779516;57306.42693573011;57892.71053644705;58208.708956454946;58234.70882645559;58239.4176058234;6583.769568065286;7229.457790665616;7561.3950883928055;7755.495892766952;7765.262300081483;8521.744347669579;11901.166918315635;12687.30219837881;12757.043221758591;12956.28740419286;10299;10331.483425828828;10451.634192803216;10533.683989480327;10813.297135686154;10001.199904007808;10518.842217366899;11035.282706624044;11095.445227738743;11159.27464714791;10718.946405267923;11230.157738169833;11260.211785174959;11327.207095503236;11432.314061156025;7575.848483030419;7593.506422082768;7728.072631284057;7789.922100778919;7937.126916039347;10607.787844242917;10645.254832161674;10764.085052770602;10947.945260273647;11098.223124381217 02:54:14 INFO - PROCESS | 3176 | |3;mootools.html;1366.7813149896015;1387.666959929617;1447.9855201447988;1658.1874881308147;1696.0756387768672;6416.358364163593;6847.726090956362;7137.393321567673;7340.559566426012;7351.926480735191;7101;7392.556446613207;7396.556206627606;7409.962950185255;7421.740239091633;2016.7680716717582;2051.979480205194;2059.845511586637;2063.597598468299;2064.318774804311;1991.7311162993028;1997.520595057183;2008;2019.5960807838417;2027.5539381336082;2004.9799502005012;2007.6787713965768;2016.4555569996091;2019.3739940618455;2021.1713197588992;1873.9437816865516;1923.6441258367176;1947.3476385410904;1953.5506833428249;1954.0522846419617;1899.1833511590012;1904.6095550412133;1910.5605710686482;1920.19351872214;1927.6722957097327;7121.608311542863;7201.207867134611;7217.530860494104;7219.169795473483;7230.674619642129;4093.8771836844944;4213.557576454459;4220.936685949713;4224.112936283384;4256.276433006366;2514.6228065790096;2575.665163528748;2615.686117665892;2653.6417583626157;2659.1357808712246;847.4957400346784;866.8526350520426;880.423322723617;881.8721285413579;885.4952676974127;9050.637974481013;9070.138336857988;9070.27437804974;9134;9216.124468175512;3698.130939229279;3815.561210460777;3857.5370955485523;3859.903502412406;3860.942085868714;7911.327537159291;7933.920660793318;7941.126476087626;7951.403644726669;7952.284294413531;4200.4959404871615;4255.787210639455;4256.936145957813;4259.936100958488;4277.059047009653;3368.4778859276853;3835.36716441784;3892.824822882977;3904.511936007999;3954.8220330085214;3222.38774632819;3259.2992506611195;3312.602487701443;3315.2043509557534;3321.485169798685;1052.2266134391216;1065.3448129400517;1070.7751372211924;1078.191356482638;1081.2323250492238;810.7486679129489;835.6991483066091;836.3643630840605;838.1409055717816;848.4188330993165;4710.788014539354;4809.831655892097;4817.807287708522;4981.078500477423;5045.596352291808;376.7795839433962;382.7971175277108;383.3884953499164;387.0729602601753;387.9825407856653;3351.5420791955576;3354.647761985005;3538.1508437975217;3679.632036796299;3686.9815650921573;8696.956515217384;8734.475931444007;8794.42836215644;8854.955725221333;8877.156670116328;8720.476771393736;8722.781930451663;8726.258268047288;8731.56342182901;8752.168543988311;6566.5731727438515;6621.86756264872;6703.530752847254;6706.22878368984;6720.865582688319;5068.037072956126;5097.8215762448135;5105.463926287686;5105.897882042413;5141.511556402136;7805.882911756442;7924.247196516321;7952.085510166405;7983.20167983197;8004.679812807422;7796.142424333206;7960.761177164694;8033.437659364022;8290.33677305802;8304.958475207586;5784.739686714024;6623.569467984758;6687.933120668731;6810.182778066664;6836.179658441019;6881.311868813079;7014.298570142945;7058.400035996998;7089.397401220954;7092.184398794205;5868.354481007171;6059.242594675666;6201.844953876189;6261.186045814015;6266;5981.7607295707685;6115.296740874856;6126.540509461719;6208.4722798562625;6248.843778905381;6045.244344456943;6089.451949324405;6090.056041313604;6106.297775755845;6129.601575897553;4224.345226489899;4315.71947823403;4402.471703395613;4429.712068715523;4456.086502266962;4297.290946993711;4300.806463709078;4348.9130217396105;4386.276264416336;4448.510663826912 02:54:14 INFO - PROCESS | 3176 | |4;prototype.html;254.6193440805993;265.34327539340126;268.1753607656457;268.61319699632526;277.0897601379469;4712.764361781906;4769.284607308906;4873.975630121849;4895.384523107375;4897.583705385042;4808.869915569839;4841.467438581753;4844.539768721966;4855.368802055727;4875.512448755123;3619.8371073301764;3659.70722342212;3663.8900832974987;3665.413533834587;3668.798216098127;3659.499605161884;3662.285854258421;3664.3404187246283;3667.156553992583;3674.9816250918707;3630.7458477906425;3637.4907512948075;3640.3447379471686;3643.325984692827;3658.725595580342;3375.2405708715587;3545.7340699447645;3558.1282585766394;3558.395072837624;3559.8220088995445;3076.9846150769326;3077.0922028342347;3082.445159871222;3082.7842051056437;3083.9206277802814;4743.02767932573;4750.263709125091;4759.738214398207;4761.2382018876815;4762.38089048425;4070.5318888327924;4108.589141085898;4109.219248342835;4112.218678451115;4113.115680128757;4740.905181896377;4975.278584605248;4976.292661966858;4981.676191047571;5002.74986250686;3393.9490907636405;3397.082787647346;3397.575303087114;3403.40440422925;3403.8638454461793;5183.2743415921805;5201.843944681666;5228.163493841005;5235.685858848443;5243.9213411798855;3376.240345922197;3410.6759857813468;3417.6411476795074;3418.162550175223;3422.1273575238156;3433.1760377509218;3451.9309613808086;3459.930801383958;3460.377132116243;3466.9479957800654;3929.8035098245546;4058.843229679527;4064.4106604542176;4067.3695577185586;4077.571854955187;4985.501449854985;5000.099982003274;5005.1491246488185;5017.648764586444;5035.413844638927;1205.722683782735;1208.4199584199635;1218.2629509146839;1225.1117939493795;1226.2764968668528;756.8372799847949;775.3370867907857;790.9802254943672;794.606669698503;812;4818.7831547580445;4840.782164802592;4841.515848415131;4850;4850.781714822841;5103;5123.564497017721;5137.717425541557;5139.820106296336;5148.845534633967;4772.904541909212;4817.807287708452;4821.373221481185;4828.227483602606;4848.490908454632;2625.3830349867767;2710.945781084407;2722.564389697639;2728.7816974641983;2729.4541091781725;5288.603354748332;5299.920501192485;5305.31030965972;5309.840704778786;5311.7344132793205;5215.739213039333;5223.921641175385;5227.738613069331;5231.24146998683;5245.868853278622;5142.742862856917;5187.507186817246;5310.389305229948;5312.442193569696;5333.359996800409;5113.00296442205;5132.486751324987;5134.5635620972635;5145.691258524501;5157.226416037624;5032.886187801059;5036.949630503648;5037.294778731054;5055.545000949785;5061.8734531637;5291.656042357235;5310.973445132904;5322.654027488201;5337.226102215234;5338.652987555796;3310.553075334799;3476.7566270361845;3480.965190348064;3483.5993860706344;3485.9825700871334;5064;5066.189409694432;5076;5078.289039534394;5084.720340381166;4910.582600479;4932.21084626458;4932.383452068491;4936.679115857458;4943.950560494349;4896.4124305083615;4915.680480768739;4925.975370123269;4933.407991041098;4938.185199442052;4646.976765116153;4830.855074347775;4837.3227748116;4848.248521479177;4927.876803079952;4866.440359358719;5059.822906198266;5081.618878584195;5082.33929589151;5082.669626474268;4819.421669399694;4920.966597014524;4941.579965702956;4956.405231372259;4966.354373931365 02:54:14 INFO - PROCESS | 3176 | |5;yui.html;645.5094128462368;671.7816709569388;713.176281394989;731.9743808966684;736.1387177002906;14565.271736413177;14778.852211477908;15271.389144434237;15595.766063509041;15692.764608530866;14878.851211487881;14898.404063837434;14967.101973881574;15020.774688379683;15087.698246035072;681.3595220492733;725.6770737022001;726.2483329753705;727.6761840980765;728.2462651156054;563.6195567991609;713.9143302803645;728;733.0690023669936;737.0639288104104;758.4273873225707;761.9885701714451;764.5374548398222;765.2424100140848;765.4641750774452;748.2293237964905;748.2405358561037;755.9130699969519;757.5757575757539;759.9696012159507;551.4099913092992;552.7899398228687;556.1962963517718;556.2518412734865;557.7462254674155;14683.412663493447;14756;14758.26208689572;14760.704785904329;14903.850961490463;13199;13459.461621535127;13521.52674656392;13685.79471307931;13924.30378481082;11840.171188016842;12058.638240852788;12069.155159138863;12075.577354792627;12128.45421956014;3627.147620309225;3638.2541578976247;3651.72612054097;3668.614795446493;3679.511115293011;70611.64694176496;72860;73618.63190684012;74451;74700;9966.352187107816;10322.638707645196;10423.530941107669;10515;10948.671539853658;17963.461096167135;18146.90926545359;18162.364317248834;18166.727499087523;18232.45302640923;7267.563946163142;7366.631668416558;7369.005184300158;7438.181800001996;7511.812204694927;4005.2766508201094;4052.371882358239;4112.609302116294;4141.399497072907;4153.563875792998;2729.9044533441233;2810.943781124366;2831.8442485663486;2833.192540126054;2836.3334616365137;470.15137676494254;475.2467339267231;478.77258302306126;481.62192678747016;482.8816939849759;552.8673118451543;567.0303780535248;621.4065567383138;653.7156336993423;660.7918505670698;8185.656116509753;8272.545010024385;8290.792730181673;8303.584820759059;8419.621117049748;636.59576169132;644.2623196440144;648.2836465705421;656.1273506236789;657.4280376072848;4033.3748269018347;4153.8961525962095;4174.833006679699;4241.618254357123;4288.656907447396;81619.5919020401;105597;107477.46261268643;112378;112613;95603;96397;96961.51519242358;97004;98234.5088274554;86492.13507864966;87020.5648971751;87710.12289877147;88026;88514.11485885187;7890.723824666109;8520.786980325542;8646.956765216133;8747.91252087471;8838;40051.39922901217;41587;42076.36885446659;43521.78239108784;54139.45860541344;62880.685596571726;63092.36907631048;65459.345406545326;65670;66710;10701.51843167075;10876.075533579418;11068.833967490655;11108.333499990027;11501.194916355775;13485.797713034117;13709.520166794115;14062.226577538333;14181.787273191117;14213.857861421668;11821.527138914347;12035.578754743541;12428.62714118578;12527.436265368262;13247.20516768997;11382.373969431761;12005.699857503632;12036.157468977088;12068.456919438822;12205.023598112308;11807.468663910317;11927.463264153308;12054.216475929037;12119.151659384112;12210.57262995791;9784.119429251117;9847.704568862946;10059;10458.843117353397;10590.89409105899;9399.577019034023;9458;9477.952610236905;10027.849582256418;10165.390076595428 02:54:14 INFO - PROCESS | 3176 | __end_tp_report 02:54:14 INFO - PROCESS | 3176 | __start_cc_report 02:54:14 INFO - PROCESS | 3176 | _x_x_mozilla_cycle_collect,301 02:54:14 INFO - PROCESS | 3176 | __end_cc_report 02:54:14 INFO - PROCESS | 3176 | __startTimestamp1472032454865__endTimestamp 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | ------- Summary: start ------- 02:54:14 INFO - PROCESS | 3176 | Number of tests: 6 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#0] dojo.html Cycles:165 Average:4245.39 Median:4416.56 stddev:494.49 (11.2%) stddev-sans-first:477.61 02:54:14 INFO - PROCESS | 3176 | Values: 2541.9 2565.5 2581.4 2584.9 2602.3 4278.0 4382.4 4383.3 4408.0 4474.4 4338.0 4353.3 4369.4 4441.5 4444.4 3687.2 3835.5 3918.0 3963.1 3967.6 3899.9 3907.3 3950.2 3956.3 3971.1 3795.7 3815.9 3819.8 3866.7 3888.6 3751.1 3816.6 3845.3 3850.0 3885.8 3795.8 3818.9 3880.1 3883.7 3884.5 4258.6 4268.5 4301.5 4355.3 4381.9 4474.5 4530.3 4571.7 4587.4 4596.3 4546.6 4554.5 4568.7 4600.4 4637.8 4271.6 4292.2 4342.6 4347.2 4368.9 4612.2 4710.3 4721.7 4729.5 4734.0 4449.5 4468.8 4471.4 4492.3 4561.3 4496.8 4509.8 4574.7 4584.9 4590.8 4491.2 4537.2 4568.0 4576.5 4617.6 4444.4 4528.5 4597.4 4602.0 4608.7 3245.9 3259.4 3269.3 3286.2 3297.8 2987.1 3006.9 3036.4 3042.1 3044.9 4240.4 4254.3 4338.2 4413.5 4426.0 4567.6 4632.5 4634.0 4670.6 4682.7 4334.3 4346.8 4440.2 4443.9 4444.2 3865.9 3920.3 3949.0 3985.2 3998.4 4680.6 4698.6 4724.9 4746.3 4787.2 4533.5 4553.7 4620.3 4636.0 4649.5 4576.4 4646.5 4695.3 4791.3 4798.6 4471.7 4502.5 4533.1 4542.5 4567.1 4481.8 4489.4 4580.3 4597.5 4620.9 4272.4 4354.2 4362.1 4464.2 4473.8 4343.5 4414.3 4415.4 4454.5 4501.3 4283.9 4390.5 4391.9 4416.6 4425.2 4407.3 4413.5 4448.6 4541.7 4572.4 4340.2 4395.4 4409.5 4419.6 4448.9 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#1] ext.html Cycles:180 Average:45506.12 Median:20545.15 stddev:78163.30 (380.4%) stddev-sans-first:78309.68 02:54:14 INFO - PROCESS | 3176 | Values: 548.7 555.0 556.6 561.1 657.9 37931.0 41079.2 41419.8 41462.8 41546.6 41466.2 41643.4 41778.4 41787.6 41817.8 8678.0 8731.1 8838.4 8848.4 8868.2 8740.3 8750.2 8763.5 8792.1 8814.5 8780.5 8796.7 8899.9 8914.5 8929.3 8814.6 8829.4 8833.0 8854.2 8905.9 8665.1 8678.7 8709.6 8746.6 8748.3 38405.2 38419.4 38614.0 38798.6 38869.6 3011.6 3025.0 3181.6 3194.2 3569.9 30230.5 30392.8 30429.7 30737.0 31482.5 3708.5 3721.8 3725.1 3730.8 3736.3 241714.0 241826.0 242786.8 243550.0 244211.0 48737.3 48745.5 49041.1 49087.0 49237.0 29767.1 30032.1 54520.8 54713.0 54917.0 13798.3 14129.4 14143.9 14153.5 14162.7 10665.1 10888.6 10925.8 10928.3 10947.2 18202.6 18219.3 18271.5 18284.2 18321.3 7425.4 7499.4 7559.4 7562.3 7580.3 824.7 846.5 899.7 921.3 921.3 27418.6 28349.9 28386.4 28559.4 28624.4 966.7 969.5 976.7 982.2 1028.9 15049.5 15255.5 15435.5 15485.4 15589.6 357963.0 358744.0 359992.0 360579.0 363731.0 50882.0 50978.7 51383.0 51529.2 51564.2 1180.3 1203.4 1204.8 1219.4 1250.2 26524.9 26647.5 26661.2 26727.6 26869.2 42806.6 43243.5 43406.8 43414.0 43628.0 278055.0 279381.0 279827.0 280731.0 281371.0 24246.3 24550.9 24641.1 24676.9 24754.4 42883.8 43206.6 43585.3 43848.0 43957.8 36793.0 36826.1 37354.0 38197.0 38279.0 37256.3 37645.8 37663.2 38092.4 38768.8 35585.0 35826.0 35890.8 36117.0 36380.5 33226.8 33537.0 33734.8 33762.7 34709.5 26320.3 26452.3 26536.0 26580.9 26744.6 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#2] jquery.html Cycles:180 Average:22835.12 Median:3850.25 stddev:56409.96 (1465.1%) stddev-sans-first:56542.90 02:54:14 INFO - PROCESS | 3176 | Values: 333.4 333.8 336.2 341.6 350.7 17514.3 18736.3 18762.5 18840.5 19461.4 8389.9 9676.0 9997.5 10081.7 10312.6 5152.1 5271.0 5271.3 5280.7 5551.9 5812.4 5887.6 5942.4 5952.4 5955.9 5825.4 5918.6 5934.1 5951.0 5962.6 5902.9 5910.2 5934.2 5948.3 5949.8 5638.7 5749.5 5769.3 5773.0 5798.3 11426.0 13646.8 21795.7 22225.4 22308.0 2019.8 2032.3 2033.9 2045.7 2090.0 9144.2 9233.3 11228.4 13622.2 14520.3 1828.4 1838.8 1842.3 1848.5 1851.2 70569.6 70676.6 71037.6 71347.0 73269.9 9916.5 10001.2 10106.3 10211.2 10240.4 13208.5 13289.7 13298.5 13304.5 13328.9 6873.9 6880.5 6941.7 6951.4 6957.0 5750.5 6996.2 7026.4 7254.9 7286.6 3838.9 3861.6 3982.6 4078.7 4144.9 1629.4 1754.8 1821.6 1830.9 1837.5 902.1 931.7 934.1 934.6 960.0 18178.3 18402.9 18427.9 18804.4 19042.1 347.2 361.8 362.4 919.8 968.7 5223.4 5411.8 5558.0 5563.7 5696.7 333430.0 334321.0 338815.0 342144.0 342817.0 50693.2 54185.5 79319.2 82125.0 84473.2 350.8 355.9 357.3 358.3 358.3 9873.9 10252.1 10281.8 10289.9 10332.7 41679.4 43978.6 43991.8 44628.8 44881.1 57306.4 57892.7 58208.7 58234.7 58239.4 6583.8 7229.5 7561.4 7755.5 7765.3 8521.7 11901.2 12687.3 12757.0 12956.3 10299.0 10331.5 10451.6 10533.7 10813.3 10001.2 10518.8 11035.3 11095.4 11159.3 10718.9 11230.2 11260.2 11327.2 11432.3 7575.8 7593.5 7728.1 7789.9 7937.1 10607.8 10645.3 10764.1 10947.9 11098.2 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#3] mootools.html Cycles:180 Average:4708.34 Median:5082.93 stddev:2596.59 (51.1%) stddev-sans-first:2591.73 02:54:14 INFO - PROCESS | 3176 | Values: 1366.8 1387.7 1448.0 1658.2 1696.1 6416.4 6847.7 7137.4 7340.6 7351.9 7101.0 7392.6 7396.6 7410.0 7421.7 2016.8 2052.0 2059.8 2063.6 2064.3 1991.7 1997.5 2008.0 2019.6 2027.6 2005.0 2007.7 2016.5 2019.4 2021.2 1873.9 1923.6 1947.3 1953.6 1954.1 1899.2 1904.6 1910.6 1920.2 1927.7 7121.6 7201.2 7217.5 7219.2 7230.7 4093.9 4213.6 4220.9 4224.1 4256.3 2514.6 2575.7 2615.7 2653.6 2659.1 847.5 866.9 880.4 881.9 885.5 9050.6 9070.1 9070.3 9134.0 9216.1 3698.1 3815.6 3857.5 3859.9 3860.9 7911.3 7933.9 7941.1 7951.4 7952.3 4200.5 4255.8 4256.9 4259.9 4277.1 3368.5 3835.4 3892.8 3904.5 3954.8 3222.4 3259.3 3312.6 3315.2 3321.5 1052.2 1065.3 1070.8 1078.2 1081.2 810.7 835.7 836.4 838.1 848.4 4710.8 4809.8 4817.8 4981.1 5045.6 376.8 382.8 383.4 387.1 388.0 3351.5 3354.6 3538.2 3679.6 3687.0 8697.0 8734.5 8794.4 8855.0 8877.2 8720.5 8722.8 8726.3 8731.6 8752.2 6566.6 6621.9 6703.5 6706.2 6720.9 5068.0 5097.8 5105.5 5105.9 5141.5 7805.9 7924.2 7952.1 7983.2 8004.7 7796.1 7960.8 8033.4 8290.3 8305.0 5784.7 6623.6 6687.9 6810.2 6836.2 6881.3 7014.3 7058.4 7089.4 7092.2 5868.4 6059.2 6201.8 6261.2 6266.0 5981.8 6115.3 6126.5 6208.5 6248.8 6045.2 6089.5 6090.1 6106.3 6129.6 4224.3 4315.7 4402.5 4429.7 4456.1 4297.3 4300.8 4348.9 4386.3 4448.5 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#4] prototype.html Cycles:180 Average:4160.06 Median:4843.03 stddev:1272.50 (26.3%) stddev-sans-first:1241.85 02:54:14 INFO - PROCESS | 3176 | Values: 254.6 265.3 268.2 268.6 277.1 4712.8 4769.3 4874.0 4895.4 4897.6 4808.9 4841.5 4844.5 4855.4 4875.5 3619.8 3659.7 3663.9 3665.4 3668.8 3659.5 3662.3 3664.3 3667.2 3675.0 3630.7 3637.5 3640.3 3643.3 3658.7 3375.2 3545.7 3558.1 3558.4 3559.8 3077.0 3077.1 3082.4 3082.8 3083.9 4743.0 4750.3 4759.7 4761.2 4762.4 4070.5 4108.6 4109.2 4112.2 4113.1 4740.9 4975.3 4976.3 4981.7 5002.7 3393.9 3397.1 3397.6 3403.4 3403.9 5183.3 5201.8 5228.2 5235.7 5243.9 3376.2 3410.7 3417.6 3418.2 3422.1 3433.2 3451.9 3459.9 3460.4 3466.9 3929.8 4058.8 4064.4 4067.4 4077.6 4985.5 5000.1 5005.1 5017.6 5035.4 1205.7 1208.4 1218.3 1225.1 1226.3 756.8 775.3 791.0 794.6 812.0 4818.8 4840.8 4841.5 4850.0 4850.8 5103.0 5123.6 5137.7 5139.8 5148.8 4772.9 4817.8 4821.4 4828.2 4848.5 2625.4 2710.9 2722.6 2728.8 2729.5 5288.6 5299.9 5305.3 5309.8 5311.7 5215.7 5223.9 5227.7 5231.2 5245.9 5142.7 5187.5 5310.4 5312.4 5333.4 5113.0 5132.5 5134.6 5145.7 5157.2 5032.9 5036.9 5037.3 5055.5 5061.9 5291.7 5311.0 5322.7 5337.2 5338.7 3310.6 3476.8 3481.0 3483.6 3486.0 5064.0 5066.2 5076.0 5078.3 5084.7 4910.6 4932.2 4932.4 4936.7 4944.0 4896.4 4915.7 4926.0 4933.4 4938.2 4647.0 4830.9 4837.3 4848.2 4927.9 4866.4 5059.8 5081.6 5082.3 5082.7 4819.4 4921.0 4941.6 4956.4 4966.4 02:54:14 INFO - PROCESS | 3176 | 02:54:14 INFO - PROCESS | 3176 | [#5] yui.html Cycles:180 Average:19327.27 Median:23905.22 stddev:28329.85 (118.5%) stddev-sans-first:28374.59 02:54:14 INFO - PROCESS | 3176 | Values: 645.5 671.8 713.2 732.0 736.1 14565.3 14778.9 15271.4 15595.8 15692.8 14878.9 14898.4 14967.1 15020.8 15087.7 681.4 725.7 726.2 727.7 728.2 563.6 713.9 728.0 733.1 737.1 758.4 762.0 764.5 765.2 765.5 748.2 748.2 755.9 757.6 760.0 551.4 552.8 556.2 556.3 557.7 14683.4 14756.0 14758.3 14760.7 14903.9 13199.0 13459.5 13521.5 13685.8 13924.3 11840.2 12058.6 12069.2 12075.6 12128.5 3627.1 3638.3 3651.7 3668.6 3679.5 70611.6 72860.0 73618.6 74451.0 74700.0 9966.4 10322.6 10423.5 10515.0 10948.7 17963.5 18146.9 18162.4 18166.7 18232.5 7267.6 7366.6 7369.0 7438.2 7511.8 4005.3 4052.4 4112.6 4141.4 4153.6 2729.9 2810.9 2831.8 2833.2 2836.3 470.2 475.2 478.8 481.6 482.9 552.9 567.0 621.4 653.7 660.8 8185.7 8272.5 8290.8 8303.6 8419.6 636.6 644.3 648.3 656.1 657.4 4033.4 4153.9 4174.8 4241.6 4288.7 81619.6 105597.0 107477.5 112378.0 112613.0 95603.0 96397.0 96961.5 97004.0 98234.5 86492.1 87020.6 87710.1 88026.0 88514.1 7890.7 8520.8 8647.0 8747.9 8838.0 40051.4 41587.0 42076.4 43521.8 54139.5 62880.7 63092.4 65459.3 65670.0 66710.0 10701.5 10876.1 11068.8 11108.3 11501.2 13485.8 13709.5 14062.2 14181.8 14213.9 11821.5 12035.6 12428.6 12527.4 13247.2 11382.4 12005.7 12036.2 12068.5 12205.0 11807.5 11927.5 12054.2 12119.2 12210.6 9784.1 9847.7 10059.0 10458.8 10590.9 9399.6 9458.0 9478.0 10027.8 10165.4 02:54:14 INFO - PROCESS | 3176 | -------- Summary: end -------- 02:54:14 INFO - PROCESS | 3176 | 02:54:15 INFO - PROCESS | 3176 | [Child 3076] WARNING: pipe error: 109: file c:/builds/moz2_slave/autoland-w32-00000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 02:54:15 INFO - TEST-INFO | 3176: exit 0 02:54:16 INFO - TEST-OK | dromaeo_css | took 1118852ms 02:54:16 INFO - TEST-START | kraken 02:54:16 INFO - operating with platform_type : w7_ 02:54:16 INFO - Initialising browser for kraken test... 02:54:16 INFO - TEST-INFO | started process 3292 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpn8anm8\profile http://localhost:49684/getInfo.html) 02:54:22 INFO - PROCESS | 3292 | __metrics Screen width/height:1600/1200 02:54:22 INFO - PROCESS | 3292 | colorDepth:24 02:54:22 INFO - PROCESS | 3292 | Browser inner width/height: 1008/673 02:54:22 INFO - PROCESS | 3292 | __metrics 02:54:23 ERROR - PROCESS | 3292 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2991: TypeError: this.tabs is undefined 02:54:23 INFO - PROCESS | 3292 | [Child 3964] WARNING: pipe error: 109: file c:/builds/moz2_slave/autoland-w32-00000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 02:54:23 INFO - PROCESS | 3292 | [Child 3964] WARNING: pipe error: 109: file c:/builds/moz2_slave/autoland-w32-00000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 02:54:23 INFO - PROCESS | 3292 | console.error: 02:54:23 INFO - PROCESS | 3292 | Message: Error: Connection closed before committing the transaction. 02:54:23 INFO - PROCESS | 3292 | Stack: 02:54:23 INFO - PROCESS | 3292 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 02:54:23 INFO - PROCESS | 3292 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 02:54:23 INFO - PROCESS | 3292 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 02:54:23 INFO - PROCESS | 3292 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 02:54:23 INFO - PROCESS | 3292 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 02:54:23 INFO - PROCESS | 3292 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 02:54:23 INFO - PROCESS | 3292 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 02:54:23 INFO - PROCESS | 3292 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 02:54:23 INFO - PROCESS | 3292 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 02:54:23 INFO - PROCESS | 3292 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 02:54:23 INFO - PROCESS | 3292 | Barrier/this.client.addBlocker/promise13.2% 02:55:20 INFO - TinderboxPrint: I/O read bytes / time
58,320,896 / 1,034,340 02:55:20 INFO - TinderboxPrint: I/O write bytes / time
483,438,080 / 3,511,420 02:55:20 INFO - TinderboxPrint: CPU idle
8,238.8 (86.8%) 02:55:20 INFO - TinderboxPrint: CPU user
1,219.1 (12.8%) 02:55:20 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 58300928; Read time: 0; Write time: 90610 02:55:20 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 58320896; Write bytes: 407056384; Read time: 1034340; Write time: 3127980 02:55:20 INFO - Running post-run listener: _upload_blobber_files 02:55:20 INFO - Blob upload gear active. 02:55:20 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 02:55:20 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:55:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 02:55:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 02:55:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:55:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ... 02:55:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:55:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:55:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:55:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:55:21 INFO - (blobuploader) - INFO - Done attempting. 02:55:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ... 02:55:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:55:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:55:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:55:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:55:22 INFO - (blobuploader) - INFO - Done attempting. 02:55:22 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 02:55:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:55:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:55:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:55:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:55:31 INFO - (blobuploader) - INFO - Done attempting. 02:55:31 INFO - (blobuploader) - INFO - Iteration through files over. 02:55:31 INFO - Return code: 0 02:55:31 INFO - rmtree: C:\slave\test\build\uploaded_files.json 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 02:55:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3a1b4bc734329eabc45eb3634561bbcf206d298984d7089551f41009ba6d0cbaf1fa834f9ade1c51be28dd86194a4428366a70d9210e0c4eb9b2f0e29cb3aff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/738dbec6d2133b07d0a634257345be0a7d7e05b9f53878994e521a7f301de432db080f1535296805d12ef373f22e30d538c454788073ad8d7cda696652baedd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9950548ff4f88517a951fc4ea6bfcaa05d013f335bb210de5892ef6750da272edbaec6e64cb757e21c6fda3c23bbe3cc2da60879abfb9d276a0d2b0dd1eabf69"} 02:55:31 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 02:55:31 INFO - Writing to file C:\slave\test\properties\blobber_files 02:55:31 INFO - Contents: 02:55:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3a1b4bc734329eabc45eb3634561bbcf206d298984d7089551f41009ba6d0cbaf1fa834f9ade1c51be28dd86194a4428366a70d9210e0c4eb9b2f0e29cb3aff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/738dbec6d2133b07d0a634257345be0a7d7e05b9f53878994e521a7f301de432db080f1535296805d12ef373f22e30d538c454788073ad8d7cda696652baedd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9950548ff4f88517a951fc4ea6bfcaa05d013f335bb210de5892ef6750da272edbaec6e64cb757e21c6fda3c23bbe3cc2da60879abfb9d276a0d2b0dd1eabf69"} 02:55:31 INFO - Running post-run listener: copy_logs_to_upload_dir 02:55:31 INFO - Copying logs to upload dir... 02:55:31 INFO - mkdir: C:\slave\test\build\upload\logs 02:55:31 INFO - Copying logs to upload dir... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... 02:55:31 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1404.991000 ========= master_lag: -0.07 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-08-24 02:55:31.586816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 02:55:31.590576) ========= '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-100 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-100 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-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3a1b4bc734329eabc45eb3634561bbcf206d298984d7089551f41009ba6d0cbaf1fa834f9ade1c51be28dd86194a4428366a70d9210e0c4eb9b2f0e29cb3aff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/738dbec6d2133b07d0a634257345be0a7d7e05b9f53878994e521a7f301de432db080f1535296805d12ef373f22e30d538c454788073ad8d7cda696652baedd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9950548ff4f88517a951fc4ea6bfcaa05d013f335bb210de5892ef6750da272edbaec6e64cb757e21c6fda3c23bbe3cc2da60879abfb9d276a0d2b0dd1eabf69"} build_url:https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/RHzaVYFqQ2CkZYFIJQcmow/artifacts/public/build/firefox-51.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3a1b4bc734329eabc45eb3634561bbcf206d298984d7089551f41009ba6d0cbaf1fa834f9ade1c51be28dd86194a4428366a70d9210e0c4eb9b2f0e29cb3aff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/738dbec6d2133b07d0a634257345be0a7d7e05b9f53878994e521a7f301de432db080f1535296805d12ef373f22e30d538c454788073ad8d7cda696652baedd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/9950548ff4f88517a951fc4ea6bfcaa05d013f335bb210de5892ef6750da272edbaec6e64cb757e21c6fda3c23bbe3cc2da60879abfb9d276a0d2b0dd1eabf69"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 02:55:31.708815) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 02:55:31.709220) ========= '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-100 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-100 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-100 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-08-24 02:55:31.823686) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-08-24 02:55:31.824116) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-08-24 02:55:32.576459) ========= ========= Total master_lag: 0.03 =========