builder: fx-team_win7-ix_test-dromaeojs slave: t-w732-ix-176 starttime: 1445591663.68 results: success (0) buildid: 20151023003428 builduid: 420731baeeb14d1b8de896b005b9dee2 revision: 0dae594de5aca88fd1fb2788c79a8e8f3755b88c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:23.683672) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:23.684749) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:23.685536) ========= '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-176 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-176 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-176 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.494000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:24.200178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:24.200562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:24.339306) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:24.340074) ========= '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-176 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-176 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-176 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.701000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:25.061076) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:25.061881) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:25.062792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:25.063521) ========= '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-176 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-176 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-176 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:14:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 02:14:26 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.735000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:25.817909) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-23 02:14:25.818713) ========= '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-176 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-176 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-176 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.201000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-23 02:14:40.048439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-23 02:14:40.048810) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 0dae594de5aca88fd1fb2788c79a8e8f3755b88c --destination scripts --debug' in dir C:\\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0dae594de5aca88fd1fb2788c79a8e8f3755b88c --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-176 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-176 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-176 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-23 02:14:41,055 truncating revision to first 12 chars 2015-10-23 02:14:41,055 Setting DEBUG logging. 2015-10-23 02:14:41,055 attempt 1/10 2015-10-23 02:14:41,055 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0dae594de5ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 02:14:42,388 unpacking tar archive at: fx-team-0dae594de5ac/testing/mozharness/ program finished with exit code 0 elapsedTime=2.393000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-23 02:14:42.764270) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:42.764612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:42.782850) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:42.783224) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 02:14:42.783681) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 34 secs) (at 2015-10-23 02:14:42.783976) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-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', 'Fx-Team-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-176 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-176 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-176 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:14:43 INFO - MultiFileLogger online at 20151023 02:14:43 in C:\slave\test 02:14:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 02:14:43 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 02:14:43 INFO - {'append_to_log': False, 02:14:43 INFO - 'base_work_dir': 'C:\\slave\\test', 02:14:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 02:14:43 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 02:14:43 INFO - 'branch': 'Fx-Team-Non-PGO', 02:14:43 INFO - 'buildbot_json_path': 'buildprops.json', 02:14:43 INFO - 'config_files': ('talos/windows_config.py',), 02:14:43 INFO - 'default_actions': ('clobber', 02:14:43 INFO - 'read-buildbot-config', 02:14:43 INFO - 'download-and-extract', 02:14:43 INFO - 'populate-webroot', 02:14:43 INFO - 'create-virtualenv', 02:14:43 INFO - 'install', 02:14:43 INFO - 'run-tests'), 02:14:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:14:43 INFO - 'download_minidump_stackwalk': True, 02:14:43 INFO - 'download_symbols': 'ondemand', 02:14:43 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 02:14:43 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 02:14:43 INFO - 'hg': 'c:/mozilla-build/hg/hg', 02:14:43 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 02:14:43 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 02:14:43 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 02:14:43 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 02:14:43 INFO - 'C:/mozilla-build/tooltool.py'), 02:14:43 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 02:14:43 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 02:14:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:14:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:14:43 INFO - 'installer_path': 'installer.exe', 02:14:43 INFO - 'log_level': 'info', 02:14:43 INFO - 'log_name': 'talos', 02:14:43 INFO - 'log_to_console': True, 02:14:43 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 02:14:43 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 02:14:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 02:14:43 INFO - 'opt_config_files': (), 02:14:43 INFO - 'pip_index': False, 02:14:43 INFO - 'sps_profile': False, 02:14:43 INFO - 'sps_profile_interval': 0, 02:14:43 INFO - 'suite': 'dromaeojs', 02:14:43 INFO - 'system_bits': '32', 02:14:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:14:43 INFO - 'title': 'T-W732-IX-176', 02:14:43 INFO - 'use_talos_json': True, 02:14:43 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 02:14:43 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 02:14:43 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 02:14:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:14:43 INFO - 'work_dir': 'build'} 02:14:43 INFO - ##### 02:14:43 INFO - ##### Running clobber step. 02:14:43 INFO - ##### 02:14:43 INFO - Running pre-action listener: _resource_record_pre_action 02:14:43 INFO - Running main action method: clobber 02:14:43 INFO - rmtree: C:\slave\test\build 02:14:43 INFO - Using _rmtree_windows ... 02:14:43 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 02:17:46 INFO - Running post-action listener: _resource_record_post_action 02:17:46 INFO - ##### 02:17:46 INFO - ##### Running read-buildbot-config step. 02:17:46 INFO - ##### 02:17:46 INFO - Running pre-action listener: _resource_record_pre_action 02:17:46 INFO - Running main action method: read_buildbot_config 02:17:46 INFO - Using buildbot properties: 02:17:46 INFO - { 02:17:46 INFO - "properties": { 02:17:46 INFO - "buildnumber": 741, 02:17:46 INFO - "product": "firefox", 02:17:46 INFO - "script_repo_revision": "production", 02:17:46 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 02:17:46 INFO - "repository": "", 02:17:46 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs", 02:17:46 INFO - "buildid": "20151023003428", 02:17:46 INFO - "slavename": "t-w732-ix-176", 02:17:46 INFO - "pgo_build": "False", 02:17:46 INFO - "basedir": "C:\\slave\\test", 02:17:46 INFO - "project": "", 02:17:46 INFO - "platform": "win7-ix", 02:17:46 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 02:17:46 INFO - "slavebuilddir": "test", 02:17:46 INFO - "scheduler": "tests-fx-team-win32-talos", 02:17:46 INFO - "branch": "fx-team", 02:17:46 INFO - "repo_path": "integration/fx-team", 02:17:46 INFO - "stage_platform": "win32", 02:17:46 INFO - "builduid": "420731baeeb14d1b8de896b005b9dee2", 02:17:46 INFO - "revision": "0dae594de5aca88fd1fb2788c79a8e8f3755b88c" 02:17:46 INFO - }, 02:17:46 INFO - "sourcestamp": { 02:17:46 INFO - "repository": "", 02:17:46 INFO - "hasPatch": false, 02:17:46 INFO - "project": "", 02:17:46 INFO - "branch": "fx-team-win32-talos", 02:17:46 INFO - "changes": [ 02:17:46 INFO - { 02:17:46 INFO - "category": null, 02:17:46 INFO - "files": [ 02:17:46 INFO - { 02:17:46 INFO - "url": null, 02:17:46 INFO - "name": "https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 02:17:46 INFO - } 02:17:46 INFO - ], 02:17:46 INFO - "repository": "", 02:17:46 INFO - "rev": "0dae594de5aca88fd1fb2788c79a8e8f3755b88c", 02:17:46 INFO - "who": "cbook@mozilla.com", 02:17:46 INFO - "when": 1445591580, 02:17:46 INFO - "number": 6565059, 02:17:46 INFO - "comments": "Bug 1201008 - only add a breakpoint in the debugger if a source is actually loaded. r=bgrins", 02:17:46 INFO - "project": "", 02:17:46 INFO - "at": "Fri 23 Oct 2015 02:13:00", 02:17:46 INFO - "branch": "fx-team-win32-talos", 02:17:46 INFO - "revlink": "", 02:17:46 INFO - "properties": [ 02:17:46 INFO - [ 02:17:46 INFO - "buildid", 02:17:46 INFO - "20151023003428", 02:17:46 INFO - "Change" 02:17:46 INFO - ], 02:17:46 INFO - [ 02:17:46 INFO - "builduid", 02:17:46 INFO - "420731baeeb14d1b8de896b005b9dee2", 02:17:46 INFO - "Change" 02:17:46 INFO - ], 02:17:46 INFO - [ 02:17:46 INFO - "pgo_build", 02:17:46 INFO - "False", 02:17:46 INFO - "Change" 02:17:46 INFO - ] 02:17:46 INFO - ], 02:17:46 INFO - "revision": "0dae594de5aca88fd1fb2788c79a8e8f3755b88c" 02:17:46 INFO - } 02:17:46 INFO - ], 02:17:46 INFO - "revision": "0dae594de5aca88fd1fb2788c79a8e8f3755b88c" 02:17:46 INFO - } 02:17:46 INFO - } 02:17:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 02:17:46 INFO - Running post-action listener: _resource_record_post_action 02:17:46 INFO - ##### 02:17:46 INFO - ##### Running download-and-extract step. 02:17:46 INFO - ##### 02:17:46 INFO - Running pre-action listener: _resource_record_pre_action 02:17:46 INFO - Running main action method: download_and_extract 02:17:46 INFO - mkdir: C:\slave\test\build\tests 02:17:46 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:17:46 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:17:46 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/test_packages.json 02:17:46 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 02:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 02:17:47 INFO - Downloaded 1221 bytes. 02:17:47 INFO - Reading from file C:\slave\test\build\test_packages.json 02:17:47 INFO - Using the following test package requirements: 02:17:47 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:17:47 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 02:17:47 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'jsshell-win32.zip'], 02:17:47 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 02:17:47 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:17:47 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 02:17:47 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 02:17:47 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 02:17:47 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:17:47 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:17:47 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 02:17:47 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:17: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:17:47 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:17:48 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:17:48 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:17:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:17:49 INFO - Downloaded 18004427 bytes. 02:17:49 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:17:49 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:17:49 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:18:15 INFO - Return code: 0 02:18:15 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:18:15 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:18:15 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:18:15 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:16 INFO - Downloaded 18004427 bytes. 02:18:16 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:18:16 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:18:16 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:18:37 INFO - Return code: 0 02:18: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:18:37 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 02:18:37 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 02:18:37 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:38 INFO - Downloaded 11131307 bytes. 02:18:38 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:18:38 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:18:38 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 02:18:40 INFO - Return code: 0 02:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:18:40 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 02:18:40 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:18:40 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 02:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:43 INFO - Downloaded 51461923 bytes. 02:18:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:18:43 INFO - mkdir: C:\slave\test\properties 02:18:43 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 02:18:43 INFO - Writing to file C:\slave\test\properties\build_url 02:18:43 INFO - Contents: 02:18:43 INFO - build_url:https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:18:43 INFO - Running post-action listener: _resource_record_post_action 02:18:43 INFO - Running post-action listener: set_extra_try_arguments 02:18:43 INFO - ##### 02:18:43 INFO - ##### Running populate-webroot step. 02:18:43 INFO - ##### 02:18:43 INFO - Running pre-action listener: _resource_record_pre_action 02:18:43 INFO - Running main action method: populate_webroot 02:18:43 INFO - Guessing talos json url... 02:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:18:43 INFO - https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 02:18:43 INFO - trying https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 02:18:43 INFO - Downloading https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:18:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/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:18:43 INFO - Downloaded 103 bytes. 02:18:43 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:18:43 INFO - Contents: 02:18:43 INFO - 20151023003428 02:18:43 INFO - https://hg.mozilla.org/integration/fx-team/rev/0dae594de5aca88fd1fb2788c79a8e8f3755b88c 02:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:18:43 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/0dae594de5/testing/talos/talos.json 02:18:43 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/0dae594de5/testing/talos/talos.json to C:\slave\test\build\talos.json 02:18:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/0dae594de5/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 02:18:44 INFO - Downloaded 4264 bytes. 02:18:44 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:18:44 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:18:44 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 02:18:44 INFO - u'../fennec_ids.txt'], 02:18:44 INFO - u'tests': [u'tcheck2']}, 02:18:44 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:18:44 INFO - u'--tppagecycles', 02:18:44 INFO - u'7'], 02:18:44 INFO - u'tests': [u'tsvgm']}}, 02:18:44 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 02:18:44 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:18:44 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 02:18:44 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'dromaeo_css', 02:18:44 INFO - u'kraken', 02:18:44 INFO - u'v8_7']}, 02:18:44 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:18:44 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:18:44 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'tests': [u'damp', u'tps']}, 02:18:44 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'damp', u'tps']}, 02:18:44 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 02:18:44 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:18:44 INFO - u'other': {u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'other_l64': {u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'other_nol64': {u'tests': [u'a11yr', 02:18:44 INFO - u'ts_paint', 02:18:44 INFO - u'tpaint', 02:18:44 INFO - u'sessionrestore', 02:18:44 INFO - u'sessionrestore_no_auto_restore']}, 02:18:44 INFO - u'svgr': {u'tests': [u'tsvgx', 02:18:44 INFO - u'tsvgr_opacity', 02:18:44 INFO - u'tart', 02:18:44 INFO - u'tscrollx', 02:18:44 INFO - u'cart']}, 02:18:44 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'tsvgx', 02:18:44 INFO - u'tsvgr_opacity', 02:18:44 INFO - u'tart', 02:18:44 INFO - u'tscrollx', 02:18:44 INFO - u'cart']}, 02:18:44 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'tests': [u'tp5o']}, 02:18:44 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'talos_options': [u'--e10s'], 02:18:44 INFO - u'tests': [u'tp5o']}, 02:18:44 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'talos_options': [u'--xperf_path', 02:18:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:44 INFO - u'tests': [u'tp5n']}, 02:18:44 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:44 INFO - u'talos_options': [u'--e10s', 02:18:44 INFO - u'--xperf_path', 02:18:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 02:18:44 INFO - u'C:/slave/talos-data/talos/xperf.config'], 02:18:44 INFO - u'tests': [u'tp5n']}}, 02:18:44 INFO - u'talos.zip': {u'path': u'', 02:18:44 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:44 INFO - Running post-action listener: _resource_record_post_action 02:18:44 INFO - ##### 02:18:44 INFO - ##### Running create-virtualenv step. 02:18:44 INFO - ##### 02:18:44 INFO - Running pre-action listener: _resource_record_pre_action 02:18:44 INFO - Running main action method: create_virtualenv 02:18:44 INFO - Creating virtualenv C:\slave\test\build/venv 02:18:44 INFO - mkdir: C:\slave\test\build\venv\Scripts 02:18:44 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 02:18:44 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:18:44 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:18:44 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:18:50 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 02:18:54 INFO - Installing distribute......................................................................................................................................................................................done. 02:18:55 INFO - Return code: 0 02:18:55 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 02:18:55 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:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:55 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:18:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:55 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:18:55 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:18:55 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:18:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:18:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:18:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:18:55 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:18:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:18:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:18:55 INFO - 'DCLOCATION': 'SCL3', 02:18:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:18:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:18:55 INFO - 'HOMEDRIVE': 'C:', 02:18:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:18:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:18:55 INFO - 'KTS_VERSION': '1.19c', 02:18:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:18:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:18:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:18:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:18:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:55 INFO - 'MOZ_NO_REMOTE': '1', 02:18:55 INFO - 'NO_EM_RESTART': '1', 02:18:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:18:55 INFO - 'OS': 'Windows_NT', 02:18:55 INFO - 'OURDRIVE': 'C:', 02:18:55 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:18:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:18:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:18:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:18:55 INFO - 'PROCESSOR_LEVEL': '6', 02:18:55 INFO - 'PROCESSOR_REVISION': '1e05', 02:18:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:18:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:18:55 INFO - 'PROMPT': '$P$G', 02:18:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:18:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:18:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:18:55 INFO - 'PWD': 'C:\\slave\\test', 02:18:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:18:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:18:55 INFO - 'SYSTEMDRIVE': 'C:', 02:18:55 INFO - 'SYSTEMROOT': 'C:\\windows', 02:18:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:18:55 INFO - 'TEST1': 'testie', 02:18:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:18:55 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:18:55 INFO - 'USERNAME': 'cltbld', 02:18:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:18:55 INFO - 'WINDIR': 'C:\\windows', 02:18:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:18:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:18:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:18:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:18:56 INFO - Downloading/unpacking pip>=1.5 02:18:56 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 02:19:02 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:19:02 INFO - Running setup.py egg_info for package pip 02:19:02 INFO - warning: no files found matching 'pip\cacert.pem' 02:19:02 INFO - warning: no files found matching '*.html' under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:19:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:19:02 INFO - Installing collected packages: pip 02:19:02 INFO - Found existing installation: pip 0.8.2 02:19:02 INFO - Uninstalling pip: 02:19:02 INFO - Successfully uninstalled pip 02:19:02 INFO - Running setup.py install for pip 02:19:02 INFO - warning: no files found matching 'pip\cacert.pem' 02:19:02 INFO - warning: no files found matching '*.html' under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:19:02 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:19:02 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 02:19:02 INFO - Successfully installed pip 02:19:02 INFO - Cleaning up... 02:19:02 INFO - Return code: 0 02:19:02 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 02:19:02 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:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:02 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:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:02 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:02 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:19:02 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:19:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:02 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:02 INFO - 'DCLOCATION': 'SCL3', 02:19:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:02 INFO - 'HOMEDRIVE': 'C:', 02:19:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:02 INFO - 'KTS_VERSION': '1.19c', 02:19:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:02 INFO - 'MOZ_NO_REMOTE': '1', 02:19:02 INFO - 'NO_EM_RESTART': '1', 02:19:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:02 INFO - 'OS': 'Windows_NT', 02:19:02 INFO - 'OURDRIVE': 'C:', 02:19:02 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:19:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:02 INFO - 'PROCESSOR_LEVEL': '6', 02:19:02 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:02 INFO - 'PROMPT': '$P$G', 02:19:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:02 INFO - 'PWD': 'C:\\slave\\test', 02:19:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:02 INFO - 'SYSTEMDRIVE': 'C:', 02:19:02 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:02 INFO - 'TEST1': 'testie', 02:19:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:02 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:02 INFO - 'USERNAME': 'cltbld', 02:19:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:02 INFO - 'WINDIR': 'C:\\windows', 02:19:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:04 INFO - Downloading/unpacking psutil>=0.7.1 02:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:05 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:19:05 INFO - Installing collected packages: psutil 02:19:05 INFO - Successfully installed psutil 02:19:05 INFO - Cleaning up... 02:19:05 INFO - Return code: 0 02:19:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 02:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:05 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:05 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:19:05 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:19:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:05 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:05 INFO - 'DCLOCATION': 'SCL3', 02:19:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:05 INFO - 'HOMEDRIVE': 'C:', 02:19:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:05 INFO - 'KTS_VERSION': '1.19c', 02:19:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:05 INFO - 'MOZ_NO_REMOTE': '1', 02:19:05 INFO - 'NO_EM_RESTART': '1', 02:19:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:05 INFO - 'OS': 'Windows_NT', 02:19:05 INFO - 'OURDRIVE': 'C:', 02:19:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:19:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:05 INFO - 'PROCESSOR_LEVEL': '6', 02:19:05 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:05 INFO - 'PROMPT': '$P$G', 02:19:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:05 INFO - 'PWD': 'C:\\slave\\test', 02:19:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:05 INFO - 'SYSTEMDRIVE': 'C:', 02:19:05 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:05 INFO - 'TEST1': 'testie', 02:19:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:05 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:05 INFO - 'USERNAME': 'cltbld', 02:19:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:05 INFO - 'WINDIR': 'C:\\windows', 02:19:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:19:06 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:19:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:19:06 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:19:06 INFO - Installing collected packages: mozsystemmonitor 02:19:06 INFO - Running setup.py install for mozsystemmonitor 02:19:07 INFO - Successfully installed mozsystemmonitor 02:19:07 INFO - Cleaning up... 02:19:07 INFO - Return code: 0 02:19:07 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 02:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:07 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:07 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:19:07 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:19:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:07 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:07 INFO - 'DCLOCATION': 'SCL3', 02:19:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:07 INFO - 'HOMEDRIVE': 'C:', 02:19:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:07 INFO - 'KTS_VERSION': '1.19c', 02:19:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:07 INFO - 'MOZ_NO_REMOTE': '1', 02:19:07 INFO - 'NO_EM_RESTART': '1', 02:19:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:07 INFO - 'OS': 'Windows_NT', 02:19:07 INFO - 'OURDRIVE': 'C:', 02:19:07 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:19:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:07 INFO - 'PROCESSOR_LEVEL': '6', 02:19:07 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:07 INFO - 'PROMPT': '$P$G', 02:19:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:07 INFO - 'PWD': 'C:\\slave\\test', 02:19:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:07 INFO - 'SYSTEMDRIVE': 'C:', 02:19:07 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:07 INFO - 'TEST1': 'testie', 02:19:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:07 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:07 INFO - 'USERNAME': 'cltbld', 02:19:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:07 INFO - 'WINDIR': 'C:\\windows', 02:19:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:08 INFO - Downloading blobuploader-1.2.4.tar.gz 02:19:08 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:19:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:19:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:08 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:19:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 02:19:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:19:09 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:19:09 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:19:09 INFO - Downloading docopt-0.6.1.tar.gz 02:19:09 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:19:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 02:19:09 INFO - Installing collected packages: blobuploader, requests, docopt 02:19:09 INFO - Running setup.py install for blobuploader 02:19:09 INFO - Running setup.py install for requests 02:19:10 INFO - Running setup.py install for docopt 02:19:10 INFO - Successfully installed blobuploader requests docopt 02:19:10 INFO - Cleaning up... 02:19:12 INFO - Return code: 0 02:19:12 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:19:12 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:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:12 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:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:12 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:12 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:19:12 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:19:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:12 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:12 INFO - 'DCLOCATION': 'SCL3', 02:19:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:12 INFO - 'HOMEDRIVE': 'C:', 02:19:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:12 INFO - 'KTS_VERSION': '1.19c', 02:19:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:12 INFO - 'MOZ_NO_REMOTE': '1', 02:19:12 INFO - 'NO_EM_RESTART': '1', 02:19:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:12 INFO - 'OS': 'Windows_NT', 02:19:12 INFO - 'OURDRIVE': 'C:', 02:19:12 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:19:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:12 INFO - 'PROCESSOR_LEVEL': '6', 02:19:12 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:12 INFO - 'PROMPT': '$P$G', 02:19:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:12 INFO - 'PWD': 'C:\\slave\\test', 02:19:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:12 INFO - 'SYSTEMDRIVE': 'C:', 02:19:12 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:12 INFO - 'TEST1': 'testie', 02:19:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:12 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:12 INFO - 'USERNAME': 'cltbld', 02:19:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:12 INFO - 'WINDIR': 'C:\\windows', 02:19:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2y7dh8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:19:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-syztxv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:19:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zfj1hv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:19:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:19:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oq_rog-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-csowvg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vw0uxw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hle7al-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4or3vc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crbcjb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5_dxqn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-473ehc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:19:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:19:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nqnfzi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:19:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:19:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rpuxje-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:19:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:19:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lw4cm6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:19:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:19:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-btmcps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:19:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:19:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ht3fpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:19:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:19:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sf6did-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:19:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:19:15 INFO - Running setup.py install for manifestparser 02:19:15 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 02:19:15 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 02:19:15 INFO - Running setup.py install for mozcrash 02:19:16 INFO - Running setup.py install for mozdebug 02:19:16 INFO - Running setup.py install for mozdevice 02:19:16 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 02:19:16 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 02:19:16 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 02:19:16 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 02:19:17 INFO - Running setup.py install for mozfile 02:19:17 INFO - Running setup.py install for mozhttpd 02:19:18 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Running setup.py install for mozinfo 02:19:18 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Running setup.py install for mozInstall 02:19:18 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 02:19:18 INFO - Running setup.py install for mozleak 02:19:19 INFO - Running setup.py install for mozlog 02:19:19 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 02:19:19 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 02:19:20 INFO - Running setup.py install for moznetwork 02:19:20 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 02:19:20 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 02:19:20 INFO - Running setup.py install for mozprocess 02:19:21 INFO - Running setup.py install for mozprofile 02:19:21 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 02:19:21 INFO - Running setup.py install for mozrunner 02:19:22 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 02:19:22 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 02:19:22 INFO - Running setup.py install for mozscreenshot 02:19:22 INFO - Running setup.py install for moztest 02:19:23 INFO - Running setup.py install for mozversion 02:19:23 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 02:19:23 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 02:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:19:23 INFO - Cleaning up... 02:19:23 INFO - Return code: 0 02:19:23 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:19:23 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:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:23 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:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:23 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:23 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:19:23 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:19:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:23 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:23 INFO - 'DCLOCATION': 'SCL3', 02:19:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:23 INFO - 'HOMEDRIVE': 'C:', 02:19:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:23 INFO - 'KTS_VERSION': '1.19c', 02:19:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:23 INFO - 'MOZ_NO_REMOTE': '1', 02:19:23 INFO - 'NO_EM_RESTART': '1', 02:19:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:23 INFO - 'OS': 'Windows_NT', 02:19:23 INFO - 'OURDRIVE': 'C:', 02:19:23 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:19:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:23 INFO - 'PROCESSOR_LEVEL': '6', 02:19:23 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:23 INFO - 'PROMPT': '$P$G', 02:19:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:23 INFO - 'PWD': 'C:\\slave\\test', 02:19:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:23 INFO - 'SYSTEMDRIVE': 'C:', 02:19:23 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:23 INFO - 'TEST1': 'testie', 02:19:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:23 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:23 INFO - 'USERNAME': 'cltbld', 02:19:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:23 INFO - 'WINDIR': 'C:\\windows', 02:19:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:19:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4xvtyz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:19:24 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:19:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:19:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sb7nsk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:19:24 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:19:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:19:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wnbk1f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:19:24 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:19:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:19:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wb9ymf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-igvj5a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nzjboc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yygrc3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kb9cmy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lb4izj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9_zvgd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:19:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-51hyjh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:19:25 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:19:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jcd0hp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:19:26 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:19:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tca7ex-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:19:26 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:19:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5qodvq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 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:19:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-huzzgw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:19:26 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:19:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gh2eug-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:19:26 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:19:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:19:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fd7vby-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:19:26 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:19:26 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:19:26 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:19:26 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:19:26 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:19:26 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:19:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 02:19:26 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:19:26 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:19:27 INFO - Downloading blessings-1.5.1.tar.gz 02:19:27 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:19:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 02:19:28 INFO - Installing collected packages: blessings 02:19:28 INFO - Running setup.py install for blessings 02:19:28 INFO - Successfully installed blessings 02:19:28 INFO - Cleaning up... 02:19:31 INFO - Return code: 0 02:19:31 INFO - Done creating virtualenv C:\slave\test\build/venv. 02:19:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:19:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:19:31 INFO - Reading from file tmpfile_stdout 02:19:31 INFO - Using _rmtree_windows ... 02:19:31 INFO - Using _rmtree_windows ... 02:19:31 INFO - Current package versions: 02:19:31 INFO - blessings == 1.5.1 02:19:31 INFO - blobuploader == 1.2.4 02:19:31 INFO - docopt == 0.6.1 02:19:31 INFO - manifestparser == 1.1 02:19:31 INFO - mozInstall == 1.12 02:19:31 INFO - mozcrash == 0.16 02:19:31 INFO - mozdebug == 0.1 02:19:31 INFO - mozdevice == 0.46 02:19:31 INFO - mozfile == 1.2 02:19:31 INFO - mozhttpd == 0.7 02:19:31 INFO - mozinfo == 0.8 02:19:31 INFO - mozleak == 0.1 02:19:31 INFO - mozlog == 3.0 02:19:31 INFO - moznetwork == 0.27 02:19:31 INFO - mozprocess == 0.22 02:19:31 INFO - mozprofile == 0.27 02:19:31 INFO - mozrunner == 6.10 02:19:31 INFO - mozscreenshot == 0.1 02:19:31 INFO - mozsystemmonitor == 0.0 02:19:31 INFO - moztest == 0.7 02:19:31 INFO - mozversion == 1.4 02:19:31 INFO - psutil == 3.1.1 02:19:31 INFO - requests == 1.2.3 02:19:31 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:31 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 0x01923B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01979EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01840680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-176', 'USERDOMAIN': 'T-W732-IX-176', '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-176', '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:19:31 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:19:31 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:19:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:31 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:31 INFO - 'DCLOCATION': 'SCL3', 02:19:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:31 INFO - 'HOMEDRIVE': 'C:', 02:19:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:31 INFO - 'KTS_VERSION': '1.19c', 02:19:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:31 INFO - 'MOZ_NO_REMOTE': '1', 02:19:31 INFO - 'NO_EM_RESTART': '1', 02:19:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:31 INFO - 'OS': 'Windows_NT', 02:19:31 INFO - 'OURDRIVE': 'C:', 02:19:31 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:19:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:31 INFO - 'PROCESSOR_LEVEL': '6', 02:19:31 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:31 INFO - 'PROMPT': '$P$G', 02:19:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:31 INFO - 'PWD': 'C:\\slave\\test', 02:19:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:31 INFO - 'SYSTEMDRIVE': 'C:', 02:19:31 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:31 INFO - 'TEST1': 'testie', 02:19:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:31 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:31 INFO - 'USERNAME': 'cltbld', 02:19:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:31 INFO - 'WINDIR': 'C:\\windows', 02:19:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 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:19:31 INFO - Cleaning up... 02:19:31 INFO - Return code: 0 02:19:31 INFO - Running post-action listener: _resource_record_post_action 02:19:31 INFO - Running post-action listener: _start_resource_monitoring 02:19:31 INFO - Starting resource monitoring. 02:19:32 INFO - ##### 02:19:32 INFO - ##### Running install step. 02:19:32 INFO - ##### 02:19:32 INFO - Running pre-action listener: _resource_record_pre_action 02:19:32 INFO - Running main action method: install 02:19:32 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:19:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:19:32 INFO - Reading from file tmpfile_stdout 02:19:32 INFO - Using _rmtree_windows ... 02:19:32 INFO - Using _rmtree_windows ... 02:19:32 INFO - Detecting whether we're running mozinstall >=1.0... 02:19:32 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 02:19:32 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 02:19:32 INFO - Reading from file tmpfile_stdout 02:19:32 INFO - Output received: 02:19:32 INFO - Usage: mozinstall-script.py [options] installer 02:19:32 INFO - Options: 02:19:32 INFO - -h, --help show this help message and exit 02:19:32 INFO - -d DEST, --destination=DEST 02:19:32 INFO - Directory to install application into. [default: 02:19:32 INFO - "C:\slave\test"] 02:19:32 INFO - --app=APP Application being installed. [default: firefox] 02:19:32 INFO - Using _rmtree_windows ... 02:19:32 INFO - Using _rmtree_windows ... 02:19:32 INFO - mkdir: C:\slave\test\build\application 02:19:32 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:19:32 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:19:33 INFO - Reading from file tmpfile_stdout 02:19:33 INFO - Output received: 02:19:33 INFO - C:\slave\test\build\application\firefox\firefox.exe 02:19:33 INFO - Using _rmtree_windows ... 02:19:33 INFO - Using _rmtree_windows ... 02:19:33 INFO - Running post-action listener: _resource_record_post_action 02:19:33 INFO - ##### 02:19:33 INFO - ##### Running run-tests step. 02:19:33 INFO - ##### 02:19:33 INFO - Running pre-action listener: _resource_record_pre_action 02:19:33 INFO - Running main action method: run_tests 02:19:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 02:19:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 02:19:33 INFO - Python 2.7.3 02:19:33 INFO - Return code: 0 02:19:33 INFO - grabbing minidump binary from tooltool 02:19:33 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:19:33 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 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01E9F660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 02:19:33 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:19:33 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:19:33 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:19:34 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpfd_1zx 02:19:34 INFO - INFO - File integrity verified, renaming tmpfd_1zx to win32-minidump_stackwalk.exe 02:19:34 INFO - Return code: 0 02:19:34 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 02:19:34 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 02:19:34 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 02:19:34 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 02:19:34 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 02:19:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-176', '--webServer', 'localhost'] in C:\slave\test\build 02:19:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-176 --webServer localhost 02:19:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:19:34 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:19:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:19:34 INFO - 'COMPUTERNAME': 'T-W732-IX-176', 02:19:34 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:19:34 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:19:34 INFO - 'DCLOCATION': 'SCL3', 02:19:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:19:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:19:34 INFO - 'HOMEDRIVE': 'C:', 02:19:34 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:19:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:19:34 INFO - 'KTS_VERSION': '1.19c', 02:19:34 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:19:34 INFO - 'LOGONSERVER': '\\\\T-W732-IX-176', 02:19:34 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:19:34 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 02:19:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:19:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:34 INFO - 'MOZ_NO_REMOTE': '1', 02:19:34 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:19:34 INFO - 'NO_EM_RESTART': '1', 02:19:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:19:34 INFO - 'OS': 'Windows_NT', 02:19:34 INFO - 'OURDRIVE': 'C:', 02:19:34 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:19:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:19:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:19:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:19:34 INFO - 'PROCESSOR_LEVEL': '6', 02:19:34 INFO - 'PROCESSOR_REVISION': '1e05', 02:19:34 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:19:34 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:19:34 INFO - 'PROMPT': '$P$G', 02:19:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:19:34 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:19:34 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:19:34 INFO - 'PWD': 'C:\\slave\\test', 02:19:34 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 02:19:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:19:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:19:34 INFO - 'SYSTEMDRIVE': 'C:', 02:19:34 INFO - 'SYSTEMROOT': 'C:\\windows', 02:19:34 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:34 INFO - 'TEST1': 'testie', 02:19:34 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:19:34 INFO - 'USERDOMAIN': 'T-W732-IX-176', 02:19:34 INFO - 'USERNAME': 'cltbld', 02:19:34 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:19:34 INFO - 'WINDIR': 'C:\\windows', 02:19:34 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:19:34 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:19:34 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-176', '--webServer', 'localhost'] with output_timeout 3600 02:19:36 INFO - mozversion INFO | application_buildid: 20151023003428 02:19:36 INFO - mozversion INFO | application_changeset: 0dae594de5aca88fd1fb2788c79a8e8f3755b88c 02:19:36 INFO - mozversion INFO | application_display_name: Nightly 02:19:36 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:36 INFO - mozversion INFO | application_name: Firefox 02:19:36 INFO - mozversion INFO | application_remotingname: firefox 02:19:36 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 02:19:36 INFO - mozversion INFO | application_vendor: Mozilla 02:19:36 INFO - mozversion INFO | application_version: 44.0a1 02:19:36 INFO - mozversion INFO | platform_buildid: 20151023003428 02:19:36 INFO - mozversion INFO | platform_changeset: 0dae594de5aca88fd1fb2788c79a8e8f3755b88c 02:19:36 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 02:19:36 INFO - mozversion INFO | platform_version: 44.0a1 02:19:36 INFO - 2015-10-23 02:19:36,497 DEBUG : using testdate: 1445591976 02:19:36 INFO - 2015-10-23 02:19:36,497 DEBUG : actual date: 1445591976 02:19:36 INFO - 2015-10-23 02:19:36,510 INFO : starting webserver on 'localhost:49672' 02:19:36 INFO - 2015-10-23 02:19:36,513 INFO : Starting test suite T-W732-IX-176 02:19:36 INFO - 2015-10-23 02:19:36,513 INFO : Starting test dromaeo_css 02:19:36 INFO - 2015-10-23 02:19:36,513 DEBUG : operating with platform_type : w7_ 02:19:36 INFO - 2015-10-23 02:19:36,513 INFO : Initialising browser for dromaeo_css test... 02:19:36 INFO - 2015-10-23 02:19:36,595 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqjam38\profile http://localhost:49672/getInfo.html 02:19:48 INFO - 2015-10-23 02:19:48,095 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:19:48 INFO - 2015-10-23 02:19:48,095 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:19:48 INFO - 2015-10-23 02:19:48,095 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1008/673 02:19:48 INFO - 2015-10-23 02:19:48,095 DEBUG : BROWSER_OUTPUT: __metrics 02:19:48 INFO - 2015-10-23 02:19:48,170 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:19:48 INFO - 2015-10-23 02:19:48,704 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 02:19:49 INFO - 2015-10-23 02:19:49,799 INFO : Browser initialized. 02:19:49 INFO - 2015-10-23 02:19:49,799 INFO : Running cycle 1/1 for dromaeo_css test... 02:19:49 INFO - 2015-10-23 02:19:49,799 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqjam38\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:20:01 INFO - 2015-10-23 02:20:01,441 DEBUG : BROWSER_OUTPUT: RSS: Main: 138944512 02:20:01 INFO - 2015-10-23 02:20:01,441 DEBUG : BROWSER_OUTPUT: 02:20:54 INFO - 2015-10-23 02:20:54,470 DEBUG : BROWSER_OUTPUT: 1445592054493 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:23:06 INFO - 2015-10-23 02:23:06,706 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49672/tests/dromaeo/cssquery-ext.html) 02:23:07 INFO - 2015-10-23 02:23:07,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 179945472 02:23:07 INFO - 2015-10-23 02:23:07,720 DEBUG : BROWSER_OUTPUT: 02:26:10 INFO - 2015-10-23 02:26:10,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-ext.html (next: http://localhost:49672/tests/dromaeo/cssquery-jquery.html) 02:26:11 INFO - 2015-10-23 02:26:11,163 DEBUG : BROWSER_OUTPUT: RSS: Main: 198250496 02:26:11 INFO - 2015-10-23 02:26:11,163 DEBUG : BROWSER_OUTPUT: 02:29:13 INFO - 2015-10-23 02:29:13,463 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49672/tests/dromaeo/cssquery-mootools.html) 02:29:14 INFO - 2015-10-23 02:29:14,038 DEBUG : BROWSER_OUTPUT: RSS: Main: 148799488 02:29:14 INFO - 2015-10-23 02:29:14,038 DEBUG : BROWSER_OUTPUT: 02:32:16 INFO - 2015-10-23 02:32:16,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49672/tests/dromaeo/cssquery-prototype.html) 02:32:16 INFO - 2015-10-23 02:32:16,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 145612800 02:32:16 INFO - 2015-10-23 02:32:16,759 DEBUG : BROWSER_OUTPUT: 02:35:19 INFO - 2015-10-23 02:35:19,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49672/tests/dromaeo/cssquery-yui.html) 02:35:20 INFO - 2015-10-23 02:35:20,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 136691712 02:35:20 INFO - 2015-10-23 02:35:20,319 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49672/tests/dromaeo/cssquery-yui.html (next: http://localhost:49672/tests/dromaeo/cssquery-dojo.html) 02:38:23 INFO - 2015-10-23 02:38:23,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 189345792 02:38:23 INFO - 2015-10-23 02:38:23,601 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,602 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:38:23 INFO - 2015-10-23 02:38:23,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:38:23 INFO - 2015-10-23 02:38:23,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:38:23 INFO - 2015-10-23 02:38:23,602 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:38:23 INFO - 2015-10-23 02:38:23,602 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2321.558903808276;2431.161249368967;2436.2204094689705;2437.8293519453646;2450.546648869959;4272.636825869801;4278.037441575651;4339.544347843478;4353.346997950309;4362.43288372512;4261.87214383569;4272;4348.630366418858;4350.738955662655;4352.064306174172;3559.875404360848;3660.6705396514308;3734.972882457319;3742.981285093571;3793.355129627963;3631.927361452769;3647;3695.408734602464;3760.4923335349786;3767.9811600942094;3663.9084022899374;3731.533558305212;3732.682721968636;3739.6073412291726;3812.218495208476;3605.1708107135505;3705.925881482355;3718.851245950159;3729.4778730977687;3764.1154328732728;3595.694365978895;3601.3157500074904;3777.112378591029;3787.223619158066;3795.905102372435;4002.139539998914;4177.791110444466;4189.958100418988;4212.031232816439;4272.081502476983;4402.559744025604;4441.511433742286;4512.34570987205;4515.909681806345;4556.567126123046;4547.704399214041;4551.726896386194;4605.98668292975;4633.32816741574;4637.698549594265;4133.689973252018;4226.028013556899;4291.163223171452;4306.849260275907;4310.7629080400875;4554;4582.495925448198;4601.72389656615;4608.147492713859;4686.156491831435;4277.251480990876;4447.933281000787;4476.888077798081;4482;4483.708558943658;4531.3882625845745;4549.749763763025;4556.225441674923;4647.256438969748;4659.767011649472;4156.667466602665;4356.281213599784;4510.774461276923;4514.0069184779295;4610.10103029906;4503.121891231179;4505.391772110723;4605.148047611203;4611.815527378934;4612.031473390625;3137.450946084426;3158.9052328430184;3166.5725127107544;3173.286010647586;3190.5692731481417;2734.5624700048293;2809.269589906598;2816.056621031936;2838.2620518665294;2919.8102123361914;4189.958100418957;4217.304144816071;4274.615284624337;4338.913221735547;4354.629856462237;4570.13167498181;4579.290210017452;4591.357209990604;4593.954060459419;4653.022865198279;4161.62545370912;4202.096549241927;4237.576242375738;4253.744775313429;4295.355696645528;3869.129445874712;3881.8059097045034;3924.136689928211;3940.625640564142;3963.623455771697;4545.477270113979;4560.156371071429;4642.744649044335;4736.928946065881;4738.241881298975;4471.7987690553355;4536.500984891725;4571.245744452128;4577.7482238477205;4591.908161836812;4581.816727330869;4627.699199552153;4629.513901040342;4636.513166117509;4724.196886529229;4460;4483.730976141442;4551;4564.7032942858605;4570.3829982951875;4316;4417.24906765857;4480.081583275355;4519.683622146418;4529.750863702528;4347.760873152007;4351.891202719958;4379;4461.776911154494;4466.330050492452;4350.825966961286;4421.093675796518;4509.391232183744;4517.932231016472;4533.274676051816;4122.484689413824;4230.703850730542;4256.4679415073115;4262.786860657016;4327.805248763876;4351.151525452507;4405.66957478184;4416.359627854007;4518.6611004174165;4524.04994951064;4261.87214383569;4326.740395576276;4350.934735979026;4352.368906508602;4440.888977775581 02:38:23 INFO - 2015-10-23 02:38:23,605 DEBUG : BROWSER_OUTPUT: |1;ext.html;463.5109958993261;481.94698583155866;517.3558919145662;524.7743470307768;525.212181727409;25431.364215894588;35256.29487410253;36332.463585560225;36459;36539.634603653954;36157.27685446296;37976;38044.23911521775;38465.230695386075;38488.84533463993;6090.725917333731;6222.751089956397;7706.7302644407455;8071.636776345079;8139.267465928066;9998.550065247082;10295.021972912562;10334.224933130045;10336.069753722164;10341.12100471457;9547.474888881108;9939.453330066843;9987.151092157175;10025.446055861317;10037.197024238043;6270.31026587075;6408.39120283577;9696.90303096967;9773.706788796348;9892.109710126088;9495.287853410953;9703.272254580934;9710.757231069209;9718.514074296327;9725.951370243174;34122.65877341245;35088.47367289493;35963.28073438517;36188.638113619054;36412.45381319256;2526;2541.224926397448;2619.188051703959;2734.740199681027;2776.9444611107865;26915.46169076627;26930.865345673144;26987.190384288308;27522.17433476999;28197.859010704815;2316.0504193280676;2321.58211521926;2346.77705617966;2384.356223819559;2431.8662473563945;234051;235803;240930;242492;243190;39371.80314098411;39903;45690.54309456929;47548.7622561885;47581.52418475771;43936.3409548857;44604.77697611491;45236.54763452389;45247;45460.54539454563;11498.885011149949;11591;11660.708482288012;11746.353950532632;11885.821712674317;8856.247218986458;8962.910370896208;8998.685046023358;9021.864672029924;9023.909760902437;14998.775018374734;15042.54021575044;15160.924195379173;15190.189814884281;15353.771698264336;6970.616616086164;7151.713931442788;7240;7247.4926755126635;7291.927080729125;748.2143749063462;795.061827044082;848.0374774630839;909.4225167018991;953.5756588318131;25142.74857251404;25483;25762.484750305262;25964.870175649;25987;857.189955492054;881.259741816877;898.3217670658742;929.1034152043311;961.3703024518951;12089.455974481169;12920.806187907188;13004.284764338052;13072.738545229042;13089.934550327187;246640;251233;258102;259149;260180;38777.22445551073;38866;38935.41596876049;38993.41509877354;39443.21113577712;1116.1126904111413;1122.887711228887;1122.960696375623;1128.841962125303;1143.5025763792776;23709.288721338387;24637.876810616548;24655.87672061628;24881.253562393158;25114.874425627753;35980.820095899355;36095.27809443849;36627.267454650224;36721.81639091788;37098.258034839695;225152;229939;231461;231627;232423;22183.778162218172;22551.097956081572;24784.50430991406;26573.867130664225;27197.320066998484;35693;35900.82049589735;35979.88341981922;36441.63558364382;36560.81719591491;30570;30663;30738.84630576833;31239.68760312459;31853.681463186;23287;25133.371665708502;25230.24309270725;29871.253218669706;31198.844005779825;27780.16659500218;30038.54942175913;30431.54352684756;31164;31167.532487013166;27021.86489067542;28630.427391451638;28806.567901481074;28873.422531548833;29199.85400072986;21696.349109526738;22692.20577279787;22774.772252277264;22821.821232062714;22962.885185573967 02:38:23 INFO - 2015-10-23 02:38:23,608 DEBUG : BROWSER_OUTPUT: |2;jquery.html;290.62534213171705;304.2227109734628;304.83538888999936;306.8971894415371;308.2417253556253;11270.718232044203;12288.754224915496;15201;15350.616234594127;15553.800473872061;9958.551865166066;13976.650583735387;14120.152790832532;14236.359363828627;14291.642708932255;4010.8195131219163;4110.97944510277;4111.773852438115;4130.132672138854;4132.710710250284;2964.762818974488;3046.299351149226;4096.487939007624;6494.480441564663;6704;6601.603903765789;6611.801645950629;6638.50211234157;6669.599824010551;6673.59958402495;6170.7531698732;6547.6071435713775;6629.73481060757;6679.799606011778;6680.799576012703;5631.267935168402;6151.108089327067;6179.536534759911;6258.405451482102;6275.968620156916;20565.89717051405;21236.150553977823;21751.56496870054;21913;21935.122595096178;1785.9107044647715;1880.1445342369188;1926.8554858385678;1934.5937353155855;2066.524699319165;13488.39302231402;13914.721705565933;14015.159090454605;14165.645858853612;14292.213928233943;1722.3196837249216;1753.7281721333213;1771.5836778357075;1774.5741022154718;1830.3136323878546;60389.8863539774;61980.69009654923;62393.68803156001;63750.36249637491;64688;13980.918300539299;14118.788218176736;14272;14279.92860035714;14353.856461435253;19563.902180489007;19594.608107837765;19965.900170499055;20084.89957550203;20118.899405503173;7832.099308579473;7878.803029924183;7917.208279172037;7972.441929065026;8056.234657707509;6777.559458635173;6822.454203663695;6832.521723479309;6958.373746362851;7024.438044956493;6626.237982631963;6647.335266473314;6771.390574848188;6807.591544507345;6812.386885180357;2380.833341666101;2419.588669926117;2866.5556838690036;2894.2619631993707;2951.409718056397;368.94465830125694;384.2583813240417;387.4169375090471;399.3670032997673;411.49797247358174;10158.831734350497;10359.689209323733;10502.632407865842;10542.42016689074;10767.461626918623;403.50772058089086;405.38381659876825;417.0969850274183;420.0779289459668;445.65907081083;5556.416576259435;5717.542596592262;5830.749753272231;5905.1142328650185;5909.438603332676;307051;308187;309835;309948;310044;49034.75482622564;56532.43467565354;58823.70588147032;59114.40885591089;65599.67200163969;440.08462398211645;457.39166908012265;458.0381199481063;458.8852786803299;459.07267320013386;10009.149222315893;10023.55157795584;10058.6479473221;10248.128909042814;10267.12729417978;33459.330813384084;34264.65735342615;34420.65579344275;34784.82607586946;35348.46977295394;45778.54221457743;45988.77005614951;46111.53888461072;46371.768141159075;46626.53373466222;5527.861803454945;5572.4148964358155;5630.662160270397;5802.82591522255;5803.332616749128;10599.62901298451;10874.891251087387;11074.5570177192;11145.832812507657;11175.050120739828;8129.634166462642;8409.075001749681;9115.589798459217;9380.906190938276;9873.703788886345;9271.211946984582;9273.119053689888;9322.254219662547;9553.092456216647;9680.225581953286;9477.052294770469;9565.330426870052;9605.615775368908;9612.615495380107;9802.803943920939;5558.388577256579;5559.63862348946;5562.3047119110115;5646.955313267059;5663.377028526941;9273.76815579589;9410.858837117587;9475.289353298393;9535.856962145712;9590.808183836423 02:38:23 INFO - 2015-10-23 02:38:23,609 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1169.8596168459785;1307.7646023715731;1393.770027945389;1473.3296350160679;1575.2123938030984;7103.85792284154;7870.803229919247;8133.999999999985;8302.211289927462;8329.791755206115;7895.171007044264;7914.841703165933;8012.318952889012;8074.51552906826;8087.110417854032;1807.5300421890272;1831.4871835885906;2043.2031507712004;2086.4679506725765;2097.737782777153;2130.446084018151;2165.133946421436;2170.70695456113;2176.4014895903674;2176.55380646967;2105.894705264738;2110.02938648222;2126.6065777831072;2154.1814110637933;2156.676498525218;1811.1849667649544;1861.4415675297357;2060.1862264405627;2090.2684060578677;2091.5607722378318;2126.436494329004;2145.6674215496623;2149.2907340577726;2164.5670865826896;2184.3992901951933;8020.719274825353;8056.35549156072;8072.152423995513;8119.512829230265;8137.145599712004;4562.1560011398;4636.652251081183;4674.065186962621;4677.064587082563;4678.251479763256;2694.8383097014244;2781.235160330905;2809.606655068292;2821.887124515017;2835.9149225523265;839.3327304792705;868.3010176807667;881.8236352729416;899.4827973915025;918.2241006349624;11223.775524489465;11389.715257118556;11459.999999999916;11470.942645286721;11483.598074067451;3852.2680690668685;4097.631213190767;4115.753054816661;4261.147770445923;4279.7218180818145;9656.468894210746;9669.419834809934;9678;9684.612615495302;9685.32202745815;4340.131973605292;4346.304591265445;4401.339799030171;4441.089576636782;4447.62195213404;3834.424836274581;3871;3873.8063096845044;3947.4276229946495;3971.3050216212046;3528.7000604948867;3539.3629146753833;3562.1628917204434;3592;3620.9275814483562;1102.4763237462141;1119.9664010079712;1123.039800970175;1125.6060378867494;1132.4903793293051;800.8118092248317;1143.1940481960198;1151.7063148896978;1159.8956093951585;1160.6692092753524;6746.797596072126;7283.70865165398;8014.358851291882;8017.158198389086;8039.236272554098;961.7643677299106;975.8926518083006;985.8028394321165;1006.1347241372414;1007.6171054999054;5576.9163462548095;5578.163275508625;5595.888082238333;5797.971010145006;5843.1235314703135;9972.551235194289;10140.44227567476;10156.289059765746;10162.39025658463;10170.847437288447;9905.158061564704;9913.30606857513;10029.548670309854;10044.698659040241;10055.69832905014;7068.681909314093;7281.963590182121;7295.562266264041;7328.890066649004;7341.045664063638;7177.6052317120975;7206.963965180141;7213.855722885618;7219.241979592067;7222.09723784527;8466.534340611326;8493.405461617627;8671.69649062305;8688.261497772508;8779.12208779117;8800;8801.339899507691;8925;8956.104389560991;9031.909680903369;6726.966365168143;6825.522213445011;6937.410320122846;6937.82655433598;7051.083359163481;7591.658375373232;7679.846403072019;7699.807504812425;7765.961170194113;7822.647980840762;7029.683664235224;7133.5719856808755;7178.066851309296;7208.315210055246;7332.816679583053;5925.466707996301;6948.6178260196175;6969.825754356181;7007.474439417166;7023.894641580483;7014.579125252501;7053.012578239153;7160.964195178991;7209.170945341353;7322.48742588035;6535.705893234911;6535.934640653532;6573.769918052845;6574.769883054261;6587.143671322698;6513.185851768529;6541.967290163519;6543.574667646588;6552.082708420729;6554.377334153439 02:38:23 INFO - 2015-10-23 02:38:23,684 DEBUG : BROWSER_OUTPUT: |4;prototype.html;284.5205828179518;289.23353114247243;289.6350598246209;295.2029520295202;295.2633180215363;4291.291936830423;4486.663500237483;4508.639308855292;4623.953760462395;4624.445066592009;4533.070720502297;4536.115457485792;4541.296099104636;4541.65937554683;4550.772461376934;3491.0225136961562;3493.6681015303634;3496.3531746626827;3498.177928186874;3498.9475157872653;3340.265141668829;3482.059843842161;3489.70337521311;3494.5107684924005;3495.4756786482103;3332.450145725952;3381.1040074380185;3398.626151123387;3447.3105378924192;3449.5860496740424;3443.345764304774;3445.913852153691;3449.137715571107;3452.171478845084;3453.8445769940413;2942.5880376747273;2974.851257437141;3005.8947936822324;3013.969860301391;3021.818690878554;4390.056137930328;4395.890102747425;4458.420405347317;4458.866234012985;4462.754548499831;3635.1093981974323;3879.262940041383;4013.1572369802375;4092.7544347339253;4114.259433302004;4799.880002999918;4808.567228949376;4812.518748125194;4813.350197723297;4815.2054910940005;3391.406503861814;3400.081977865987;3403.6256011838927;3404.0979140527534;3461;4922.852314430573;4960.553550180465;4970.403551573798;4979.775910084055;4990.850274491735;3441.9311613768086;3562.340966921076;3574.8033858137533;3586.677199052098;3599.4240921452965;3625.9818700906326;3638.945415818765;3643.5809881863397;3643.635636436335;3646.6717995380545;4016;4023.1752490739327;4106.425100485874;4107.445494858216;4112.3420252759415;4510.977445112753;4790.161721698689;4803.663743537919;4805.7597120144555;4810.4467986181335;1289.6969212235117;1294.5986744109357;1295.3976400973404;1317.5915466205506;1317.7957416600443;859.6475445067492;894.8612964990307;906.1708536688856;913.995430022859;924.9630014799333;4603.608693261111;4605.608523275492;4608.815647374135;4609.308603709471;4615.861524154214;5029.421616514074;5030.622703297267;5031.4465408805;5033.295338652591;5034.471380505068;4601.677882548256;4608.700434471749;4612.561806628365;4615.792289346988;4616.1229366420275;2710.457908418324;2738.8082834201414;2741.876615552305;2743.7942154338502;2747.120921305163;5092.159793634083;5102.9489705103215;5104.3109180260935;5106.693598384109;5112.4120726116225;5041.193409054534;5060.5951523878;5065.442801291855;5114.769835357418;5156.819511317086;4856.17445034345;4906.8773280668265;5043;5103.106956282636;5110.540051395392;4895.4370247420575;4904.583110435653;4922.366533904344;4979.278004689372;5030.4466508684745;4817.349657796293;4819.518048195152;4841.104395686809;4940.7035577865445;4941.555260026615;4845.9757701211265;5003.874903127451;5073.7716802743225;5101.79592816298;5107.233914912793;3420.1107711994564;3457.740669449852;3499.177693242137;3504.123969007748;3506.561679790026;4785.688930219525;4945.950540494549;4958.851234462972;5056;5056.367954005749;4690.882727931693;4693.24908014716;4715.434147902137;4731.810727570859;4807.158747219153;4725.456572494207;4759.595434388116;4778.474367819606;4798.712077275374;4807.95192048089;4487.6185524230805;4600.42494688164;4671.8364857229835;4690.601298889633;4703.435587729357;4722.12640661465;4758.452777930583;4833.275008748576;4843.636727245541;4845.903081938271;4677.357492074079;4739.810407583658;4769.189237829647;4835.734034627989;4849 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: |5;yui.html;590.2916500199761;598.4464370457329;617.62609866181;655.3479288108332;733.6881825224278;10228.744281392968;10348.172146228302;10350.482475876195;10356.378617282968;10359.741006474842;7875.645595948182;10002.249831262649;10098.747531311721;10111;10410.271281010333;594.7502049139362;596.2487266044793;601.5007543738708;604.939506049396;611.3977731934064;604.3291945940025;607.4957785038407;608.2184393054926;610.5298919831727;614.2322097378277;636.8630744389956;637.512303088138;640.9647469389182;641.6246495799962;644.2333622988652;623.890819106659;624.8843963866699;638.0747915522488;641.8716256748645;644.6325594411164;449.869537834031;457.88781748471837;459.41424683528436;465.27184955544465;465.75315083006063;9860.359076659994;10195.7451063724;10216.89783102167;10218.335808172445;10227.590896364136;8938.68714594986;9026.729198124067;9125.908740912639;9271.351005429622;9394.107559781809;7960;7982.800429989296;8242.216989386;8271.793205169859;8650.826983460296;3120.2823350629255;3123.3128711683394;3151.732102771244;3195;3218.4045951498815;32876;33299.83350083258;34182.6581734182;34240.99999999975;34300.313993720236;7780.49426787257;8309;8486.915130848613;8666;8749.825003499895;14192.148471091561;14447.566573002827;14559;14569.562913112624;14573.125612463286;6054.935374539044;6057.3336932937345;6231.283402408691;6280.7487700491465;6341.112244285804;3259.9837000814846;3285.704286614216;3303;3307.9007629771145;3482.8084455355197;2478.9752102478747;2494.8128890333296;2496.8252222344627;2499.7500249974855;2501.3121391617087;387.5156054931336;395.93269144245545;397.65006794021076;400.43938486119083;405.8680928698185;560.7981126794351;572.9455701708414;588.5291766586713;606.375433303698;618.5453691536717;7149.678264478007;7167.569945803164;7214.531055481377;7229.457790665721;7274.418046556263;463.77738685430506;578.6383510306059;590.3801008940595;602.2622287697535;610.8869859075996;3768.5999850452054;3804.505414296124;3933.449317095609;3949.743266687656;4043.5754245804355;43926.34110488345;45714.31428528575;46027.76986115048;46064.76967615208;46155.30767038497;37843.81078094592;38106.809465952494;38194;38238.6176138235;38324.42513362246;28669.139925802257;28926.566101508495;31567.21081972969;31970.68029319677;32053.839730801665;7034.548225125569;7285.490015698851;7424.628768561658;7527.8118047049265;7547.924520754722;21048.579028419652;21465.356039318845;21678.783212168306;21752.89123554372;21753;26951.326216844736;27126.72873271242;29057.27356816012;29565.556516651835;31332;6181.196444462191;6307.211598550181;6626.213832493522;6737.663116844236;6793.592384456949;10285.98129911972;10570.8414373786;10668.750186621102;10783.946080269548;11533.769324613628;9278.550437822425;9544.713658590254;9562.378445401027;9580.856287155559;10000.59997600117;10111.59553617847;10238.232132589937;10241.9487902563;10245.436500992517;10275.7431064224;9561.378510396802;9665.5204126513;9674.7581310465;9793.755156121155;10084.495775211357;8767.29861611057;8770;8819.426737262058;9145.63417463294;9169.266458683156;8163.387715931082;8209.79475513093;8389.95805020971;8390.496570205807;8508.001858689733 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,830 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: __startTimestamp1445593103628__endTimestamp 02:38:23 INFO - 2015-10-23 02:38:23,687 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,688 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:38:23 INFO - 2015-10-23 02:38:23,688 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:38:23 INFO - 2015-10-23 02:38:23,688 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,688 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4165.29 Median:4350.83 stddev:535.28 (12.3%) stddev-sans-first:517.02 02:38:23 INFO - 2015-10-23 02:38:23,688 DEBUG : BROWSER_OUTPUT: Values: 2321.6 2431.2 2436.2 2437.8 2450.5 4272.6 4278.0 4339.5 4353.3 4362.4 4261.9 4272.0 4348.6 4350.7 4352.1 3559.9 3660.7 3735.0 3743.0 3793.4 3631.9 3647.0 3695.4 3760.5 3768.0 3663.9 3731.5 3732.7 3739.6 3812.2 3605.2 3705.9 3718.9 3729.5 3764.1 3595.7 3601.3 3777.1 3787.2 3795.9 4002.1 4177.8 4190.0 4212.0 4272.1 4402.6 4441.5 4512.3 4515.9 4556.6 4547.7 4551.7 4606.0 4633.3 4637.7 4133.7 4226.0 4291.2 4306.8 4310.8 4554.0 4582.5 4601.7 4608.1 4686.2 4277.3 4447.9 4476.9 4482.0 4483.7 4531.4 4549.7 4556.2 4647.3 4659.8 4156.7 4356.3 4510.8 4514.0 4610.1 4503.1 4505.4 4605.1 4611.8 4612.0 3137.5 3158.9 3166.6 3173.3 3190.6 2734.6 2809.3 2816.1 2838.3 2919.8 4190.0 4217.3 4274.6 4338.9 4354.6 4570.1 4579.3 4591.4 4594.0 4653.0 4161.6 4202.1 4237.6 4253.7 4295.4 3869.1 3881.8 3924.1 3940.6 3963.6 4545.5 4560.2 4642.7 4736.9 4738.2 4471.8 4536.5 4571.2 4577.7 4591.9 4581.8 4627.7 4629.5 4636.5 4724.2 4460.0 4483.7 4551.0 4564.7 4570.4 4316.0 4417.2 4480.1 4519.7 4529.8 4347.8 4351.9 4379.0 4461.8 4466.3 4350.8 4421.1 4509.4 4517.9 4533.3 4122.5 4230.7 4256.5 4262.8 4327.8 4351.2 4405.7 4416.4 4518.7 4524.0 4261.9 4326.7 4350.9 4352.4 4440.9 02:38:23 INFO - 2015-10-23 02:38:23,697 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,697 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38430.51 Median:30700.92 stddev:62936.37 (205.0%) stddev-sans-first:63048.36 02:38:23 INFO - 2015-10-23 02:38:23,697 DEBUG : BROWSER_OUTPUT: Values: 463.5 481.9 517.4 524.8 525.2 25431.4 35256.3 36332.5 36459.0 36539.6 36157.3 37976.0 38044.2 38465.2 38488.8 6090.7 6222.8 7706.7 8071.6 8139.3 9998.6 10295.0 10334.2 10336.1 10341.1 9547.5 9939.5 9987.2 10025.4 10037.2 6270.3 6408.4 9696.9 9773.7 9892.1 9495.3 9703.3 9710.8 9718.5 9726.0 34122.7 35088.5 35963.3 36188.6 36412.5 2526.0 2541.2 2619.2 2734.7 2776.9 26915.5 26930.9 26987.2 27522.2 28197.9 2316.1 2321.6 2346.8 2384.4 2431.9 234051.0 235803.0 240930.0 242492.0 243190.0 39371.8 39903.0 45690.5 47548.8 47581.5 43936.3 44604.8 45236.5 45247.0 45460.5 11498.9 11591.0 11660.7 11746.4 11885.8 8856.2 8962.9 8998.7 9021.9 9023.9 14998.8 15042.5 15160.9 15190.2 15353.8 6970.6 7151.7 7240.0 7247.5 7291.9 748.2 795.1 848.0 909.4 953.6 25142.7 25483.0 25762.5 25964.9 25987.0 857.2 881.3 898.3 929.1 961.4 12089.5 12920.8 13004.3 13072.7 13089.9 246640.0 251233.0 258102.0 259149.0 260180.0 38777.2 38866.0 38935.4 38993.4 39443.2 1116.1 1122.9 1123.0 1128.8 1143.5 23709.3 24637.9 24655.9 24881.3 25114.9 35980.8 36095.3 36627.3 36721.8 37098.3 225152.0 229939.0 231461.0 231627.0 232423.0 22183.8 22551.1 24784.5 26573.9 27197.3 35693.0 35900.8 35979.9 36441.6 36560.8 30570.0 30663.0 30738.8 31239.7 31853.7 23287.0 25133.4 25230.2 29871.3 31198.8 27780.2 30038.5 30431.5 31164.0 31167.5 27021.9 28630.4 28806.6 28873.4 29199.9 21696.3 22692.2 22774.8 22821.8 22962.9 02:38:23 INFO - 2015-10-23 02:38:23,697 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,698 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:20847.40 Median:430.08 stddev:51025.75 (11864.2%) stddev-sans-first:51145.54 02:38:23 INFO - 2015-10-23 02:38:23,698 DEBUG : BROWSER_OUTPUT: Values: 290.6 304.2 304.8 306.9 308.2 11270.7 12288.8 15201.0 15350.6 15553.8 9958.6 13976.7 14120.2 14236.4 14291.6 4010.8 4111.0 4111.8 4130.1 4132.7 2964.8 3046.3 4096.5 6494.5 6704.0 6601.6 6611.8 6638.5 6669.6 6673.6 6170.8 6547.6 6629.7 6679.8 6680.8 5631.3 6151.1 6179.5 6258.4 6276.0 20565.9 21236.2 21751.6 21913.0 21935.1 1785.9 1880.1 1926.9 1934.6 2066.5 13488.4 13914.7 14015.2 14165.6 14292.2 1722.3 1753.7 1771.6 1774.6 1830.3 60389.9 61980.7 62393.7 63750.4 64688.0 13980.9 14118.8 14272.0 14279.9 14353.9 19563.9 19594.6 19965.9 20084.9 20118.9 7832.1 7878.8 7917.2 7972.4 8056.2 6777.6 6822.5 6832.5 6958.4 7024.4 6626.2 6647.3 6771.4 6807.6 6812.4 2380.8 2419.6 2866.6 2894.3 2951.4 368.9 384.3 387.4 399.4 411.5 10158.8 10359.7 10502.6 10542.4 10767.5 403.5 405.4 417.1 420.1 445.7 5556.4 5717.5 5830.7 5905.1 5909.4 307051.0 308187.0 309835.0 309948.0 310044.0 49034.8 56532.4 58823.7 59114.4 65599.7 440.1 457.4 458.0 458.9 459.1 10009.1 10023.6 10058.6 10248.1 10267.1 33459.3 34264.7 34420.7 34784.8 35348.5 45778.5 45988.8 46111.5 46371.8 46626.5 5527.9 5572.4 5630.7 5802.8 5803.3 10599.6 10874.9 11074.6 11145.8 11175.1 8129.6 8409.1 9115.6 9380.9 9873.7 9271.2 9273.1 9322.3 9553.1 9680.2 9477.1 9565.3 9605.6 9612.6 9802.8 5558.4 5559.6 5562.3 5647.0 5663.4 9273.8 9410.9 9475.3 9535.9 9590.8 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5516.77 Median:6535.82 stddev:3058.80 (46.8%) stddev-sans-first:3049.93 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: Values: 1169.9 1307.8 1393.8 1473.3 1575.2 7103.9 7870.8 8134.0 8302.2 8329.8 7895.2 7914.8 8012.3 8074.5 8087.1 1807.5 1831.5 2043.2 2086.5 2097.7 2130.4 2165.1 2170.7 2176.4 2176.6 2105.9 2110.0 2126.6 2154.2 2156.7 1811.2 1861.4 2060.2 2090.3 2091.6 2126.4 2145.7 2149.3 2164.6 2184.4 8020.7 8056.4 8072.2 8119.5 8137.1 4562.2 4636.7 4674.1 4677.1 4678.3 2694.8 2781.2 2809.6 2821.9 2835.9 839.3 868.3 881.8 899.5 918.2 11223.8 11389.7 11460.0 11470.9 11483.6 3852.3 4097.6 4115.8 4261.1 4279.7 9656.5 9669.4 9678.0 9684.6 9685.3 4340.1 4346.3 4401.3 4441.1 4447.6 3834.4 3871.0 3873.8 3947.4 3971.3 3528.7 3539.4 3562.2 3592.0 3620.9 1102.5 1120.0 1123.0 1125.6 1132.5 800.8 1143.2 1151.7 1159.9 1160.7 6746.8 7283.7 8014.4 8017.2 8039.2 961.8 975.9 985.8 1006.1 1007.6 5576.9 5578.2 5595.9 5798.0 5843.1 9972.6 10140.4 10156.3 10162.4 10170.8 9905.2 9913.3 10029.5 10044.7 10055.7 7068.7 7282.0 7295.6 7328.9 7341.0 7177.6 7207.0 7213.9 7219.2 7222.1 8466.5 8493.4 8671.7 8688.3 8779.1 8800.0 8801.3 8925.0 8956.1 9031.9 6727.0 6825.5 6937.4 6937.8 7051.1 7591.7 7679.8 7699.8 7766.0 7822.6 7029.7 7133.6 7178.1 7208.3 7332.8 5925.5 6948.6 6969.8 7007.5 7023.9 7014.6 7053.0 7161.0 7209.2 7322.5 6535.7 6535.9 6573.8 6574.8 6587.1 6513.2 6542.0 6543.6 6552.1 6554.4 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4030.30 Median:4609.06 stddev:1181.92 (25.6%) stddev-sans-first:1151.31 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: Values: 284.5 289.2 289.6 295.2 295.3 4291.3 4486.7 4508.6 4624.0 4624.4 4533.1 4536.1 4541.3 4541.7 4550.8 3491.0 3493.7 3496.4 3498.2 3498.9 3340.3 3482.1 3489.7 3494.5 3495.5 3332.5 3381.1 3398.6 3447.3 3449.6 3443.3 3445.9 3449.1 3452.2 3453.8 2942.6 2974.9 3005.9 3014.0 3021.8 4390.1 4395.9 4458.4 4458.9 4462.8 3635.1 3879.3 4013.2 4092.8 4114.3 4799.9 4808.6 4812.5 4813.4 4815.2 3391.4 3400.1 3403.6 3404.1 3461.0 4922.9 4960.6 4970.4 4979.8 4990.9 3441.9 3562.3 3574.8 3586.7 3599.4 3626.0 3638.9 3643.6 3643.6 3646.7 4016.0 4023.2 4106.4 4107.4 4112.3 4511.0 4790.2 4803.7 4805.8 4810.4 1289.7 1294.6 1295.4 1317.6 1317.8 859.6 894.9 906.2 914.0 925.0 4603.6 4605.6 4608.8 4609.3 4615.9 5029.4 5030.6 5031.4 5033.3 5034.5 4601.7 4608.7 4612.6 4615.8 4616.1 2710.5 2738.8 2741.9 2743.8 2747.1 5092.2 5102.9 5104.3 5106.7 5112.4 5041.2 5060.6 5065.4 5114.8 5156.8 4856.2 4906.9 5043.0 5103.1 5110.5 4895.4 4904.6 4922.4 4979.3 5030.4 4817.3 4819.5 4841.1 4940.7 4941.6 4846.0 5003.9 5073.8 5101.8 5107.2 3420.1 3457.7 3499.2 3504.1 3506.6 4785.7 4946.0 4958.9 5056.0 5056.4 4690.9 4693.2 4715.4 4731.8 4807.2 4725.5 4759.6 4778.5 4798.7 4808.0 4487.6 4600.4 4671.8 4690.6 4703.4 4722.1 4758.5 4833.3 4843.6 4845.9 4677.4 4739.8 4769.2 4835.7 4849.0 02:38:23 INFO - 2015-10-23 02:38:23,703 DEBUG : BROWSER_OUTPUT: 02:38:23 INFO - 2015-10-23 02:38:23,704 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10328.33 Median:513.28 stddev:11332.25 (2207.8%) stddev-sans-first:11340.44 02:38:23 INFO - 2015-10-23 02:38:23,704 DEBUG : BROWSER_OUTPUT: Values: 590.3 598.4 617.6 655.3 733.7 10228.7 10348.2 10350.5 10356.4 10359.7 7875.6 10002.2 10098.7 10111.0 10410.3 594.8 596.2 601.5 604.9 611.4 604.3 607.5 608.2 610.5 614.2 636.9 637.5 641.0 641.6 644.2 623.9 624.9 638.1 641.9 644.6 449.9 457.9 459.4 465.3 465.8 9860.4 10195.7 10216.9 10218.3 10227.6 8938.7 9026.7 9125.9 9271.4 9394.1 7960.0 7982.8 8242.2 8271.8 8650.8 3120.3 3123.3 3151.7 3195.0 3218.4 32876.0 33299.8 34182.7 34241.0 34300.3 7780.5 8309.0 8486.9 8666.0 8749.8 14192.1 14447.6 14559.0 14569.6 14573.1 6054.9 6057.3 6231.3 6280.7 6341.1 3260.0 3285.7 3303.0 3307.9 3482.8 2479.0 2494.8 2496.8 2499.8 2501.3 387.5 395.9 397.7 400.4 405.9 560.8 572.9 588.5 606.4 618.5 7149.7 7167.6 7214.5 7229.5 7274.4 463.8 578.6 590.4 602.3 610.9 3768.6 3804.5 3933.4 3949.7 4043.6 43926.3 45714.3 46027.8 46064.8 46155.3 37843.8 38106.8 38194.0 38238.6 38324.4 28669.1 28926.6 31567.2 31970.7 32053.8 7034.5 7285.5 7424.6 7527.8 7547.9 21048.6 21465.4 21678.8 21752.9 21753.0 26951.3 27126.7 29057.3 29565.6 31332.0 6181.2 6307.2 6626.2 6737.7 6793.6 10286.0 10570.8 10668.8 10783.9 11533.8 9278.6 9544.7 9562.4 9580.9 10000.6 10111.6 10238.2 10241.9 10245.4 10275.7 9561.4 9665.5 9674.8 9793.8 10084.5 8767.3 8770.0 8819.4 9145.6 9169.3 8163.4 8209.8 8390.0 8390.5 8508.0 02:38:23 INFO - 2015-10-23 02:38:23,713 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:38:23 INFO - 2015-10-23 02:38:23,714 DEBUG : BROWSER_OUTPUT: 02:38:24 INFO - 2015-10-23 02:38:24,137 INFO : Browser exited with error code: 0 02:38:24 INFO - 2015-10-23 02:38:24,874 INFO : Completed test dromaeo_css (00:18:48) 02:38:24 INFO - 2015-10-23 02:38:24,875 INFO : Starting test kraken 02:38:24 INFO - 2015-10-23 02:38:24,875 DEBUG : operating with platform_type : w7_ 02:38:24 INFO - 2015-10-23 02:38:24,875 INFO : Initialising browser for kraken test... 02:38:24 INFO - 2015-10-23 02:38:24,923 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmps_zdcn\profile http://localhost:49672/getInfo.html 02:38:30 INFO - 2015-10-23 02:38:30,266 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:38:30 INFO - 2015-10-23 02:38:30,266 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:38:30 INFO - 2015-10-23 02:38:30,266 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1008/673 02:38:30 INFO - 2015-10-23 02:38:30,266 DEBUG : BROWSER_OUTPUT: __metrics 02:38:30 INFO - 2015-10-23 02:38:30,365 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:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: console.error: 02:38:31 INFO - 2015-10-23 02:38:31,292 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:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: Stack: 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:791:13 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:38:31 INFO - 2015-10-23 02:38:31,292 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:38:31 INFO - 2015-10-23 02:38:31,293 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:38:31 INFO - 2015-10-23 02:38:31,293 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 02:38:31 INFO - 2015-10-23 02:38:31,293 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5801.53 02:40:11 INFO - RETURN: kraken: 1623.70 02:40:11 INFO - RETURN: v8_7: 20036.96 02:40:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,25]]", "result": "20036.96"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,25]]", "result": "1623.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,25]]", "result": "5801.53"}}} 02:40:11 INFO - Return code: 0 02:40:11 INFO - # TBPL SUCCESS # 02:40:11 INFO - Running post-action listener: _resource_record_post_action 02:40:11 INFO - Running post-run listener: _resource_record_post_run 02:40:12 INFO - Total resource usage - Wall time: 1238s; CPU: 13.0%; Read bytes: 265165312; Write bytes: 578465280; Read time: 5874570; Write time: 7292750 02:40:12 INFO - install - Wall time: 2s; CPU: 11.0%; Read bytes: 327680; Write bytes: 978944; Read time: 39640; Write time: 41130 02:40:12 INFO - run-tests - Wall time: 1237s; CPU: 13.0%; Read bytes: 264837632; Write bytes: 556346880; Read time: 5834930; Write time: 7237410 02:40:12 INFO - Running post-run listener: _upload_blobber_files 02:40:12 INFO - Blob upload gear active. 02:40:12 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:40:12 INFO - Copying logs to upload dir... 02:40:12 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1529.181000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 34 secs) (at 2015-10-23 02:40:17.438955) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:40:17.444202) ========= '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-176 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-176 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-176 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/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/pmfku5GaTcCZ0ej8_Cr3Og/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 02:40:18.105704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 02:40:18.106176) ========= '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-176 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-176 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-176 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-23 02:40:18.227889) ========= ========= Started reboot slave lost (results: 0, elapsed: 15 secs) (at 2015-10-23 02:40:18.228190) ========= ========= Finished reboot slave lost (results: 0, elapsed: 15 secs) (at 2015-10-23 02:40:33.809433) =========