builder: mozilla-central_win7-ix_test-dromaeojs slave: t-w732-ix-075 starttime: 1445765392.88 results: success (0) buildid: 20151025005432 builduid: 271edc89668a42c4bb1a17553053a44a revision: d53a52b39a95dced722cca90ac74529b66dd5253 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:52.882579) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:52.883215) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:52.883631) ========= 'cd' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-075 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-075 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-075 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 C:\slave\test program finished with exit code 0 elapsedTime=0.565000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:53.726300) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:53.726615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.124018) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.124330) ========= 'rm' '-rf' 'properties' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-075 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-075 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-075 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.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.800390) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.800706) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.801105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:54.801444) ========= '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-075 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-075 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-075 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:29:55-- 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% 16.80 MB/s 02:29:55 (16.80 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.739000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:29:55.558337) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-25 02:29:55.558749) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-075 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-075 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-075 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.801000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-25 02:30:10.392298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-25 02:30:10.392658) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev d53a52b39a95dced722cca90ac74529b66dd5253 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d53a52b39a95dced722cca90ac74529b66dd5253 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-075 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-075 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-075 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 2015-10-25 02:30:11,096 truncating revision to first 12 chars 2015-10-25 02:30:11,096 Setting DEBUG logging. 2015-10-25 02:30:11,096 attempt 1/10 2015-10-25 02:30:11,096 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d53a52b39a95?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-25 02:30:11,956 unpacking tar archive at: mozilla-central-d53a52b39a95/testing/mozharness/ program finished with exit code 0 elapsedTime=1.580000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-25 02:30:12.004758) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-25 02:30:12.005088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-25 02:30:12.019712) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-25 02:30:12.020146) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-25 02:30:12.020647) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2015-10-25 02:30:12.020959) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-075 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-075 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-075 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:30:12 INFO - MultiFileLogger online at 20151025 02:30:12 in C:\slave\test 02:30:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:30:12 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 02:30:12 INFO - {'append_to_log': False, 02:30:12 INFO - 'base_work_dir': 'C:\\slave\\test', 02:30:12 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:30:12 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 02:30:12 INFO - 'branch': 'Firefox-Non-PGO', 02:30:12 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:12 INFO - 'config_files': ('talos/windows_config.py',), 02:30:12 INFO - 'default_actions': ('clobber', 02:30:12 INFO - 'read-buildbot-config', 02:30:12 INFO - 'download-and-extract', 02:30:12 INFO - 'populate-webroot', 02:30:12 INFO - 'create-virtualenv', 02:30:12 INFO - 'install', 02:30:12 INFO - 'run-tests'), 02:30:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:12 INFO - 'download_minidump_stackwalk': True, 02:30:12 INFO - 'download_symbols': 'ondemand', 02:30:12 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 02:30:12 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 02:30:12 INFO - 'hg': 'c:/mozilla-build/hg/hg', 02:30:12 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 02:30:12 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 02:30:12 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 02:30:12 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 02:30:12 INFO - 'C:/mozilla-build/tooltool.py'), 02:30:12 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 02:30:12 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 02:30:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:12 INFO - 'installer_path': 'installer.exe', 02:30:12 INFO - 'log_level': 'info', 02:30:12 INFO - 'log_name': 'talos', 02:30:12 INFO - 'log_to_console': True, 02:30:12 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 02:30:12 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 02:30:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 02:30:12 INFO - 'opt_config_files': (), 02:30:12 INFO - 'pip_index': False, 02:30:12 INFO - 'sps_profile': False, 02:30:12 INFO - 'sps_profile_interval': 0, 02:30:12 INFO - 'suite': 'dromaeojs', 02:30:12 INFO - 'system_bits': '32', 02:30:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:12 INFO - 'title': 'T-W732-IX-075', 02:30:12 INFO - 'use_talos_json': True, 02:30:12 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 02:30:12 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 02:30:12 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 02:30:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:12 INFO - 'work_dir': 'build'} 02:30:12 INFO - ##### 02:30:12 INFO - ##### Running clobber step. 02:30:12 INFO - ##### 02:30:12 INFO - Running pre-action listener: _resource_record_pre_action 02:30:12 INFO - Running main action method: clobber 02:30:12 INFO - rmtree: C:\slave\test\build 02:30:12 INFO - Using _rmtree_windows ... 02:30:12 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 02:31:36 INFO - Running post-action listener: _resource_record_post_action 02:31:36 INFO - ##### 02:31:36 INFO - ##### Running read-buildbot-config step. 02:31:36 INFO - ##### 02:31:36 INFO - Running pre-action listener: _resource_record_pre_action 02:31:36 INFO - Running main action method: read_buildbot_config 02:31:36 INFO - Using buildbot properties: 02:31:36 INFO - { 02:31:36 INFO - "properties": { 02:31:36 INFO - "buildnumber": 136, 02:31:36 INFO - "product": "firefox", 02:31:36 INFO - "script_repo_revision": "production", 02:31:36 INFO - "builddir": "mozilla-central_win7-ix_test-dromaeojs", 02:31:36 INFO - "repository": "", 02:31:36 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 02:31:36 INFO - "buildid": "20151025005432", 02:31:36 INFO - "slavename": "t-w732-ix-075", 02:31:36 INFO - "pgo_build": "False", 02:31:36 INFO - "basedir": "C:\\slave\\test", 02:31:36 INFO - "project": "", 02:31:36 INFO - "platform": "win7-ix", 02:31:36 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 02:31:36 INFO - "slavebuilddir": "test", 02:31:36 INFO - "scheduler": "tests-mozilla-central-win32-talos", 02:31:36 INFO - "branch": "mozilla-central", 02:31:36 INFO - "repo_path": "mozilla-central", 02:31:36 INFO - "stage_platform": "win32", 02:31:36 INFO - "builduid": "271edc89668a42c4bb1a17553053a44a", 02:31:36 INFO - "revision": "d53a52b39a95dced722cca90ac74529b66dd5253" 02:31:36 INFO - }, 02:31:36 INFO - "sourcestamp": { 02:31:36 INFO - "repository": "", 02:31:36 INFO - "hasPatch": false, 02:31:36 INFO - "project": "", 02:31:36 INFO - "branch": "mozilla-central-win32-talos", 02:31:36 INFO - "changes": [ 02:31:36 INFO - { 02:31:36 INFO - "category": null, 02:31:36 INFO - "files": [ 02:31:36 INFO - { 02:31:36 INFO - "url": null, 02:31:36 INFO - "name": "https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 02:31:36 INFO - } 02:31:36 INFO - ], 02:31:36 INFO - "repository": "", 02:31:36 INFO - "rev": "d53a52b39a95dced722cca90ac74529b66dd5253", 02:31:36 INFO - "who": "nthomas@mozilla.com", 02:31:36 INFO - "when": 1445765366, 02:31:36 INFO - "number": 6571104, 02:31:36 INFO - "comments": "Bug 1217431 - Don't get 'latest' URLs for android updates; r=jlund, a=nightly-automation", 02:31:36 INFO - "project": "", 02:31:36 INFO - "at": "Sun 25 Oct 2015 02:29:26", 02:31:36 INFO - "branch": "mozilla-central-win32-talos", 02:31:36 INFO - "revlink": "", 02:31:36 INFO - "properties": [ 02:31:36 INFO - [ 02:31:36 INFO - "buildid", 02:31:36 INFO - "20151025005432", 02:31:36 INFO - "Change" 02:31:36 INFO - ], 02:31:36 INFO - [ 02:31:36 INFO - "builduid", 02:31:36 INFO - "271edc89668a42c4bb1a17553053a44a", 02:31:36 INFO - "Change" 02:31:36 INFO - ], 02:31:36 INFO - [ 02:31:36 INFO - "pgo_build", 02:31:36 INFO - "False", 02:31:36 INFO - "Change" 02:31:36 INFO - ] 02:31:36 INFO - ], 02:31:36 INFO - "revision": "d53a52b39a95dced722cca90ac74529b66dd5253" 02:31:36 INFO - } 02:31:36 INFO - ], 02:31:36 INFO - "revision": "d53a52b39a95dced722cca90ac74529b66dd5253" 02:31:36 INFO - } 02:31:36 INFO - } 02:31:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 02:31:36 INFO - Running post-action listener: _resource_record_post_action 02:31:36 INFO - ##### 02:31:36 INFO - ##### Running download-and-extract step. 02:31:36 INFO - ##### 02:31:36 INFO - Running pre-action listener: _resource_record_pre_action 02:31:36 INFO - Running main action method: download_and_extract 02:31:36 INFO - mkdir: C:\slave\test\build\tests 02:31:36 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:36 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:31:36 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/test_packages.json 02:31:36 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 02:31:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 02:31:37 INFO - Downloaded 1221 bytes. 02:31:37 INFO - Reading from file C:\slave\test\build\test_packages.json 02:31:37 INFO - Using the following test package requirements: 02:31:37 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:31:37 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 02:31:37 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'jsshell-win32.zip'], 02:31:37 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 02:31:37 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:31:37 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 02:31:37 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 02:31:37 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 02:31:37 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:31:37 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:31:37 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 02:31:37 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:31:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:37 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:31:37 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:31:37 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:31:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:31:38 INFO - Downloaded 18007297 bytes. 02:31:38 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 02:31:38 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:31:38 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:32:14 INFO - Return code: 0 02:32:14 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 02:32: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:14 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:32:14 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:32:14 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:32:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:32:15 INFO - Downloaded 18007297 bytes. 02:32:15 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 02:32:15 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:32:15 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:32:44 INFO - Return code: 0 02:32:44 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:44 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 02:32:44 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 02:32:44 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:32:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 02:32:45 INFO - Downloaded 11131307 bytes. 02:32:45 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests 02:32:45 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:32:45 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 02:32:47 INFO - Return code: 0 02:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:47 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 02:32:47 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:32:47 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 02:32:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 02:32:50 INFO - Downloaded 51464471 bytes. 02:32:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:32:50 INFO - mkdir: C:\slave\test\properties 02:32:50 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 02:32:50 INFO - Writing to file C:\slave\test\properties\build_url 02:32:50 INFO - Contents: 02:32:50 INFO - build_url:https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:32:50 INFO - Running post-action listener: _resource_record_post_action 02:32:50 INFO - Running post-action listener: set_extra_try_arguments 02:32:50 INFO - ##### 02:32:50 INFO - ##### Running populate-webroot step. 02:32:50 INFO - ##### 02:32:50 INFO - Running pre-action listener: _resource_record_pre_action 02:32:50 INFO - Running main action method: populate_webroot 02:32:50 INFO - Guessing talos json url... 02:32:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:50 INFO - https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 02:32:50 INFO - trying https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 02:32:50 INFO - Downloading https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 02:32:51 INFO - Downloaded 99 bytes. 02:32:51 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:32:51 INFO - Contents: 02:32:51 INFO - 20151025005432 02:32:51 INFO - https://hg.mozilla.org/mozilla-central/rev/d53a52b39a95dced722cca90ac74529b66dd5253 02:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:51 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/d53a52b39a/testing/talos/talos.json 02:32:51 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/d53a52b39a/testing/talos/talos.json to C:\slave\test\build\talos.json 02:32:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/d53a52b39a/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 02:32:51 INFO - Downloaded 4264 bytes. 02:32:51 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:32:51 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:32:51 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 02:32:51 INFO - u'../fennec_ids.txt'], 02:32:51 INFO - u'tests': [u'tcheck2']}, 02:32:51 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:32:51 INFO - u'--tppagecycles', 02:32:51 INFO - u'7'], 02:32:51 INFO - u'tests': [u'tsvgm']}}, 02:32:51 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 02:32:51 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:32:51 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 02:32:51 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'dromaeo_css', 02:32:51 INFO - u'kraken', 02:32:51 INFO - u'v8_7']}, 02:32:51 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:32:51 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:32:51 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'tests': [u'damp', u'tps']}, 02:32:51 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'damp', u'tps']}, 02:32:51 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 02:32:51 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:32:51 INFO - u'other': {u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'other_l64': {u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'other_nol64': {u'tests': [u'a11yr', 02:32:51 INFO - u'ts_paint', 02:32:51 INFO - u'tpaint', 02:32:51 INFO - u'sessionrestore', 02:32:51 INFO - u'sessionrestore_no_auto_restore']}, 02:32:51 INFO - u'svgr': {u'tests': [u'tsvgx', 02:32:51 INFO - u'tsvgr_opacity', 02:32:51 INFO - u'tart', 02:32:51 INFO - u'tscrollx', 02:32:51 INFO - u'cart']}, 02:32:51 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'tsvgx', 02:32:51 INFO - u'tsvgr_opacity', 02:32:51 INFO - u'tart', 02:32:51 INFO - u'tscrollx', 02:32:51 INFO - u'cart']}, 02:32:51 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'tests': [u'tp5o']}, 02:32:51 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'talos_options': [u'--e10s'], 02:32:51 INFO - u'tests': [u'tp5o']}, 02:32:51 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'talos_options': [u'--xperf_path', 02:32:51 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:51 INFO - u'tests': [u'tp5n']}, 02:32:51 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:51 INFO - u'talos_options': [u'--e10s', 02:32:51 INFO - u'--xperf_path', 02:32:51 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 02:32:51 INFO - u'C:/slave/talos-data/talos/xperf.config'], 02:32:51 INFO - u'tests': [u'tp5n']}}, 02:32:51 INFO - u'talos.zip': {u'path': u'', 02:32:51 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:32:51 INFO - Running post-action listener: _resource_record_post_action 02:32:51 INFO - ##### 02:32:51 INFO - ##### Running create-virtualenv step. 02:32:51 INFO - ##### 02:32:51 INFO - Running pre-action listener: _resource_record_pre_action 02:32:51 INFO - Running main action method: create_virtualenv 02:32:51 INFO - Creating virtualenv C:\slave\test\build/venv 02:32:51 INFO - mkdir: C:\slave\test\build\venv\Scripts 02:32:51 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 02:32:51 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:32:51 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:32:51 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:33:02 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 02:33:06 INFO - Installing distribute......................................................................................................................................................................................done. 02:33:08 INFO - Return code: 0 02:33:08 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 02:33:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:08 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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:33:08 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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:33:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:08 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:08 INFO - 'DCLOCATION': 'SCL3', 02:33:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:08 INFO - 'HOMEDRIVE': 'C:', 02:33:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:08 INFO - 'KTS_VERSION': '1.19c', 02:33:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:08 INFO - 'MOZ_NO_REMOTE': '1', 02:33:08 INFO - 'NO_EM_RESTART': '1', 02:33:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:08 INFO - 'OS': 'Windows_NT', 02:33:08 INFO - 'OURDRIVE': 'C:', 02:33:08 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:33:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:08 INFO - 'PROCESSOR_LEVEL': '6', 02:33:08 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:08 INFO - 'PROMPT': '$P$G', 02:33:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:08 INFO - 'PWD': 'C:\\slave\\test', 02:33:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:08 INFO - 'SYSTEMDRIVE': 'C:', 02:33:08 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:08 INFO - 'TEST1': 'testie', 02:33:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:08 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:08 INFO - 'USERNAME': 'cltbld', 02:33:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:08 INFO - 'WINDIR': 'C:\\windows', 02:33:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:33:09 INFO - Downloading/unpacking pip>=1.5 02:33:09 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 02:33:14 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 02:33:14 INFO - Running setup.py egg_info for package pip 02:33:14 INFO - warning: no files found matching 'pip\cacert.pem' 02:33:14 INFO - warning: no files found matching '*.html' under directory 'docs' 02:33:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:33:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:33:14 INFO - Installing collected packages: pip 02:33:14 INFO - Found existing installation: pip 0.8.2 02:33:14 INFO - Uninstalling pip: 02:33:14 INFO - Successfully uninstalled pip 02:33:14 INFO - Running setup.py install for pip 02:33:14 INFO - warning: no files found matching 'pip\cacert.pem' 02:33:14 INFO - warning: no files found matching '*.html' under directory 'docs' 02:33:14 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:33:14 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:33:14 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 02:33:14 INFO - Successfully installed pip 02:33:14 INFO - Cleaning up... 02:33:14 INFO - Return code: 0 02:33:14 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build 02:33:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:33:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:14 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:14 INFO - 'DCLOCATION': 'SCL3', 02:33:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:14 INFO - 'HOMEDRIVE': 'C:', 02:33:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:14 INFO - 'KTS_VERSION': '1.19c', 02:33:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:14 INFO - 'MOZ_NO_REMOTE': '1', 02:33:14 INFO - 'NO_EM_RESTART': '1', 02:33:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:14 INFO - 'OS': 'Windows_NT', 02:33:14 INFO - 'OURDRIVE': 'C:', 02:33: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:33:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:14 INFO - 'PROCESSOR_LEVEL': '6', 02:33:14 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:14 INFO - 'PROMPT': '$P$G', 02:33:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:14 INFO - 'PWD': 'C:\\slave\\test', 02:33:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:14 INFO - 'SYSTEMDRIVE': 'C:', 02:33:14 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:14 INFO - 'TEST1': 'testie', 02:33:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:14 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:14 INFO - 'USERNAME': 'cltbld', 02:33:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:14 INFO - 'WINDIR': 'C:\\windows', 02:33:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:17 INFO - Downloading/unpacking psutil>=0.7.1 02:33:17 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:33:17 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:33:18 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 02:33:18 INFO - Installing collected packages: psutil 02:33:18 INFO - Successfully installed psutil 02:33:18 INFO - Cleaning up... 02:33:18 INFO - Return code: 0 02:33:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 02:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test\build 02:33:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:33:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:18 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:18 INFO - 'DCLOCATION': 'SCL3', 02:33:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:18 INFO - 'HOMEDRIVE': 'C:', 02:33:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:18 INFO - 'KTS_VERSION': '1.19c', 02:33:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:18 INFO - 'MOZ_NO_REMOTE': '1', 02:33:18 INFO - 'NO_EM_RESTART': '1', 02:33:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:18 INFO - 'OS': 'Windows_NT', 02:33:18 INFO - 'OURDRIVE': 'C:', 02:33:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:33:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:18 INFO - 'PROCESSOR_LEVEL': '6', 02:33:18 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:18 INFO - 'PROMPT': '$P$G', 02:33:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:18 INFO - 'PWD': 'C:\\slave\\test', 02:33:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:18 INFO - 'SYSTEMDRIVE': 'C:', 02:33:18 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:18 INFO - 'TEST1': 'testie', 02:33:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:18 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:18 INFO - 'USERNAME': 'cltbld', 02:33:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:18 INFO - 'WINDIR': 'C:\\windows', 02:33:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:33:18 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:33:18 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:33:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:33:19 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 02:33:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 02:33:19 INFO - Installing collected packages: mozsystemmonitor 02:33:19 INFO - Running setup.py install for mozsystemmonitor 02:33:19 INFO - Successfully installed mozsystemmonitor 02:33:19 INFO - Cleaning up... 02:33:20 INFO - Return code: 0 02:33:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 02:33:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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:33:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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:33:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:20 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:20 INFO - 'DCLOCATION': 'SCL3', 02:33:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:20 INFO - 'HOMEDRIVE': 'C:', 02:33:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:20 INFO - 'KTS_VERSION': '1.19c', 02:33:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:20 INFO - 'MOZ_NO_REMOTE': '1', 02:33:20 INFO - 'NO_EM_RESTART': '1', 02:33:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:20 INFO - 'OS': 'Windows_NT', 02:33:20 INFO - 'OURDRIVE': 'C:', 02:33:20 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:33:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:20 INFO - 'PROCESSOR_LEVEL': '6', 02:33:20 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:20 INFO - 'PROMPT': '$P$G', 02:33:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:20 INFO - 'PWD': 'C:\\slave\\test', 02:33:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:20 INFO - 'SYSTEMDRIVE': 'C:', 02:33:20 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:20 INFO - 'TEST1': 'testie', 02:33:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:20 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:20 INFO - 'USERNAME': 'cltbld', 02:33:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:20 INFO - 'WINDIR': 'C:\\windows', 02:33:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:20 INFO - Downloading/unpacking blobuploader==1.2.4 02:33:20 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:33:20 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:33:21 INFO - Downloading blobuploader-1.2.4.tar.gz 02:33:21 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 02:33:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:33:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:21 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 02:33:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 02:33:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:33:22 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:33:22 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:33:22 INFO - Downloading docopt-0.6.1.tar.gz 02:33:22 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 02:33:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 02:33:22 INFO - Installing collected packages: blobuploader, requests, docopt 02:33:22 INFO - Running setup.py install for blobuploader 02:33:22 INFO - Running setup.py install for requests 02:33:23 INFO - Running setup.py install for docopt 02:33:23 INFO - Successfully installed blobuploader requests docopt 02:33:23 INFO - Cleaning up... 02:33:24 INFO - Return code: 0 02:33:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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:33:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --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:33:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:24 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:24 INFO - 'DCLOCATION': 'SCL3', 02:33:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:24 INFO - 'HOMEDRIVE': 'C:', 02:33:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:24 INFO - 'KTS_VERSION': '1.19c', 02:33:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:24 INFO - 'MOZ_NO_REMOTE': '1', 02:33:24 INFO - 'NO_EM_RESTART': '1', 02:33:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:24 INFO - 'OS': 'Windows_NT', 02:33:24 INFO - 'OURDRIVE': 'C:', 02:33: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:33:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:24 INFO - 'PROCESSOR_LEVEL': '6', 02:33:24 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:24 INFO - 'PROMPT': '$P$G', 02:33:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:24 INFO - 'PWD': 'C:\\slave\\test', 02:33:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:24 INFO - 'SYSTEMDRIVE': 'C:', 02:33:24 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:24 INFO - 'TEST1': 'testie', 02:33:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:24 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:24 INFO - 'USERNAME': 'cltbld', 02:33:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:24 INFO - 'WINDIR': 'C:\\windows', 02:33:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vgzxim-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:33:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:33:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5igxs7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0peieo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6kh0og-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pcy2vq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qx0vbk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x3vuac-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gh5g9b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2i3q2q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:33:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:33:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vflgi5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvpppx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dsrhu8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oon8fb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bknv9b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lor3u1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:33:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:33:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fydng2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:33:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:33:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kb1czz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:33:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:33:27 INFO - Running setup.py install for manifestparser 02:33:27 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 02:33:27 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 02:33:27 INFO - Running setup.py install for mozcrash 02:33:28 INFO - Running setup.py install for mozdebug 02:33:28 INFO - Running setup.py install for mozdevice 02:33:28 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 02:33:28 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 02:33:28 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 02:33:28 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 02:33:29 INFO - Running setup.py install for mozfile 02:33:29 INFO - Running setup.py install for mozhttpd 02:33:29 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 02:33:29 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 02:33:29 INFO - Running setup.py install for mozinfo 02:33:29 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 02:33:29 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Running setup.py install for mozInstall 02:33:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 02:33:30 INFO - Running setup.py install for mozleak 02:33:30 INFO - Running setup.py install for mozlog 02:33:31 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 02:33:31 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 02:33:31 INFO - Running setup.py install for moznetwork 02:33:31 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 02:33:31 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Running setup.py install for mozprocess 02:33:32 INFO - Running setup.py install for mozprofile 02:33:32 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 02:33:32 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 02:33:33 INFO - Running setup.py install for mozrunner 02:33:33 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 02:33:33 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 02:33:33 INFO - Running setup.py install for mozscreenshot 02:33:34 INFO - Running setup.py install for moztest 02:33:34 INFO - Running setup.py install for mozversion 02:33:34 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 02:33:34 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 02:33:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:33:35 INFO - Cleaning up... 02:33:35 INFO - Return code: 0 02:33:35 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:35 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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:33:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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:33:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:35 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:35 INFO - 'DCLOCATION': 'SCL3', 02:33:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:35 INFO - 'HOMEDRIVE': 'C:', 02:33:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:35 INFO - 'KTS_VERSION': '1.19c', 02:33:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:35 INFO - 'MOZ_NO_REMOTE': '1', 02:33:35 INFO - 'NO_EM_RESTART': '1', 02:33:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:35 INFO - 'OS': 'Windows_NT', 02:33:35 INFO - 'OURDRIVE': 'C:', 02:33:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:33:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:35 INFO - 'PROCESSOR_LEVEL': '6', 02:33:35 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:35 INFO - 'PROMPT': '$P$G', 02:33:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:35 INFO - 'PWD': 'C:\\slave\\test', 02:33:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:35 INFO - 'SYSTEMDRIVE': 'C:', 02:33:35 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:35 INFO - 'TEST1': 'testie', 02:33:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:35 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:35 INFO - 'USERNAME': 'cltbld', 02:33:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:35 INFO - 'WINDIR': 'C:\\windows', 02:33:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-osevu8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:33:35 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:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ymb876-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:33:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jyr5iy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:33:35 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:33:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:33:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ingvui-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yjtjsh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:33:36 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f2z8os-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:33:36 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ld0gl0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-smnk0c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:33:36 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qkvtsg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:33:36 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gd7hk0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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:33:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:33:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6hlaov-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:33:37 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jedb2q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dtpost-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eq6ff7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ns4b54-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:33:37 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-twc_6s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:33:37 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:33:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:33:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tvkahz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:33:37 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:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 02:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 02:33:37 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:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 02:33:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 02:33:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 02:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:33:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:33:38 INFO - Downloading blessings-1.5.1.tar.gz 02:33:38 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 02:33:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 02:33:39 INFO - Installing collected packages: blessings 02:33:39 INFO - Running setup.py install for blessings 02:33:39 INFO - Successfully installed blessings 02:33:39 INFO - Cleaning up... 02:33:42 INFO - Return code: 0 02:33:42 INFO - Done creating virtualenv C:\slave\test\build/venv. 02:33:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:33:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:33:42 INFO - Reading from file tmpfile_stdout 02:33:42 INFO - Using _rmtree_windows ... 02:33:42 INFO - Using _rmtree_windows ... 02:33:42 INFO - Current package versions: 02:33:42 INFO - blessings == 1.5.1 02:33:42 INFO - blobuploader == 1.2.4 02:33:42 INFO - docopt == 0.6.1 02:33:42 INFO - manifestparser == 1.1 02:33:42 INFO - mozInstall == 1.12 02:33:42 INFO - mozcrash == 0.16 02:33:42 INFO - mozdebug == 0.1 02:33:42 INFO - mozdevice == 0.46 02:33:42 INFO - mozfile == 1.2 02:33:42 INFO - mozhttpd == 0.7 02:33:42 INFO - mozinfo == 0.8 02:33:42 INFO - mozleak == 0.1 02:33:42 INFO - mozlog == 3.0 02:33:42 INFO - moznetwork == 0.27 02:33:42 INFO - mozprocess == 0.22 02:33:42 INFO - mozprofile == 0.27 02:33:42 INFO - mozrunner == 6.11 02:33:42 INFO - mozscreenshot == 0.1 02:33:42 INFO - mozsystemmonitor == 0.0 02:33:42 INFO - moztest == 0.7 02:33:42 INFO - mozversion == 1.4 02:33:42 INFO - psutil == 3.1.1 02:33:42 INFO - requests == 1.2.3 02:33:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:33:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:33:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:33:42 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x01A57B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A1EEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01930680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-075', 'USERDOMAIN': 'T-W732-IX-075', '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-075', '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:33:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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:33:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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:33:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:42 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:42 INFO - 'DCLOCATION': 'SCL3', 02:33:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:42 INFO - 'HOMEDRIVE': 'C:', 02:33:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:42 INFO - 'KTS_VERSION': '1.19c', 02:33:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:42 INFO - 'MOZ_NO_REMOTE': '1', 02:33:42 INFO - 'NO_EM_RESTART': '1', 02:33:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:42 INFO - 'OS': 'Windows_NT', 02:33:42 INFO - 'OURDRIVE': 'C:', 02:33:42 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:33:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:42 INFO - 'PROCESSOR_LEVEL': '6', 02:33:42 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:42 INFO - 'PROMPT': '$P$G', 02:33:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:42 INFO - 'PWD': 'C:\\slave\\test', 02:33:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:42 INFO - 'SYSTEMDRIVE': 'C:', 02:33:42 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:42 INFO - 'TEST1': 'testie', 02:33:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:42 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:42 INFO - 'USERNAME': 'cltbld', 02:33:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:42 INFO - 'WINDIR': 'C:\\windows', 02:33:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:33:42 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 1)) 02:33:42 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 2)) 02:33:42 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 3)) 02:33:42 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 4)) 02:33:42 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 5)) 02:33:42 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 6)) 02:33:42 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 7)) 02:33:42 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 8)) 02:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 02:33:42 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 3)) 02:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 02:33:42 INFO - Cleaning up... 02:33:42 INFO - Return code: 0 02:33:42 INFO - Running post-action listener: _resource_record_post_action 02:33:42 INFO - Running post-action listener: _start_resource_monitoring 02:33:42 INFO - Starting resource monitoring. 02:33:43 INFO - ##### 02:33:43 INFO - ##### Running install step. 02:33:43 INFO - ##### 02:33:43 INFO - Running pre-action listener: _resource_record_pre_action 02:33:43 INFO - Running main action method: install 02:33:43 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:33:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:33:43 INFO - Reading from file tmpfile_stdout 02:33:43 INFO - Using _rmtree_windows ... 02:33:43 INFO - Using _rmtree_windows ... 02:33:43 INFO - Detecting whether we're running mozinstall >=1.0... 02:33:43 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 02:33:43 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 02:33:43 INFO - Reading from file tmpfile_stdout 02:33:43 INFO - Output received: 02:33:43 INFO - Usage: mozinstall-script.py [options] installer 02:33:43 INFO - Options: 02:33:43 INFO - -h, --help show this help message and exit 02:33:43 INFO - -d DEST, --destination=DEST 02:33:43 INFO - Directory to install application into. [default: 02:33:43 INFO - "C:\slave\test"] 02:33:43 INFO - --app=APP Application being installed. [default: firefox] 02:33:43 INFO - Using _rmtree_windows ... 02:33:43 INFO - Using _rmtree_windows ... 02:33:43 INFO - mkdir: C:\slave\test\build\application 02:33:43 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-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 02:33:43 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test\build\application 02:33:45 INFO - Reading from file tmpfile_stdout 02:33:45 INFO - Output received: 02:33:45 INFO - C:\slave\test\build\application\firefox\firefox.exe 02:33:45 INFO - Using _rmtree_windows ... 02:33:45 INFO - Using _rmtree_windows ... 02:33:45 INFO - Running post-action listener: _resource_record_post_action 02:33:45 INFO - ##### 02:33:45 INFO - ##### Running run-tests step. 02:33:45 INFO - ##### 02:33:45 INFO - Running pre-action listener: _resource_record_pre_action 02:33:45 INFO - Running main action method: run_tests 02:33:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 02:33:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 02:33:45 INFO - Python 2.7.3 02:33:45 INFO - Return code: 0 02:33:45 INFO - grabbing minidump binary from tooltool 02:33:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:33:45 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F59660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 02:33:45 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:33:45 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:33:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:33:45 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmplepo4j 02:33:45 INFO - INFO - File integrity verified, renaming tmplepo4j to win32-minidump_stackwalk.exe 02:33:45 INFO - Return code: 0 02:33:45 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 02:33:45 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 02:33:45 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 02:33:45 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 02:33:45 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 02:33:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-075', '--webServer', 'localhost'] in C:\slave\test\build 02:33:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-075 --webServer localhost 02:33:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:33:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:33:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:33:45 INFO - 'COMPUTERNAME': 'T-W732-IX-075', 02:33:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:33:45 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:33:45 INFO - 'DCLOCATION': 'SCL3', 02:33:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:33:45 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:33:45 INFO - 'HOMEDRIVE': 'C:', 02:33:45 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:33:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:33:45 INFO - 'KTS_VERSION': '1.19c', 02:33:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:33:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-075', 02:33:45 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:33:45 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 02:33:45 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:33:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:33:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:45 INFO - 'MOZ_NO_REMOTE': '1', 02:33:45 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:33:45 INFO - 'NO_EM_RESTART': '1', 02:33:45 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:33:45 INFO - 'OS': 'Windows_NT', 02:33:45 INFO - 'OURDRIVE': 'C:', 02:33:45 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:33:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:33:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:33:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:33:45 INFO - 'PROCESSOR_LEVEL': '6', 02:33:45 INFO - 'PROCESSOR_REVISION': '1e05', 02:33:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:33:45 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:33:45 INFO - 'PROMPT': '$P$G', 02:33:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:33:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:33:45 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:33:45 INFO - 'PWD': 'C:\\slave\\test', 02:33:45 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 02:33:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:33:45 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:33:45 INFO - 'SYSTEMDRIVE': 'C:', 02:33:45 INFO - 'SYSTEMROOT': 'C:\\windows', 02:33:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:45 INFO - 'TEST1': 'testie', 02:33:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:33:45 INFO - 'USERDOMAIN': 'T-W732-IX-075', 02:33:45 INFO - 'USERNAME': 'cltbld', 02:33:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:33:45 INFO - 'WINDIR': 'C:\\windows', 02:33:45 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:33:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:33:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:33:45 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-075', '--webServer', 'localhost'] with output_timeout 3600 02:33:47 INFO - mozversion INFO | application_buildid: 20151025005432 02:33:47 INFO - mozversion INFO | application_changeset: d53a52b39a95dced722cca90ac74529b66dd5253 02:33:47 INFO - mozversion INFO | application_display_name: Nightly 02:33:47 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:33:47 INFO - mozversion INFO | application_name: Firefox 02:33:47 INFO - mozversion INFO | application_remotingname: firefox 02:33:47 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 02:33:47 INFO - mozversion INFO | application_vendor: Mozilla 02:33:47 INFO - mozversion INFO | application_version: 44.0a1 02:33:47 INFO - mozversion INFO | platform_buildid: 20151025005432 02:33:47 INFO - mozversion INFO | platform_changeset: d53a52b39a95dced722cca90ac74529b66dd5253 02:33:47 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 02:33:47 INFO - mozversion INFO | platform_version: 44.0a1 02:33:47 INFO - 2015-10-25 02:33:47,105 DEBUG : using testdate: 1445765627 02:33:47 INFO - 2015-10-25 02:33:47,105 DEBUG : actual date: 1445765627 02:33:47 INFO - 2015-10-25 02:33:47,115 INFO : starting webserver on 'localhost:49716' 02:33:47 INFO - 2015-10-25 02:33:47,115 INFO : Starting test suite T-W732-IX-075 02:33:47 INFO - 2015-10-25 02:33:47,117 INFO : Starting test dromaeo_css 02:33:47 INFO - 2015-10-25 02:33:47,117 DEBUG : operating with platform_type : w7_ 02:33:47 INFO - 2015-10-25 02:33:47,117 INFO : Initialising browser for dromaeo_css test... 02:33:47 INFO - 2015-10-25 02:33:47,219 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpg9hmqu\profile http://localhost:49716/getInfo.html 02:33:55 INFO - 2015-10-25 02:33:55,361 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:33:55 INFO - 2015-10-25 02:33:55,361 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:33:55 INFO - 2015-10-25 02:33:55,361 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:33:55 INFO - 2015-10-25 02:33:55,361 DEBUG : BROWSER_OUTPUT: __metrics 02:33:55 INFO - 2015-10-25 02:33:55,482 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: console.error: 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: Stack: 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 02:33:57 INFO - 2015-10-25 02:33:57,256 DEBUG : BROWSER_OUTPUT: 02:33:57 INFO - 2015-10-25 02:33:57,957 INFO : Browser initialized. 02:33:57 INFO - 2015-10-25 02:33:57,957 INFO : Running cycle 1/1 for dromaeo_css test... 02:33:57 INFO - 2015-10-25 02:33:57,957 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpg9hmqu\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:34:09 INFO - 2015-10-25 02:34:09,579 DEBUG : BROWSER_OUTPUT: RSS: Main: 138960896 02:34:09 INFO - 2015-10-25 02:34:09,579 DEBUG : BROWSER_OUTPUT: 02:35:00 INFO - 2015-10-25 02:35:00,542 DEBUG : BROWSER_OUTPUT: 1445765700571 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:37:13 INFO - 2015-10-25 02:37:13,661 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49716/tests/dromaeo/cssquery-ext.html) 02:37:14 INFO - 2015-10-25 02:37:14,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 202817536 02:37:14 INFO - 2015-10-25 02:37:14,836 DEBUG : BROWSER_OUTPUT: 02:40:17 INFO - 2015-10-25 02:40:17,694 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-ext.html (next: http://localhost:49716/tests/dromaeo/cssquery-jquery.html) 02:40:18 INFO - 2015-10-25 02:40:18,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 195751936 02:40:18 INFO - 2015-10-25 02:40:18,253 DEBUG : BROWSER_OUTPUT: 02:43:20 INFO - 2015-10-25 02:43:20,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49716/tests/dromaeo/cssquery-mootools.html) 02:43:21 INFO - 2015-10-25 02:43:21,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 146169856 02:43:21 INFO - 2015-10-25 02:43:21,128 DEBUG : BROWSER_OUTPUT: 02:46:23 INFO - 2015-10-25 02:46:23,282 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49716/tests/dromaeo/cssquery-prototype.html) 02:46:23 INFO - 2015-10-25 02:46:23,868 DEBUG : BROWSER_OUTPUT: RSS: Main: 141598720 02:46:23 INFO - 2015-10-25 02:46:23,868 DEBUG : BROWSER_OUTPUT: 02:49:26 INFO - 2015-10-25 02:49:26,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49716/tests/dromaeo/cssquery-yui.html) 02:49:27 INFO - 2015-10-25 02:49:27,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 136527872 02:49:27 INFO - 2015-10-25 02:49:27,414 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,289 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49716/tests/dromaeo/cssquery-yui.html (next: http://localhost:49716/tests/dromaeo/cssquery-dojo.html) 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 188981248 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2318.1191147364;2381.7261014983274;2384.928452146436;2396.6285225789998;2401.943145016193;4143.73065750726;4150;4229.957700422995;4233.407322974782;4237.8728638140865;4102.384642303656;4154.397612346208;4220.577942205778;4221.134667393185;4238.215930052942;3535.292941411715;3604.585472670637;3645.1069487975483;3664.670179683828;3715.3869611514137;3600.891973240807;3651.0689774107564;3664.065663255866;3718.6653201211884;3761.2477504499075;3654.7076233901225;3657.2868290683327;3670.4310831821113;3702.185519185775;3707.1473561080966;3557.3952428087287;3612.3317186320614;3707.3512135376336;3708.5735140458924;3715.498407714951;3581.1405262737017;3620.3845346291196;3636.581793093775;3651.06897741077;3666.17511060012;4063.7215502049403;4104.15864747726;4140.37894315855;4140.482439695038;4167.770772607505;4315.568443155691;4356.499002614708;4440.933385999213;4441.622462090726;4451.399061126771;4383.75889326087;4386.232409328355;4399.054203346296;4482.551744825491;4533.138703646329;4065;4068.7151899367054;4138.985948442651;4151.3772934059525;4166.66666666669;4398.516163222043;4462.9107417851465;4540.046590216091;4545.840895568639;4560.429946256718;4300.548442413564;4395.846145384896;4401.889952751143;4411.338299255138;4412;4336.73979561221;4364.650827933758;4394.252976993855;4447.066116115588;4449.9555004450185;4154.210699966997;4350.412694286231;4419.049904270597;4429.955700443019;4439.689221754511;4274.572542745701;4315.266404711207;4323.740575565476;4331.848385306499;4332.718373305788;3041.31570396659;3093.6287645482685;3099.2561785171847;3105.7049580289836;3108.3006323577015;2810.170999555136;2859.6282483277455;2869.2826793301674;2870.540713485874;2870.899518516842;4051.777152256654;4072.38914162872;4083.8774836754947;4107.958920410817;4115.670746340285;4416.227160247008;4439.511653718088;4443.555644435595;4479.977600112044;4503.864884053483;4103.610157035077;4126.8761937141335;4240.660747140221;4244.766537840386;4246.957530424656;3596.532450781434;3722.181120153562;3793.165503589206;3793.715471339661;3831.7700937943814;4510.2783554631105;4537.205988951986;4538.931916021263;4606.378138951199;4634.907301854011;4311.482622085245;4413.691041627056;4440.800163992563;4527.185106680829;4530.637548996138;4404.581564751344;4453.955460445354;4527.90944181108;4529.8641040768825;4532.818687252605;4318.3738357938555;4322.940879484547;4395.230834603867;4424.269995450843;4442.8667139985855;4385.890352741207;4416.293393057095;4488.192125417456;4505.459344878635;4516.88707782308;4177.728447650893;4212;4265.808038638207;4276.080642661843;4341.023269764278;4217.135487225173;4220.2192594370135;4300.182965236595;4317.654587633045;4330.393744875783;3975.940360894531;4156.91686166281;4241.957580424156;4254.212970600449;4258.61672449481;4200.180964711852;4229.873103806891;4248.02295472037;4298.183345164409;4403.987082970871;4206.17979494008;4305.225059489197;4313.0726893718;4346.304591265382;4358.542353052884 02:52:30 INFO - 2015-10-25 02:52:30,545 DEBUG : BROWSER_OUTPUT: |1;ext.html;481.2684719226775;483.94918533553977;502.9622778291627;506.4429127959244;523.6099106165907;24956;36505.26989460209;37201.25597488052;37561.81219093901;37615.623843761554;38897;39123.21753564927;39477;39555;39614.80192599033;6228.3771622837585;6450.129232553616;7711.768646940601;7730.8453830923345;7859.489133206352;9772.267079968995;10009;10188.541515631778;10214.080732734052;10216.693499195037;10043.945385734502;10176.08415242628;10182.185425166006;10184.59261629534;10186.898131018706;6293.874122517547;6557.639329836857;9872.60509579616;9987.151092157175;10208;9665.516724163834;9738.756531086708;9746.853797193047;9830.754231144207;9842.753931151707;36298.274034519425;36590.26819463622;36739.26521469582;36755.81622091872;36782.63217367846;2736.849473278969;2739.7534221919923;2741.931451713723;2744.986275068632;2801.971980280212;27606;27707.168784936483;27929.441411171865;27950.580741288897;28425.147245582666;2156.8813715245656;2175.9020844062056;2190.7042549255807;2225.10995601759;2247.9213227536957;234380;236084.99999999828;237025;237175;237711;43828.78085609616;45389.319160212624;50933;54335.72832135814;55155.44844551583;50710.746446267534;52237.7388113057;52505.2124218137;52576.47423525792;52601.473985260425;10942.343459392461;11091.944540277409;11135.33188008722;11179.44102794857;11184.273022253528;7938.087119981276;8122.512649240946;8207.138250483733;8228.670853165806;8229.46508476947;16606.750898736285;16644.750328745078;16740.41408550695;16756.32974680999;16757.49727508177;7280.3083707047745;7531.849363012819;7566.546007239584;7581.734639287599;7632;775.7517594369748;855.5251835231506;862.3446180902433;888.8222355528791;992.8510723391405;25993;26099.478010439685;29561.55657665137;29636.85181574078;30417.23956901095;874.7856775090144;908.2053203446984;921.9677311294207;933.9439633622004;1004.437514991607;13751.243681597609;13923.443062277598;14010.579682609537;14014.299285035708;14050.016498844983;324365;327612;331261;333292;333545;46944.530554694014;47532.52467475281;47713.28430073552;47740;47782;1021.6628512590827;1106.2477515289359;1109.672646569264;1111.6442738323658;1121.4953271027907;25903.61144582795;26532.8673356632;26854.59718104269;26958.326041848326;27213.455730885667;39112.6088739109;39506.40740388954;39519.80240098781;39528.209435811696;39558.604413956644;252732;254585;256914;298350;306154;26292.342691432867;26627.201183964513;26670.599941000513;26745.197644070708;26842;35825.82087089548;36059;36618.26763464769;37092.44361334636;37116.25767484581;30636.846815765777;31053.534196986613;31372.372552549277;31497.527537086502;31915.840420797747;32392.83803580967;32773.672263278015;33060.66939330576;33083.669163308055;33630.3273934515;32295.677043230207;33154.668453316124;33178.33643327071;33237.833810830794;33263.66736332606;28738.568921465776;30447.238819029702;30855.2286192838;30905.381892361576;31032.689673103883;23017.284513293984;23522.529549408573;23676.289711308687;23761.16835910735;24091.638625420983 02:52:30 INFO - 2015-10-25 02:52:30,546 DEBUG : BROWSER_OUTPUT: |2;jquery.html;302.47671528256103;303.07410859823244;304.56599345932045;308.4909898667199;310.19194997032724;11241.66275011749;12307.63077107686;15246.771298430518;15271.541853744391;15296.69406611867;11953.64139075827;12906.290154041524;12939.482420703183;12955.676108097303;13144.671383215424;4924.581410580096;5054.115529782273;5065.468125846788;5087.313212716273;5089.6691715038405;3420.9486857697157;3449.603295620998;5045.64680472367;5539.556835453154;5706.400827913071;5582.8325150245555;5647.887042259153;5659.66042037477;5727.083666613343;5795.130730390433;4261.744295342259;4280.850170244026;5618.887622247553;5623.775048998035;5633.63381380209;3610.4223324268255;3621.692156166729;3625.075605720551;3681.4661874028116;3690.2988432197794;11402.258853174517;11415.42922853854;11485;11502.539898404053;11886.702832429171;1470.9852901470956;1623.9106849123293;1644.6875093732272;1677.4129054830833;1684.132671674089;8551.786205344853;8556.315494760403;8787.25308348791;8842.823143537158;8870.955645221797;1429.27821450167;1440.2150827798876;1455.6142622205125;1471.8748906342973;1501.6846462242943;55937.440625593634;56148.71925640386;56364.15453768197;57322.42677573213;57648;5658.405867383948;5658.830235092953;5703.572232082611;5721.971390143106;5761.251037365199;6464.644444555594;6472.190976127984;6584.572002819801;6601.3728695773825;6650.933490665031;4294.634956028773;4309.159713855768;4399.23013472641;4460.1971645104195;4493.168763778712;4473.753943533137;4486.955130448654;4497.3254011897825;4502.414686090788;4584.633229341712;2532.1643857527342;2565.4227798745133;2580.87095645217;2588.818782685232;2588.977353945181;913.0914739833908;937.0582564522612;947.7109481608176;966.3863446711248;1015.7409860485529;372.0271490053491;409.850404602324;418.97276677015896;422.1662217121161;425.4830381086994;9842.261830362751;9990.750231244278;10017.949910250549;10123.088921997056;10417.479126043667;350.82283446859685;381.7003652133062;389.3789405897589;399.76214152579166;410.8336123869778;5298.496642818926;5308.150695888639;5359.919601205985;5469.917951230735;5474.753636086386;307897;308691.2827179338;308706;309116;310493;59644.40355596389;63197;66434.33565664379;66977.66511167413;67308.32691673118;424.4100700026965;434.9847755328548;436.5874248834821;436.74668692158474;437.55806635297944;9458.668946586837;9632.95183524078;9678.51607419612;9772.804543909224;9827.705168844946;34184;34678.30643387169;34889.302213956085;36040.459393109646;36240.27519449649;44095;44270.557294427934;44502.77748611236;45549.31676024796;45593.77203113963;5290.576753859759;5424.1321388577635;5437.700926448925;5473.480019398151;5501.092319767194;10446.895531044593;10514.158867290445;10591.20565957541;10610.787784244118;10672.413017284123;7006.544574602634;9037.277017838689;9057.275417966415;9062.954685226532;9073.500957447404;7873.803154920944;7941.205879412013;8271.793205169679;8383.28742056932;9047;9076.364654474124;9136.26909847224;9175.862362064441;9187.586558605013;9261.981182069832;5372.086745253395;5373.811916582901;5374.086405311028;5379.865503362447;5397.59518036141;8799.736007919773;8903.287736981156;9009.828722266064;9016.368854180144;9400.247980161708 02:52:30 INFO - 2015-10-25 02:52:30,548 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1086.6631344283269;1237.9071569632276;1297.3642914971667;1448.760954442517;1536.823265324488;5262.763175657095;7165.928340716598;7526.7741967740985;7564.092308922924;7570.772876813704;7611.733589324374;7625.885611715828;7658.9617051914665;7740.109887362941;7781.9221807781905;1938.6510428122967;1939.156466936887;1939.4472575316038;1941.0294852573713;1950.1906708715867;2031.9796802032013;2033.9593208135902;2060.814526692597;2063.370671945056;2067.865588736727;1869.3924474545759;1950.4148755373403;1992.4122383896786;1992.750906136733;2000.1999200319842;1988.602279544097;1991.2632326039386;2000.669889468236;2014.0332640332576;2037.8064083912004;1908.7327774111636;1916.463390250718;1939.8933058681766;1944.3583617406223;1946.9902650486656;6359.87280254397;6656;7450.739224127129;7519.624018799038;7526.322630963185;4093.89765255871;4192.413062171298;4195.265828480004;4207.91584168318;4244.151169766028;2570.126157106593;2626.7110617832022;2655.482180974711;2665.160474450561;2671.973280267192;774.8062984253936;790.9564973926432;797.7766225456882;799.580220384297;806.1938061938062;10568.52441640128;10656.254062215648;10663.573457061708;10676.733081672943;10882.564697412094;3995.5205375355144;4004.5594984551676;4016.457778199964;4027.5368332641533;4070.3283958147167;9004.279657627374;9054.185123338799;9098.226650734761;9102.772430689154;9130.726078217664;4257.40396344512;4259.829606815754;4261.232978063978;4271.8718438446895;4363.563643635611;3765.811709414518;3782.3002744492355;3800.3349413852466;3807.809609519513;3811.5616704079384;3197.2646291353117;3278.164068162651;3282.9330467597647;3286.2113092857544;3362.36115138123;988.4217732626494;1014.5079636376352;1015.8323876560285;1021.5862575656871;1021.9846702299471;1112.7496313329598;1116.3580940958982;1130.6325444230492;1131.1120770195357;1158.7624536969902;7144;7151.249118842551;7152.392046675986;7153.785386438415;7369.115706115301;406.1714103229379;409.23677341757406;419.66636523963524;421.3910898751275;429.29381167979125;5221.765020574083;5469.480399362054;5477.972610136924;5492.505674489315;5497.653074996571;8257;8796.8680469793;9309;9371.250299975985;9414.482203478738;9183;9233.353665243369;9247.352685312098;9261.814763704688;9270.582823772946;6569.605823650596;6739.696713647995;6811.284815094443;6829.112215411965;6852.383285504328;6422.582532135396;6782.626955517494;6784.185897692309;6853.554518956252;6922.826929326807;8007.639656215601;8057.435979481615;8078.434509584273;8101.230383113831;8340.04089529712;8128.715494957648;8246.216609422096;8381.161883811814;8417;8559.614817333111;6485.967570162119;6656.667166641551;6680.465562755066;6704.262531121671;6758.5606935550995;7011.754588589375;7381.04046473955;7442.553446793209;7501.137369202612;7757.301842834172;6887.586744795329;6909.689063992032;6931.757388491378;7004.264552222147;7138.964305178441;6574.112494813139;6588.242352129567;6669.4997875160525;6843.931560684329;7131.25121862197;6807.251202367636;6815.761448349284;6836.974453831965;6876.346747059021;6970.965145174242;6006.279246490449;6040.6979651018155;6048.848778780566;6051.969740151271;6052;6142.969285153725;6209.410106039919;6256.186695729526;6257.78097766576;6271.278802937538 02:52:30 INFO - 2015-10-25 02:52:30,615 DEBUG : BROWSER_OUTPUT: |4;prototype.html;280.11344095936363;280.4069393233311;285.17584181714835;285.8827880568967;288.76465680470415;4467.486239082498;4475.709078909873;4480.2607569751035;4502.4371953505815;4522.954770452295;4290.785460726966;4345.5219925808215;4369.8033588488515;4415.20526305265;4416.315471101984;3388.88138915138;3389.762716609838;3398.966010339896;3403.0641573567345;3417.914552136204;3293.5059741038917;3390.983045084771;3392.321535692859;3393.558837351141;3400.9797060881638;3313.5361049453095;3324.7173990210863;3351.2962277921665;3359.731221502286;3364.9831750841213;3354;3366.2425954160362;3380.6112297085656;3386.4412371958597;3409.1988382730037;3008.443437963984;3019.139545229599;3019.562163486304;3048.3446059097178;3060.2655362713012;4279.636230920375;4296.978515107436;4310.418093557361;4349.086691794727;4356.368326592658;3985.242803867256;4028.274910516106;4030.3954406838916;4036.455078564415;4036.9394459083137;4679.625629949596;4681.555252251065;4683.625309975194;4684.859454216379;4687.695299805502;3312.867485300609;3395.9830200848837;3407.8807241746667;3495.5106285120105;3512.4204506256438;4873.561379475828;4875.536824001679;4881.975590122062;4887.095887260833;4890.217565189588;3497.440409534462;3500.4049311616577;3503.141730276048;3503.421935380634;3509.4560343146854;3553.591336996227;3560.9821950890596;3571.589267234301;3576.302620988882;3632.2553876455263;3998.100427403869;4001.979495228756;4005.6394924456354;4033.5361433434946;4049.534303555129;4703.858884233478;4714.316424118552;4733.242681171065;4744.359511465908;4815.7351345676325;1253.1854294708512;1257.063487701668;1265.13970500059;1266.303533056815;1272.0459655258555;884.5311984648147;894.2577660541734;900.3292547052488;903.4353529044347;909.1226965977731;4411.801468933906;4415.735055896656;4418.961544037149;4425.048614547888;4430.844920427834;4746.786394612251;4751.4535828379485;4772.665913386029;4784.856454306377;4834.613230941516;4452.510223875371;4461.933071003938;4466.240739074366;4467.798949047301;4476.977615111904;2601.4666993266337;2610.6475625790276;2618.9655086240828;2624.4095078607543;2629.6975847777367;4823.95176048235;4898.779554920037;4926.162552366106;4961.751912404366;4964.280179373971;4941.407031156285;4959.08256972454;5030.949690503121;5040.470750571138;5077.035363280965;4925.679830810986;4936.777844996984;4940.580050695731;4943.5797957174045;5025.899482010339;4824.95175048245;4830.541098595628;4831.106245344482;4841.515848415131;4842.176829938849;4725.125851717443;4725.527447255247;4738.810447582058;4793.952060479351;4798.920242945309;4934.8766280843265;4938.827141050046;4948.010397920502;4956.677815941952;4980.576650984707;3344.197392625801;3369.3429781193413;3375.3080618473646;3376.5610470638658;3389.5424117743787;4789.161896668139;4792.7603619819565;4799;4800.855974320776;4804.95195048045;4631;4631.930521042254;4637.443506779208;4655.162070827284;4655.441347038377;4626.583607475343;4629.768511574475;4630.351750754964;4682.812687492598;4690.788914498788;4441.860578731606;4575.9771201143785;4587.2201725706045;4598.724076555417;4615.515370886009;4715.24556071027;4721.551452611996;4728.38530990969;4833.419989601271;4845.53967373099;4652.185867473111;4656.627469802475;4658.254679251304;4671.229247174178;4735.668503204743 02:52:30 INFO - 2015-10-25 02:52:30,617 DEBUG : BROWSER_OUTPUT: |5;yui.html;587.051911163471;599.2748773983478;642.6336987916886;647.5434818452991;654.8330175805175;11109.388983605899;11250.493727782248;11284;11329.603463878766;11408.885911140886;10049.547770350333;10136.23978201634;10156.33983791055;10166.186705063596;10186.98130186981;584.4710536964044;590.852286928268;599.9310079340888;600.396601415579;605.8061420345491;599.7630935780364;604.9818505444844;605.7970579855754;605.9575829691923;608.5344711295862;620.6369273974731;625.8935980883238;625.8967270400378;626.9028300613389;635.4439865118022;621.3817251834431;621.9471344935663;623.4108767214983;627.5262177056316;627.8901192291331;450.8827704796744;450.9481409637901;451.02128381412416;451.2621863253571;464.753680549306;10142.797144057151;10230.9488452558;10248.846267305997;10281.794364112677;10311.535980880879;8086.474379165335;8322.8335433291;8429.747107586782;8892.333075019335;9055;8243.175682431769;8468.280196183332;8503.81267426869;8514.659413623447;8700.78248043804;2720.931976700598;2739.7260273972643;2772.7365900239447;2789.1493094606135;2793.748562629353;33669.32661346784;34054.14864628379;34156.82921585401;34271.97184084456;34315.31369372624;5972.313183983897;6178.285086295159;6216.316205217422;6228.813135605939;6326.177596912372;9496.667616633385;9503.904960950302;9518.95240523793;9521.619135234652;9530.90469095314;5254.3169387979315;5256.500632439912;5286.81496147633;5386.649867758583;5389.245505629215;3237.1927687384214;3315.817630030323;3339.1985923378215;3356.14418323326;3475;2180.433087397288;2180.9236676716555;2181;2194.1113848891223;2226.4545186429427;349.6119307568597;352.3780527369195;354.7091385064223;354.7587640404498;356.3567760192877;499.8075740839803;541.9078756611385;544.5589072851003;557.3145031611141;585.853536615846;6423.807285781434;6642.966785166043;6717.227518835396;6825.590464572141;6850.6574671266235;423.68223832125904;485.4126506926658;498.461661405681;525.5191499777737;564.6132399306488;2976.791624586258;3083.020921212271;3106.4735606572817;3182.0135757915123;3204;44719;44879;45006.32490512645;45307.320390194174;45371.77314113474;32071;35482.112947176014;35799;37188.25623487515;37275;27473.587896181572;31284;31329.06012819619;31374.215644608612;32393.51409728856;6788.7284508619105;6989.8951515726285;7099.503034787515;7179.641017949186;7323.597202153933;21398.57202855903;21451.249206277706;22372.440688982904;22710.88644556833;22728;30727.539086913268;31251.218719532193;31656.841715791274;31667.20831979219;32417.35165296728;6579.671016449254;6642.634655094016;6734;6855.691493882688;6915.688794004182;9691.951540242253;9898.455584942898;10036.949815250877;10248.333858299187;10595.311304765166;8871.689490867788;9137.5888085035;9150.176484116462;9190.816183676156;9255;9339.392939458914;9359.766005849908;9368.156865882103;9487.193588545051;9567.61729530811;8687.478751274944;8767.649294028168;8884.689035883714;8897.822043559223;9051.502167380593;8421.752967871242;8607.139286071342;8738.825223495367;8848.734537963872;9031.774205644911;7705.42209334291;8157.428979971345;8221;8416.845591990825;8457.323414126731 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,981 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: __startTimestamp1445766750566__endTimestamp 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4065.04 Median:4240.66 stddev:504.43 (11.9%) stddev-sans-first:487.01 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: Values: 2318.1 2381.7 2384.9 2396.6 2401.9 4143.7 4150.0 4230.0 4233.4 4237.9 4102.4 4154.4 4220.6 4221.1 4238.2 3535.3 3604.6 3645.1 3664.7 3715.4 3600.9 3651.1 3664.1 3718.7 3761.2 3654.7 3657.3 3670.4 3702.2 3707.1 3557.4 3612.3 3707.4 3708.6 3715.5 3581.1 3620.4 3636.6 3651.1 3666.2 4063.7 4104.2 4140.4 4140.5 4167.8 4315.6 4356.5 4440.9 4441.6 4451.4 4383.8 4386.2 4399.1 4482.6 4533.1 4065.0 4068.7 4139.0 4151.4 4166.7 4398.5 4462.9 4540.0 4545.8 4560.4 4300.5 4395.8 4401.9 4411.3 4412.0 4336.7 4364.7 4394.3 4447.1 4450.0 4154.2 4350.4 4419.0 4430.0 4439.7 4274.6 4315.3 4323.7 4331.8 4332.7 3041.3 3093.6 3099.3 3105.7 3108.3 2810.2 2859.6 2869.3 2870.5 2870.9 4051.8 4072.4 4083.9 4108.0 4115.7 4416.2 4439.5 4443.6 4480.0 4503.9 4103.6 4126.9 4240.7 4244.8 4247.0 3596.5 3722.2 3793.2 3793.7 3831.8 4510.3 4537.2 4538.9 4606.4 4634.9 4311.5 4413.7 4440.8 4527.2 4530.6 4404.6 4454.0 4527.9 4529.9 4532.8 4318.4 4322.9 4395.2 4424.3 4442.9 4385.9 4416.3 4488.2 4505.5 4516.9 4177.7 4212.0 4265.8 4276.1 4341.0 4217.1 4220.2 4300.2 4317.7 4330.4 3975.9 4156.9 4242.0 4254.2 4258.6 4200.2 4229.9 4248.0 4298.2 4404.0 4206.2 4305.2 4313.1 4346.3 4358.5 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43028.34 Median:180192.84 stddev:74180.79 (41.2%) stddev-sans-first:74320.10 02:52:30 INFO - 2015-10-25 02:52:30,618 DEBUG : BROWSER_OUTPUT: Values: 481.3 483.9 503.0 506.4 523.6 24956.0 36505.3 37201.3 37561.8 37615.6 38897.0 39123.2 39477.0 39555.0 39614.8 6228.4 6450.1 7711.8 7730.8 7859.5 9772.3 10009.0 10188.5 10214.1 10216.7 10043.9 10176.1 10182.2 10184.6 10186.9 6293.9 6557.6 9872.6 9987.2 10208.0 9665.5 9738.8 9746.9 9830.8 9842.8 36298.3 36590.3 36739.3 36755.8 36782.6 2736.8 2739.8 2741.9 2745.0 2802.0 27606.0 27707.2 27929.4 27950.6 28425.1 2156.9 2175.9 2190.7 2225.1 2247.9 234380.0 236085.0 237025.0 237175.0 237711.0 43828.8 45389.3 50933.0 54335.7 55155.4 50710.7 52237.7 52505.2 52576.5 52601.5 10942.3 11091.9 11135.3 11179.4 11184.3 7938.1 8122.5 8207.1 8228.7 8229.5 16606.8 16644.8 16740.4 16756.3 16757.5 7280.3 7531.8 7566.5 7581.7 7632.0 775.8 855.5 862.3 888.8 992.9 25993.0 26099.5 29561.6 29636.9 30417.2 874.8 908.2 922.0 933.9 1004.4 13751.2 13923.4 14010.6 14014.3 14050.0 324365.0 327612.0 331261.0 333292.0 333545.0 46944.5 47532.5 47713.3 47740.0 47782.0 1021.7 1106.2 1109.7 1111.6 1121.5 25903.6 26532.9 26854.6 26958.3 27213.5 39112.6 39506.4 39519.8 39528.2 39558.6 252732.0 254585.0 256914.0 298350.0 306154.0 26292.3 26627.2 26670.6 26745.2 26842.0 35825.8 36059.0 36618.3 37092.4 37116.3 30636.8 31053.5 31372.4 31497.5 31915.8 32392.8 32773.7 33060.7 33083.7 33630.3 32295.7 33154.7 33178.3 33237.8 33263.7 28738.6 30447.2 30855.2 30905.4 31032.7 23017.3 23522.5 23676.3 23761.2 24091.6 02:52:30 INFO - 2015-10-25 02:52:30,619 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,619 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:19231.61 Median:45571.54 stddev:51332.25 (112.6%) stddev-sans-first:51456.57 02:52:30 INFO - 2015-10-25 02:52:30,619 DEBUG : BROWSER_OUTPUT: Values: 302.5 303.1 304.6 308.5 310.2 11241.7 12307.6 15246.8 15271.5 15296.7 11953.6 12906.3 12939.5 12955.7 13144.7 4924.6 5054.1 5065.5 5087.3 5089.7 3420.9 3449.6 5045.6 5539.6 5706.4 5582.8 5647.9 5659.7 5727.1 5795.1 4261.7 4280.9 5618.9 5623.8 5633.6 3610.4 3621.7 3625.1 3681.5 3690.3 11402.3 11415.4 11485.0 11502.5 11886.7 1471.0 1623.9 1644.7 1677.4 1684.1 8551.8 8556.3 8787.3 8842.8 8871.0 1429.3 1440.2 1455.6 1471.9 1501.7 55937.4 56148.7 56364.2 57322.4 57648.0 5658.4 5658.8 5703.6 5722.0 5761.3 6464.6 6472.2 6584.6 6601.4 6650.9 4294.6 4309.2 4399.2 4460.2 4493.2 4473.8 4487.0 4497.3 4502.4 4584.6 2532.2 2565.4 2580.9 2588.8 2589.0 913.1 937.1 947.7 966.4 1015.7 372.0 409.9 419.0 422.2 425.5 9842.3 9990.8 10017.9 10123.1 10417.5 350.8 381.7 389.4 399.8 410.8 5298.5 5308.2 5359.9 5469.9 5474.8 307897.0 308691.3 308706.0 309116.0 310493.0 59644.4 63197.0 66434.3 66977.7 67308.3 424.4 435.0 436.6 436.7 437.6 9458.7 9633.0 9678.5 9772.8 9827.7 34184.0 34678.3 34889.3 36040.5 36240.3 44095.0 44270.6 44502.8 45549.3 45593.8 5290.6 5424.1 5437.7 5473.5 5501.1 10446.9 10514.2 10591.2 10610.8 10672.4 7006.5 9037.3 9057.3 9063.0 9073.5 7873.8 7941.2 8271.8 8383.3 9047.0 9076.4 9136.3 9175.9 9187.6 9262.0 5372.1 5373.8 5374.1 5379.9 5397.6 8799.7 8903.3 9009.8 9016.4 9400.2 02:52:30 INFO - 2015-10-25 02:52:30,621 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,621 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5171.02 Median:6176.19 stddev:2865.64 (46.4%) stddev-sans-first:2857.24 02:52:30 INFO - 2015-10-25 02:52:30,621 DEBUG : BROWSER_OUTPUT: Values: 1086.7 1237.9 1297.4 1448.8 1536.8 5262.8 7165.9 7526.8 7564.1 7570.8 7611.7 7625.9 7659.0 7740.1 7781.9 1938.7 1939.2 1939.4 1941.0 1950.2 2032.0 2034.0 2060.8 2063.4 2067.9 1869.4 1950.4 1992.4 1992.8 2000.2 1988.6 1991.3 2000.7 2014.0 2037.8 1908.7 1916.5 1939.9 1944.4 1947.0 6359.9 6656.0 7450.7 7519.6 7526.3 4093.9 4192.4 4195.3 4207.9 4244.2 2570.1 2626.7 2655.5 2665.2 2672.0 774.8 791.0 797.8 799.6 806.2 10568.5 10656.3 10663.6 10676.7 10882.6 3995.5 4004.6 4016.5 4027.5 4070.3 9004.3 9054.2 9098.2 9102.8 9130.7 4257.4 4259.8 4261.2 4271.9 4363.6 3765.8 3782.3 3800.3 3807.8 3811.6 3197.3 3278.2 3282.9 3286.2 3362.4 988.4 1014.5 1015.8 1021.6 1022.0 1112.7 1116.4 1130.6 1131.1 1158.8 7144.0 7151.2 7152.4 7153.8 7369.1 406.2 409.2 419.7 421.4 429.3 5221.8 5469.5 5478.0 5492.5 5497.7 8257.0 8796.9 9309.0 9371.3 9414.5 9183.0 9233.4 9247.4 9261.8 9270.6 6569.6 6739.7 6811.3 6829.1 6852.4 6422.6 6782.6 6784.2 6853.6 6922.8 8007.6 8057.4 8078.4 8101.2 8340.0 8128.7 8246.2 8381.2 8417.0 8559.6 6486.0 6656.7 6680.5 6704.3 6758.6 7011.8 7381.0 7442.6 7501.1 7757.3 6887.6 6909.7 6931.8 7004.3 7139.0 6574.1 6588.2 6669.5 6843.9 7131.3 6807.3 6815.8 6837.0 6876.3 6971.0 6006.3 6040.7 6048.8 6052.0 6052.0 6143.0 6209.4 6256.2 6257.8 6271.3 02:52:30 INFO - 2015-10-25 02:52:30,622 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,622 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:3949.33 Median:4471.75 stddev:1151.68 (25.8%) stddev-sans-first:1121.50 02:52:30 INFO - 2015-10-25 02:52:30,622 DEBUG : BROWSER_OUTPUT: Values: 280.1 280.4 285.2 285.9 288.8 4467.5 4475.7 4480.3 4502.4 4523.0 4290.8 4345.5 4369.8 4415.2 4416.3 3388.9 3389.8 3399.0 3403.1 3417.9 3293.5 3391.0 3392.3 3393.6 3401.0 3313.5 3324.7 3351.3 3359.7 3365.0 3354.0 3366.2 3380.6 3386.4 3409.2 3008.4 3019.1 3019.6 3048.3 3060.3 4279.6 4297.0 4310.4 4349.1 4356.4 3985.2 4028.3 4030.4 4036.5 4036.9 4679.6 4681.6 4683.6 4684.9 4687.7 3312.9 3396.0 3407.9 3495.5 3512.4 4873.6 4875.5 4882.0 4887.1 4890.2 3497.4 3500.4 3503.1 3503.4 3509.5 3553.6 3561.0 3571.6 3576.3 3632.3 3998.1 4002.0 4005.6 4033.5 4049.5 4703.9 4714.3 4733.2 4744.4 4815.7 1253.2 1257.1 1265.1 1266.3 1272.0 884.5 894.3 900.3 903.4 909.1 4411.8 4415.7 4419.0 4425.0 4430.8 4746.8 4751.5 4772.7 4784.9 4834.6 4452.5 4461.9 4466.2 4467.8 4477.0 2601.5 2610.6 2619.0 2624.4 2629.7 4824.0 4898.8 4926.2 4961.8 4964.3 4941.4 4959.1 5030.9 5040.5 5077.0 4925.7 4936.8 4940.6 4943.6 5025.9 4825.0 4830.5 4831.1 4841.5 4842.2 4725.1 4725.5 4738.8 4794.0 4798.9 4934.9 4938.8 4948.0 4956.7 4980.6 3344.2 3369.3 3375.3 3376.6 3389.5 4789.2 4792.8 4799.0 4800.9 4805.0 4631.0 4631.9 4637.4 4655.2 4655.4 4626.6 4629.8 4630.4 4682.8 4690.8 4441.9 4576.0 4587.2 4598.7 4615.5 4715.2 4721.6 4728.4 4833.4 4845.5 4652.2 4656.6 4658.3 4671.2 4735.7 02:52:30 INFO - 2015-10-25 02:52:30,624 DEBUG : BROWSER_OUTPUT: 02:52:30 INFO - 2015-10-25 02:52:30,624 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10004.20 Median:560.96 stddev:11338.98 (2021.3%) stddev-sans-first:11348.74 02:52:30 INFO - 2015-10-25 02:52:30,624 DEBUG : BROWSER_OUTPUT: Values: 587.1 599.3 642.6 647.5 654.8 11109.4 11250.5 11284.0 11329.6 11408.9 10049.5 10136.2 10156.3 10166.2 10187.0 584.5 590.9 599.9 600.4 605.8 599.8 605.0 605.8 606.0 608.5 620.6 625.9 625.9 626.9 635.4 621.4 621.9 623.4 627.5 627.9 450.9 450.9 451.0 451.3 464.8 10142.8 10230.9 10248.8 10281.8 10311.5 8086.5 8322.8 8429.7 8892.3 9055.0 8243.2 8468.3 8503.8 8514.7 8700.8 2720.9 2739.7 2772.7 2789.1 2793.7 33669.3 34054.1 34156.8 34272.0 34315.3 5972.3 6178.3 6216.3 6228.8 6326.2 9496.7 9503.9 9519.0 9521.6 9530.9 5254.3 5256.5 5286.8 5386.6 5389.2 3237.2 3315.8 3339.2 3356.1 3475.0 2180.4 2180.9 2181.0 2194.1 2226.5 349.6 352.4 354.7 354.8 356.4 499.8 541.9 544.6 557.3 585.9 6423.8 6643.0 6717.2 6825.6 6850.7 423.7 485.4 498.5 525.5 564.6 2976.8 3083.0 3106.5 3182.0 3204.0 44719.0 44879.0 45006.3 45307.3 45371.8 32071.0 35482.1 35799.0 37188.3 37275.0 27473.6 31284.0 31329.1 31374.2 32393.5 6788.7 6989.9 7099.5 7179.6 7323.6 21398.6 21451.2 22372.4 22710.9 22728.0 30727.5 31251.2 31656.8 31667.2 32417.4 6579.7 6642.6 6734.0 6855.7 6915.7 9692.0 9898.5 10036.9 10248.3 10595.3 8871.7 9137.6 9150.2 9190.8 9255.0 9339.4 9359.8 9368.2 9487.2 9567.6 8687.5 8767.6 8884.7 8897.8 9051.5 8421.8 8607.1 8738.8 8848.7 9031.8 7705.4 8157.4 8221.0 8416.8 8457.3 02:52:30 INFO - 2015-10-25 02:52:30,625 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:52:30 INFO - 2015-10-25 02:52:30,625 DEBUG : BROWSER_OUTPUT: 02:52:31 INFO - 2015-10-25 02:52:31,089 INFO : Browser exited with error code: 0 02:52:31 INFO - 2015-10-25 02:52:31,901 INFO : Completed test dromaeo_css (00:18:44) 02:52:31 INFO - 2015-10-25 02:52:31,901 INFO : Starting test kraken 02:52:31 INFO - 2015-10-25 02:52:31,901 DEBUG : operating with platform_type : w7_ 02:52:31 INFO - 2015-10-25 02:52:31,901 INFO : Initialising browser for kraken test... 02:52:31 INFO - 2015-10-25 02:52:31,926 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp0wrip3\profile http://localhost:49716/getInfo.html 02:52:36 INFO - 2015-10-25 02:52:36,562 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:52:36 INFO - 2015-10-25 02:52:36,562 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:52:36 INFO - 2015-10-25 02:52:36,562 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:52:36 INFO - 2015-10-25 02:52:36,562 DEBUG : BROWSER_OUTPUT: __metrics 02:52:36 INFO - 2015-10-25 02:52:36,684 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: console.error: 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: Stack: 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:52:37 INFO - 2015-10-25 02:52:37,753 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:52:37 INFO - 2015-10-25 02:52:37,755 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:52:37 INFO - 2015-10-25 02:52:37,755 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 02:52:37 INFO - 2015-10-25 02:52:37,755 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5475.66 02:54:17 INFO - RETURN: kraken: 1629.60 02:54:17 INFO - RETURN: v8_7: 20189.69 02:54:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,25]]", "result": "20189.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,25]]", "result": "1629.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,25]]", "result": "5475.66"}}} 02:54:17 INFO - Return code: 0 02:54:17 INFO - # TBPL SUCCESS # 02:54:17 INFO - Running post-action listener: _resource_record_post_action 02:54:17 INFO - Running post-run listener: _resource_record_post_run 02:54:18 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 112806400; Write bytes: 434114048; Read time: 1296240; Write time: 4861940 02:54:18 INFO - install - Wall time: 2s; CPU: 9.0%; Read bytes: 0; Write bytes: 14403072; Read time: 0; Write time: 372100 02:54:18 INFO - run-tests - Wall time: 1232s; CPU: 13.0%; Read bytes: 112669696; Write bytes: 409847808; Read time: 1273430; Write time: 4483140 02:54:18 INFO - Running post-run listener: _upload_blobber_files 02:54:18 INFO - Blob upload gear active. 02:54:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:54:18 INFO - Copying logs to upload dir... 02:54:18 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1446.079000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 6 secs) (at 2015-10-25 02:54:18.519496) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:18.525677) ========= '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-075 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-075 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-075 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 build_url:https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/9p8-kyYZSKKddmB5_A51Iw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:18.649364) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:18.650135) ========= '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-075 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-075 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-075 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 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:18.769436) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:18.770107) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-25 02:54:19.469219) =========