builder: mozilla-inbound_win7-ix_test-dromaeojs slave: t-w732-ix-078 starttime: 1445947988.77 results: success (0) buildid: 20151026024333 builduid: c8c0980905524249b66e65e1a87ce6b0 revision: 67770106a029c671f6af7a6b9653eedef3ef08aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:08.770066) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:08.770836) ========= ========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:08.771165) ========= '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-078 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-078 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-078 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.583000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:10.609450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2015-10-27 05:13:10.609742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2015-10-27 05:13:13.075401) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:13.075667) ========= '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-078 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-078 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-078 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:14.285223) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:14.285507) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:14.285837) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2015-10-27 05:13:14.286112) ========= '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-078 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-078 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-078 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 --05:13:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.27 GB/s 05:13:15 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.772000 ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2015-10-27 05:13:23.132729) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2015-10-27 05:13:23.133044) ========= '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-078 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-078 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-078 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=16.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 17 secs) (at 2015-10-27 05:13:40.721180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-27 05:13:40.721541) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 67770106a029c671f6af7a6b9653eedef3ef08aa --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 67770106a029c671f6af7a6b9653eedef3ef08aa --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-078 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-078 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-078 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-27 05:13:41,296 truncating revision to first 12 chars 2015-10-27 05:13:41,296 Setting DEBUG logging. 2015-10-27 05:13:41,296 attempt 1/10 2015-10-27 05:13:41,296 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/67770106a029?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-27 05:13:41,786 unpacking tar archive at: mozilla-inbound-67770106a029/testing/mozharness/ program finished with exit code 0 elapsedTime=1.174000 ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2015-10-27 05:13:50.276572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:50.276863) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-27 05:13:51.992239) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:51.992510) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 05:13:51.992866) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 28 secs) (at 2015-10-27 05:13:51.993150) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound-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', 'Mozilla-Inbound-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-078 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-078 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-078 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 05:13:52 INFO - MultiFileLogger online at 20151027 05:13:52 in C:\slave\test 05:13:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound-Non-PGO 05:13:52 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 05:13:52 INFO - {'append_to_log': False, 05:13:52 INFO - 'base_work_dir': 'C:\\slave\\test', 05:13:52 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO', 05:13:52 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 05:13:52 INFO - 'branch': 'Mozilla-Inbound-Non-PGO', 05:13:52 INFO - 'buildbot_json_path': 'buildprops.json', 05:13:52 INFO - 'config_files': ('talos/windows_config.py',), 05:13:52 INFO - 'default_actions': ('clobber', 05:13:52 INFO - 'read-buildbot-config', 05:13:52 INFO - 'download-and-extract', 05:13:52 INFO - 'populate-webroot', 05:13:52 INFO - 'create-virtualenv', 05:13:52 INFO - 'install', 05:13:52 INFO - 'run-tests'), 05:13:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:13:52 INFO - 'download_minidump_stackwalk': True, 05:13:52 INFO - 'download_symbols': 'ondemand', 05:13:52 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 05:13:52 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 05:13:52 INFO - 'hg': 'c:/mozilla-build/hg/hg', 05:13:52 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 05:13:52 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 05:13:52 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 05:13:52 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 05:13:52 INFO - 'C:/mozilla-build/tooltool.py'), 05:13:52 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 05:13:52 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 05:13:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:13:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:13:52 INFO - 'installer_path': 'installer.exe', 05:13:52 INFO - 'log_level': 'info', 05:13:52 INFO - 'log_name': 'talos', 05:13:52 INFO - 'log_to_console': True, 05:13:52 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 05:13:52 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 05:13:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 05:13:52 INFO - 'opt_config_files': (), 05:13:52 INFO - 'pip_index': False, 05:13:52 INFO - 'sps_profile': False, 05:13:52 INFO - 'sps_profile_interval': 0, 05:13:52 INFO - 'suite': 'dromaeojs', 05:13:52 INFO - 'system_bits': '32', 05:13:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:13:52 INFO - 'title': 'T-W732-IX-078', 05:13:52 INFO - 'use_talos_json': True, 05:13:52 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 05:13:52 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 05:13:52 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 05:13:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:13:52 INFO - 'work_dir': 'build'} 05:13:52 INFO - ##### 05:13:52 INFO - ##### Running clobber step. 05:13:52 INFO - ##### 05:13:52 INFO - Running pre-action listener: _resource_record_pre_action 05:13:52 INFO - Running main action method: clobber 05:13:52 INFO - rmtree: C:\slave\test\build 05:13:52 INFO - Using _rmtree_windows ... 05:13:52 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 05:15:21 INFO - Running post-action listener: _resource_record_post_action 05:15:21 INFO - ##### 05:15:21 INFO - ##### Running read-buildbot-config step. 05:15:21 INFO - ##### 05:15:21 INFO - Running pre-action listener: _resource_record_pre_action 05:15:21 INFO - Running main action method: read_buildbot_config 05:15:21 INFO - Using buildbot properties: 05:15:21 INFO - { 05:15:21 INFO - "properties": { 05:15:21 INFO - "buildnumber": 2283, 05:15:21 INFO - "product": "firefox", 05:15:21 INFO - "script_repo_revision": "production", 05:15:21 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs", 05:15:21 INFO - "repository": "", 05:15:21 INFO - "buildername": "Windows 7 32-bit mozilla-inbound talos dromaeojs", 05:15:21 INFO - "buildid": "20151026024333", 05:15:21 INFO - "slavename": "t-w732-ix-078", 05:15:21 INFO - "pgo_build": "False", 05:15:21 INFO - "basedir": "C:\\slave\\test", 05:15:21 INFO - "project": "", 05:15:21 INFO - "platform": "win7-ix", 05:15:21 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 05:15:21 INFO - "slavebuilddir": "test", 05:15:21 INFO - "scheduler": "tests-mozilla-inbound-win32-talos", 05:15:21 INFO - "branch": "mozilla-inbound", 05:15:21 INFO - "repo_path": "integration/mozilla-inbound", 05:15:21 INFO - "stage_platform": "win32", 05:15:21 INFO - "builduid": "c8c0980905524249b66e65e1a87ce6b0", 05:15:21 INFO - "revision": "67770106a029c671f6af7a6b9653eedef3ef08aa" 05:15:21 INFO - }, 05:15:21 INFO - "sourcestamp": { 05:15:21 INFO - "repository": "", 05:15:21 INFO - "hasPatch": false, 05:15:21 INFO - "project": "", 05:15:21 INFO - "branch": "mozilla-inbound-win32-talos", 05:15:21 INFO - "changes": [ 05:15:21 INFO - { 05:15:21 INFO - "category": null, 05:15:21 INFO - "files": [ 05:15:21 INFO - { 05:15:21 INFO - "url": null, 05:15:21 INFO - "name": "https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 05:15:21 INFO - } 05:15:21 INFO - ], 05:15:21 INFO - "repository": "", 05:15:21 INFO - "rev": "67770106a029c671f6af7a6b9653eedef3ef08aa", 05:15:21 INFO - "who": "cbook@mozilla.com", 05:15:21 INFO - "when": 1445856782, 05:15:21 INFO - "number": 6572724, 05:15:21 INFO - "comments": "Bug 1196654 - navigator.mozBrowserElementProxy for access Browser API methods from the embedded, r=kanru, sr=sicking", 05:15:21 INFO - "project": "", 05:15:21 INFO - "at": "Mon 26 Oct 2015 03:53:02", 05:15:21 INFO - "branch": "mozilla-inbound-win32-talos", 05:15:21 INFO - "revlink": "", 05:15:21 INFO - "properties": [ 05:15:21 INFO - [ 05:15:21 INFO - "buildid", 05:15:21 INFO - "20151026024333", 05:15:21 INFO - "Change" 05:15:21 INFO - ], 05:15:21 INFO - [ 05:15:21 INFO - "builduid", 05:15:21 INFO - "c8c0980905524249b66e65e1a87ce6b0", 05:15:21 INFO - "Change" 05:15:21 INFO - ], 05:15:21 INFO - [ 05:15:21 INFO - "pgo_build", 05:15:21 INFO - "False", 05:15:21 INFO - "Change" 05:15:21 INFO - ] 05:15:21 INFO - ], 05:15:21 INFO - "revision": "67770106a029c671f6af7a6b9653eedef3ef08aa" 05:15:21 INFO - } 05:15:21 INFO - ], 05:15:21 INFO - "revision": "67770106a029c671f6af7a6b9653eedef3ef08aa" 05:15:21 INFO - } 05:15:21 INFO - } 05:15:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 05:15:21 INFO - Running post-action listener: _resource_record_post_action 05:15:21 INFO - ##### 05:15:21 INFO - ##### Running download-and-extract step. 05:15:21 INFO - ##### 05:15:21 INFO - Running pre-action listener: _resource_record_pre_action 05:15:21 INFO - Running main action method: download_and_extract 05:15:21 INFO - mkdir: C:\slave\test\build\tests 05:15:21 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')]} 05:15:21 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:15:21 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/test_packages.json 05:15:21 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 05:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 05:15:22 INFO - Downloaded 1221 bytes. 05:15:22 INFO - Reading from file C:\slave\test\build\test_packages.json 05:15:22 INFO - Using the following test package requirements: 05:15:22 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 05:15:22 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 05:15:22 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'jsshell-win32.zip'], 05:15:22 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 05:15:22 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 05:15:22 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 05:15:22 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 05:15:22 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 05:15:22 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 05:15:22 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 05:15:22 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 05:15:22 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 05:15:22 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')]} 05:15:22 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 05:15:22 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 05:15:22 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:15:24 INFO - Downloaded 18013463 bytes. 05:15:24 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 05:15:24 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 05:15:24 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 05:15:58 INFO - Return code: 0 05:15:58 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 05:15:58 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')]} 05:15:58 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 05:15:58 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 05:15:58 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:15:59 INFO - Downloaded 18013463 bytes. 05:15:59 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 05:15:59 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 05:15:59 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 05:16:22 INFO - Return code: 0 05:16:22 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')]} 05:16:22 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 05:16:22 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 05:16:22 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:16:24 INFO - Downloaded 11131307 bytes. 05:16:24 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 05:16:24 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 05:16:24 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 05:16:28 INFO - Return code: 0 05:16:28 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')]} 05:16:28 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 05:16:28 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 05:16:28 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 05:16:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:16:31 INFO - Downloaded 51495121 bytes. 05:16:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 05:16:31 INFO - mkdir: C:\slave\test\properties 05:16:31 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 05:16:31 INFO - Writing to file C:\slave\test\properties\build_url 05:16:31 INFO - Contents: 05:16:31 INFO - build_url:https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 05:16:31 INFO - Running post-action listener: _resource_record_post_action 05:16:31 INFO - Running post-action listener: set_extra_try_arguments 05:16:31 INFO - ##### 05:16:31 INFO - ##### Running populate-webroot step. 05:16:31 INFO - ##### 05:16:31 INFO - Running pre-action listener: _resource_record_pre_action 05:16:31 INFO - Running main action method: populate_webroot 05:16:31 INFO - Guessing talos json url... 05:16: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')]} 05:16:31 INFO - https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 05:16:31 INFO - trying https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 05:16:31 INFO - Downloading https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 05:16:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/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 05:16:32 INFO - Downloaded 111 bytes. 05:16:32 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 05:16:32 INFO - Contents: 05:16:32 INFO - 20151026024333 05:16:32 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/67770106a029c671f6af7a6b9653eedef3ef08aa 05:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:16:32 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/67770106a0/testing/talos/talos.json 05:16:32 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/67770106a0/testing/talos/talos.json to C:\slave\test\build\talos.json 05:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/67770106a0/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 05:16:32 INFO - Downloaded 4264 bytes. 05:16:32 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 05:16:32 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 05:16:32 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 05:16:32 INFO - u'../fennec_ids.txt'], 05:16:32 INFO - u'tests': [u'tcheck2']}, 05:16:32 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 05:16:32 INFO - u'--tppagecycles', 05:16:32 INFO - u'7'], 05:16:32 INFO - u'tests': [u'tsvgm']}}, 05:16:32 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 05:16:32 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 05:16:32 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 05:16:32 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'dromaeo_css', 05:16:32 INFO - u'kraken', 05:16:32 INFO - u'v8_7']}, 05:16:32 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 05:16:32 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 05:16:32 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'tests': [u'damp', u'tps']}, 05:16:32 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'damp', u'tps']}, 05:16:32 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 05:16:32 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 05:16:32 INFO - u'other': {u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'other_l64': {u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'other_nol64': {u'tests': [u'a11yr', 05:16:32 INFO - u'ts_paint', 05:16:32 INFO - u'tpaint', 05:16:32 INFO - u'sessionrestore', 05:16:32 INFO - u'sessionrestore_no_auto_restore']}, 05:16:32 INFO - u'svgr': {u'tests': [u'tsvgx', 05:16:32 INFO - u'tsvgr_opacity', 05:16:32 INFO - u'tart', 05:16:32 INFO - u'tscrollx', 05:16:32 INFO - u'cart']}, 05:16:32 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'tsvgx', 05:16:32 INFO - u'tsvgr_opacity', 05:16:32 INFO - u'tart', 05:16:32 INFO - u'tscrollx', 05:16:32 INFO - u'cart']}, 05:16:32 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'tests': [u'tp5o']}, 05:16:32 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'talos_options': [u'--e10s'], 05:16:32 INFO - u'tests': [u'tp5o']}, 05:16:32 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'talos_options': [u'--xperf_path', 05:16:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:16:32 INFO - u'tests': [u'tp5n']}, 05:16:32 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:16:32 INFO - u'talos_options': [u'--e10s', 05:16:32 INFO - u'--xperf_path', 05:16:32 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 05:16:32 INFO - u'C:/slave/talos-data/talos/xperf.config'], 05:16:32 INFO - u'tests': [u'tp5n']}}, 05:16:32 INFO - u'talos.zip': {u'path': u'', 05:16:32 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:16:32 INFO - Running post-action listener: _resource_record_post_action 05:16:32 INFO - ##### 05:16:32 INFO - ##### Running create-virtualenv step. 05:16:32 INFO - ##### 05:16:32 INFO - Running pre-action listener: _resource_record_pre_action 05:16:32 INFO - Running main action method: create_virtualenv 05:16:32 INFO - Creating virtualenv C:\slave\test\build/venv 05:16:32 INFO - mkdir: C:\slave\test\build\venv\Scripts 05:16:32 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 05:16:32 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'! 05:16:32 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 05:16:32 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 05:16:35 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 05:16:39 INFO - Installing distribute......................................................................................................................................................................................done. 05:16:40 INFO - Return code: 0 05:16:40 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 05:16: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')]} 05:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16: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')]} 05:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:40 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:16:40 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 05:16:40 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 05:16:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:16:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:16:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:16:40 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:16:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:16:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:16:40 INFO - 'DCLOCATION': 'SCL3', 05:16:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:16:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:16:40 INFO - 'HOMEDRIVE': 'C:', 05:16:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:16:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:16:40 INFO - 'KTS_VERSION': '1.19c', 05:16:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:16:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:16:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:16:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:40 INFO - 'MOZ_NO_REMOTE': '1', 05:16:40 INFO - 'NO_EM_RESTART': '1', 05:16:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:16:40 INFO - 'OS': 'Windows_NT', 05:16:40 INFO - 'OURDRIVE': 'C:', 05:16:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 05:16:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:16:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:16:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:16:40 INFO - 'PROCESSOR_LEVEL': '6', 05:16:40 INFO - 'PROCESSOR_REVISION': '1e05', 05:16:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:16:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:16:40 INFO - 'PROMPT': '$P$G', 05:16:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:16:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:16:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:16:40 INFO - 'PWD': 'C:\\slave\\test', 05:16:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:16:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:16:40 INFO - 'SYSTEMDRIVE': 'C:', 05:16:40 INFO - 'SYSTEMROOT': 'C:\\windows', 05:16:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:40 INFO - 'TEST1': 'testie', 05:16:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:40 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:16:40 INFO - 'USERNAME': 'cltbld', 05:16:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:16:40 INFO - 'WINDIR': 'C:\\windows', 05:16:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:16:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:16:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:16:41 INFO - Downloading/unpacking pip>=1.5 05:16:41 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 05:16:46 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 05:16:46 INFO - Running setup.py egg_info for package pip 05:16:46 INFO - warning: no files found matching 'pip\cacert.pem' 05:16:46 INFO - warning: no files found matching '*.html' under directory 'docs' 05:16:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 05:16:46 INFO - no previously-included directories found matching 'docs\_build\_sources' 05:16:46 INFO - Installing collected packages: pip 05:16:46 INFO - Found existing installation: pip 0.8.2 05:16:46 INFO - Uninstalling pip: 05:16:46 INFO - Successfully uninstalled pip 05:16:46 INFO - Running setup.py install for pip 05:16:46 INFO - warning: no files found matching 'pip\cacert.pem' 05:16:46 INFO - warning: no files found matching '*.html' under directory 'docs' 05:16:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 05:16:46 INFO - no previously-included directories found matching 'docs\_build\_sources' 05:16:46 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 05:16:46 INFO - Successfully installed pip 05:16:46 INFO - Cleaning up... 05:16:46 INFO - Return code: 0 05:16:46 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 05:16: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')]} 05:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16: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')]} 05:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:46 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:16:46 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 05:16:46 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 05:16:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:16:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:16:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:16:46 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:16:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:16:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:16:46 INFO - 'DCLOCATION': 'SCL3', 05:16:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:16:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:16:46 INFO - 'HOMEDRIVE': 'C:', 05:16:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:16:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:16:46 INFO - 'KTS_VERSION': '1.19c', 05:16:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:16:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:16:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:16:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:46 INFO - 'MOZ_NO_REMOTE': '1', 05:16:46 INFO - 'NO_EM_RESTART': '1', 05:16:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:16:46 INFO - 'OS': 'Windows_NT', 05:16:46 INFO - 'OURDRIVE': 'C:', 05:16:46 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;', 05:16:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:16:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:16:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:16:46 INFO - 'PROCESSOR_LEVEL': '6', 05:16:46 INFO - 'PROCESSOR_REVISION': '1e05', 05:16:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:16:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:16:46 INFO - 'PROMPT': '$P$G', 05:16:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:16:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:16:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:16:46 INFO - 'PWD': 'C:\\slave\\test', 05:16:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:16:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:16:46 INFO - 'SYSTEMDRIVE': 'C:', 05:16:46 INFO - 'SYSTEMROOT': 'C:\\windows', 05:16:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:46 INFO - 'TEST1': 'testie', 05:16:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:46 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:16:46 INFO - 'USERNAME': 'cltbld', 05:16:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:16:46 INFO - 'WINDIR': 'C:\\windows', 05:16:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:16:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:48 INFO - Downloading/unpacking psutil>=0.7.1 05:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:49 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 05:16:49 INFO - Installing collected packages: psutil 05:16:49 INFO - Successfully installed psutil 05:16:49 INFO - Cleaning up... 05:16:50 INFO - Return code: 0 05:16:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 05:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:50 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:16:50 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 05:16:50 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 05:16:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:16:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:16:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:16:50 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:16:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:16:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:16:50 INFO - 'DCLOCATION': 'SCL3', 05:16:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:16:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:16:50 INFO - 'HOMEDRIVE': 'C:', 05:16:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:16:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:16:50 INFO - 'KTS_VERSION': '1.19c', 05:16:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:16:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:16:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:16:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:50 INFO - 'MOZ_NO_REMOTE': '1', 05:16:50 INFO - 'NO_EM_RESTART': '1', 05:16:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:16:50 INFO - 'OS': 'Windows_NT', 05:16:50 INFO - 'OURDRIVE': 'C:', 05:16:50 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;', 05:16:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:16:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:16:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:16:50 INFO - 'PROCESSOR_LEVEL': '6', 05:16:50 INFO - 'PROCESSOR_REVISION': '1e05', 05:16:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:16:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:16:50 INFO - 'PROMPT': '$P$G', 05:16:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:16:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:16:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:16:50 INFO - 'PWD': 'C:\\slave\\test', 05:16:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:16:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:16:50 INFO - 'SYSTEMDRIVE': 'C:', 05:16:50 INFO - 'SYSTEMROOT': 'C:\\windows', 05:16:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:50 INFO - 'TEST1': 'testie', 05:16:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:50 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:16:50 INFO - 'USERNAME': 'cltbld', 05:16:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:16:50 INFO - 'WINDIR': 'C:\\windows', 05:16:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:16:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:16:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:16:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:16:51 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 05:16:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 05:16:51 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) 05:16:51 INFO - Installing collected packages: mozsystemmonitor 05:16:51 INFO - Running setup.py install for mozsystemmonitor 05:16:51 INFO - Successfully installed mozsystemmonitor 05:16:51 INFO - Cleaning up... 05:16:51 INFO - Return code: 0 05:16:51 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 05:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:51 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:16:51 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 05:16:51 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 05:16:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:16:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:16:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:16:51 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:16:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:16:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:16:51 INFO - 'DCLOCATION': 'SCL3', 05:16:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:16:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:16:51 INFO - 'HOMEDRIVE': 'C:', 05:16:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:16:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:16:51 INFO - 'KTS_VERSION': '1.19c', 05:16:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:16:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:16:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:16:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:51 INFO - 'MOZ_NO_REMOTE': '1', 05:16:51 INFO - 'NO_EM_RESTART': '1', 05:16:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:16:51 INFO - 'OS': 'Windows_NT', 05:16:51 INFO - 'OURDRIVE': 'C:', 05:16:51 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;', 05:16:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:16:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:16:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:16:51 INFO - 'PROCESSOR_LEVEL': '6', 05:16:51 INFO - 'PROCESSOR_REVISION': '1e05', 05:16:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:16:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:16:51 INFO - 'PROMPT': '$P$G', 05:16:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:16:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:16:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:16:51 INFO - 'PWD': 'C:\\slave\\test', 05:16:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:16:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:16:51 INFO - 'SYSTEMDRIVE': 'C:', 05:16:51 INFO - 'SYSTEMROOT': 'C:\\windows', 05:16:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:51 INFO - 'TEST1': 'testie', 05:16:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:51 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:16:51 INFO - 'USERNAME': 'cltbld', 05:16:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:16:51 INFO - 'WINDIR': 'C:\\windows', 05:16:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:16:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:16:52 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 05:16:52 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 05:16:53 INFO - Downloading blobuploader-1.2.4.tar.gz 05:16:53 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 05:16:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 05:16:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:16:53 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 05:16:53 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 05:16:53 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 05:16:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 05:16:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:16:53 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 05:16:53 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 05:16:53 INFO - Downloading docopt-0.6.1.tar.gz 05:16:53 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 05:16:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 05:16:54 INFO - Installing collected packages: blobuploader, requests, docopt 05:16:54 INFO - Running setup.py install for blobuploader 05:16:54 INFO - Running setup.py install for requests 05:16:54 INFO - Running setup.py install for docopt 05:16:55 INFO - Successfully installed blobuploader requests docopt 05:16:55 INFO - Cleaning up... 05:16:56 INFO - Return code: 0 05:16:56 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:16:56 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')]} 05:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:16:56 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')]} 05:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:16:56 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:16:56 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 05:16:56 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 05:16:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:16:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:16:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:16:56 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:16:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:16:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:16:56 INFO - 'DCLOCATION': 'SCL3', 05:16:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:16:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:16:56 INFO - 'HOMEDRIVE': 'C:', 05:16:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:16:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:16:56 INFO - 'KTS_VERSION': '1.19c', 05:16:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:16:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:16:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:16:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:56 INFO - 'MOZ_NO_REMOTE': '1', 05:16:56 INFO - 'NO_EM_RESTART': '1', 05:16:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:16:56 INFO - 'OS': 'Windows_NT', 05:16:56 INFO - 'OURDRIVE': 'C:', 05:16:56 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;', 05:16:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:16:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:16:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:16:56 INFO - 'PROCESSOR_LEVEL': '6', 05:16:56 INFO - 'PROCESSOR_REVISION': '1e05', 05:16:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:16:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:16:56 INFO - 'PROMPT': '$P$G', 05:16:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:16:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:16:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:16:56 INFO - 'PWD': 'C:\\slave\\test', 05:16:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:16:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:16:56 INFO - 'SYSTEMDRIVE': 'C:', 05:16:56 INFO - 'SYSTEMROOT': 'C:\\windows', 05:16:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:56 INFO - 'TEST1': 'testie', 05:16:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:16:56 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:16:56 INFO - 'USERNAME': 'cltbld', 05:16:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:16:56 INFO - 'WINDIR': 'C:\\windows', 05:16:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:16:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:16:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 05:16:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wajmqp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 05:16:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 05:16:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tnrvsm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 05:16:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 05:16:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-evsdrb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 05:16:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 05:16:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ccr2f9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 05:16:57 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 05:16:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pqwslp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v70cno-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gdw7lz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8h4sw7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1imt5d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o6wmr2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zzg6jl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tp1ifa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 05:16:58 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 05:16:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yfdxmi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 05:16:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 05:16:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-67oevh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 05:16:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 05:16:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bij3rk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 05:16:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 05:16:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a7637n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 05:16:59 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 05:16:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-95vobw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 05:16:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:16:59 INFO - Running setup.py install for manifestparser 05:16:59 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 05:16:59 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 05:17:00 INFO - Running setup.py install for mozcrash 05:17:00 INFO - Running setup.py install for mozdebug 05:17:00 INFO - Running setup.py install for mozdevice 05:17:01 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 05:17:01 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 05:17:01 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 05:17:01 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 05:17:01 INFO - Running setup.py install for mozfile 05:17:02 INFO - Running setup.py install for mozhttpd 05:17:02 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 05:17:02 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 05:17:02 INFO - Running setup.py install for mozinfo 05:17:02 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 05:17:02 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Running setup.py install for mozInstall 05:17:03 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 05:17:03 INFO - Running setup.py install for mozleak 05:17:03 INFO - Running setup.py install for mozlog 05:17:04 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 05:17:04 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 05:17:04 INFO - Running setup.py install for moznetwork 05:17:04 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 05:17:04 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 05:17:04 INFO - Running setup.py install for mozprocess 05:17:05 INFO - Running setup.py install for mozprofile 05:17:05 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 05:17:05 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 05:17:05 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 05:17:05 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 05:17:05 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 05:17:05 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 05:17:06 INFO - Running setup.py install for mozrunner 05:17:06 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 05:17:06 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 05:17:06 INFO - Running setup.py install for mozscreenshot 05:17:07 INFO - Running setup.py install for moztest 05:17:07 INFO - Running setup.py install for mozversion 05:17:07 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 05:17:07 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 05:17:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:17:07 INFO - Cleaning up... 05:17:07 INFO - Return code: 0 05:17:07 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:17: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')]} 05:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17: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')]} 05:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17: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', '-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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:17:08 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 05:17:08 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 05:17:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:17:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:17:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:17:08 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:17:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:17:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:17:08 INFO - 'DCLOCATION': 'SCL3', 05:17:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:17:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:17:08 INFO - 'HOMEDRIVE': 'C:', 05:17:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:17:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:17:08 INFO - 'KTS_VERSION': '1.19c', 05:17:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:17:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:17:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:17:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:08 INFO - 'MOZ_NO_REMOTE': '1', 05:17:08 INFO - 'NO_EM_RESTART': '1', 05:17:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:17:08 INFO - 'OS': 'Windows_NT', 05:17:08 INFO - 'OURDRIVE': 'C:', 05:17:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 05:17:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:17:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:17:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:17:08 INFO - 'PROCESSOR_LEVEL': '6', 05:17:08 INFO - 'PROCESSOR_REVISION': '1e05', 05:17:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:17:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:17:08 INFO - 'PROMPT': '$P$G', 05:17:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:17:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:17:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:17:08 INFO - 'PWD': 'C:\\slave\\test', 05:17:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:17:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:17:08 INFO - 'SYSTEMDRIVE': 'C:', 05:17:08 INFO - 'SYSTEMROOT': 'C:\\windows', 05:17:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:08 INFO - 'TEST1': 'testie', 05:17:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:08 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:17:08 INFO - 'USERNAME': 'cltbld', 05:17:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:17:08 INFO - 'WINDIR': 'C:\\windows', 05:17:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:17:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 05:17:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b0uuxb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 05:17:08 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)) 05:17:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 05:17:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zs7stn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 05:17:08 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)) 05:17:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 05:17:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-83sfga-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 05:17:08 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)) 05:17:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 05:17:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wzpnvo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 05:17:08 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)) 05:17:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 05:17:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zdtctx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oudxmu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-arsi0p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-asf5ir-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-msre_c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fu_pkh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 05:17:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wzr_3d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 05:17:09 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)) 05:17:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i3mszh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 05:17:10 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)) 05:17:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fj_j0s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 05:17:10 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)) 05:17:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s2nb9k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 05:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 05:17:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lctjz4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 05:17:10 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)) 05:17:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sqjoox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 05:17:10 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)) 05:17:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 05:17:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v04itk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 05:17:10 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)) 05:17:10 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)) 05:17:10 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)) 05:17:10 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)) 05:17:10 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)) 05:17:10 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)) 05:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 05:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:17:11 INFO - Downloading blessings-1.5.1.tar.gz 05:17:11 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 05:17:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 05:17:13 INFO - Installing collected packages: blessings 05:17:13 INFO - Running setup.py install for blessings 05:17:13 INFO - Successfully installed blessings 05:17:13 INFO - Cleaning up... 05:17:16 INFO - Return code: 0 05:17:16 INFO - Done creating virtualenv C:\slave\test\build/venv. 05:17:16 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 05:17:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 05:17:16 INFO - Reading from file tmpfile_stdout 05:17:16 INFO - Using _rmtree_windows ... 05:17:16 INFO - Using _rmtree_windows ... 05:17:16 INFO - Current package versions: 05:17:16 INFO - blessings == 1.5.1 05:17:16 INFO - blobuploader == 1.2.4 05:17:16 INFO - docopt == 0.6.1 05:17:16 INFO - manifestparser == 1.1 05:17:16 INFO - mozInstall == 1.12 05:17:16 INFO - mozcrash == 0.16 05:17:16 INFO - mozdebug == 0.1 05:17:16 INFO - mozdevice == 0.46 05:17:16 INFO - mozfile == 1.2 05:17:16 INFO - mozhttpd == 0.7 05:17:16 INFO - mozinfo == 0.8 05:17:16 INFO - mozleak == 0.1 05:17:16 INFO - mozlog == 3.0 05:17:16 INFO - moznetwork == 0.27 05:17:16 INFO - mozprocess == 0.22 05:17:16 INFO - mozprofile == 0.27 05:17:16 INFO - mozrunner == 6.11 05:17:16 INFO - mozscreenshot == 0.1 05:17:16 INFO - mozsystemmonitor == 0.0 05:17:16 INFO - moztest == 0.7 05:17:16 INFO - mozversion == 1.4 05:17:16 INFO - psutil == 3.1.1 05:17:16 INFO - requests == 1.2.3 05:17:16 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:17:16 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')]} 05:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:17:16 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')]} 05:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:17:16 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 0x01906B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0180B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-078', 'USERDOMAIN': 'T-W732-IX-078', '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-078', '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 05:17:16 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 05:17:16 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 05:17:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:17:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:17:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:17:16 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:17:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:17:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:17:16 INFO - 'DCLOCATION': 'SCL3', 05:17:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:17:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:17:16 INFO - 'HOMEDRIVE': 'C:', 05:17:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:17:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:17:16 INFO - 'KTS_VERSION': '1.19c', 05:17:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:17:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:17:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:17:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:16 INFO - 'MOZ_NO_REMOTE': '1', 05:17:16 INFO - 'NO_EM_RESTART': '1', 05:17:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:17:16 INFO - 'OS': 'Windows_NT', 05:17:16 INFO - 'OURDRIVE': 'C:', 05:17:16 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;', 05:17:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:17:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:17:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:17:16 INFO - 'PROCESSOR_LEVEL': '6', 05:17:16 INFO - 'PROCESSOR_REVISION': '1e05', 05:17:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:17:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:17:16 INFO - 'PROMPT': '$P$G', 05:17:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:17:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:17:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:17:16 INFO - 'PWD': 'C:\\slave\\test', 05:17:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:17:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:17:16 INFO - 'SYSTEMDRIVE': 'C:', 05:17:16 INFO - 'SYSTEMROOT': 'C:\\windows', 05:17:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:16 INFO - 'TEST1': 'testie', 05:17:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:16 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:17:16 INFO - 'USERNAME': 'cltbld', 05:17:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:17:16 INFO - 'WINDIR': 'C:\\windows', 05:17:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:17:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 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)) 05:17:16 INFO - Cleaning up... 05:17:16 INFO - Return code: 0 05:17:16 INFO - Running post-action listener: _resource_record_post_action 05:17:16 INFO - Running post-action listener: _start_resource_monitoring 05:17:16 INFO - Starting resource monitoring. 05:17:17 INFO - ##### 05:17:17 INFO - ##### Running install step. 05:17:17 INFO - ##### 05:17:17 INFO - Running pre-action listener: _resource_record_pre_action 05:17:17 INFO - Running main action method: install 05:17:17 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 05:17:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 05:17:17 INFO - Reading from file tmpfile_stdout 05:17:17 INFO - Using _rmtree_windows ... 05:17:17 INFO - Using _rmtree_windows ... 05:17:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:17:17 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 05:17:17 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 05:17:17 INFO - Reading from file tmpfile_stdout 05:17:17 INFO - Output received: 05:17:17 INFO - Usage: mozinstall-script.py [options] installer 05:17:17 INFO - Options: 05:17:17 INFO - -h, --help show this help message and exit 05:17:17 INFO - -d DEST, --destination=DEST 05:17:17 INFO - Directory to install application into. [default: 05:17:17 INFO - "C:\slave\test"] 05:17:17 INFO - --app=APP Application being installed. [default: firefox] 05:17:17 INFO - Using _rmtree_windows ... 05:17:17 INFO - Using _rmtree_windows ... 05:17:17 INFO - mkdir: C:\slave\test\build\application 05:17:17 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'] 05:17:17 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 05:17:18 INFO - Reading from file tmpfile_stdout 05:17:18 INFO - Output received: 05:17:18 INFO - C:\slave\test\build\application\firefox\firefox.exe 05:17:18 INFO - Using _rmtree_windows ... 05:17:18 INFO - Using _rmtree_windows ... 05:17:18 INFO - Running post-action listener: _resource_record_post_action 05:17:18 INFO - ##### 05:17:18 INFO - ##### Running run-tests step. 05:17:18 INFO - ##### 05:17:18 INFO - Running pre-action listener: _resource_record_pre_action 05:17:18 INFO - Running main action method: run_tests 05:17:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 05:17:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 05:17:18 INFO - Python 2.7.3 05:17:18 INFO - Return code: 0 05:17:18 INFO - grabbing minidump binary from tooltool 05:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:17:18 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 0x012C8B48>, '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 0x012EA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01990660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 05:17:18 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 05:17:18 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 05:17:18 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 05:17:22 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpeu94pg 05:17:22 INFO - INFO - File integrity verified, renaming tmpeu94pg to win32-minidump_stackwalk.exe 05:17:22 INFO - Return code: 0 05:17:22 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 05:17:22 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 05:17:22 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 05:17:22 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 05:17:22 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 05:17:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-078', '--webServer', 'localhost'] in C:\slave\test\build 05:17:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-078 --webServer localhost 05:17:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 05:17:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 05:17:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:17:22 INFO - 'COMPUTERNAME': 'T-W732-IX-078', 05:17:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 05:17:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:17:22 INFO - 'DCLOCATION': 'SCL3', 05:17:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:17:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:17:22 INFO - 'HOMEDRIVE': 'C:', 05:17:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 05:17:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:17:22 INFO - 'KTS_VERSION': '1.19c', 05:17:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 05:17:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-078', 05:17:22 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 05:17:22 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 05:17:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:17:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:17:22 INFO - 'MOZ_NO_REMOTE': '1', 05:17:22 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 05:17:22 INFO - 'NO_EM_RESTART': '1', 05:17:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:17:22 INFO - 'OS': 'Windows_NT', 05:17:22 INFO - 'OURDRIVE': 'C:', 05:17:22 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;', 05:17:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 05:17:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:17:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:17:22 INFO - 'PROCESSOR_LEVEL': '6', 05:17:22 INFO - 'PROCESSOR_REVISION': '1e05', 05:17:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 05:17:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:17:22 INFO - 'PROMPT': '$P$G', 05:17:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:17:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 05:17:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 05:17:22 INFO - 'PWD': 'C:\\slave\\test', 05:17:22 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 05:17:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:17:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:17:22 INFO - 'SYSTEMDRIVE': 'C:', 05:17:22 INFO - 'SYSTEMROOT': 'C:\\windows', 05:17:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:22 INFO - 'TEST1': 'testie', 05:17:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 05:17:22 INFO - 'USERDOMAIN': 'T-W732-IX-078', 05:17:22 INFO - 'USERNAME': 'cltbld', 05:17:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 05:17:22 INFO - 'WINDIR': 'C:\\windows', 05:17:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 05:17:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 05:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:17:22 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-078', '--webServer', 'localhost'] with output_timeout 3600 05:17:23 INFO - mozversion INFO | application_buildid: 20151026024333 05:17:23 INFO - mozversion INFO | application_changeset: 67770106a029c671f6af7a6b9653eedef3ef08aa 05:17:23 INFO - mozversion INFO | application_display_name: Nightly 05:17:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:17:23 INFO - mozversion INFO | application_name: Firefox 05:17:23 INFO - mozversion INFO | application_remotingname: firefox 05:17:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:17:23 INFO - mozversion INFO | application_vendor: Mozilla 05:17:23 INFO - mozversion INFO | application_version: 44.0a1 05:17:23 INFO - mozversion INFO | platform_buildid: 20151026024333 05:17:23 INFO - mozversion INFO | platform_changeset: 67770106a029c671f6af7a6b9653eedef3ef08aa 05:17:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:17:23 INFO - mozversion INFO | platform_version: 44.0a1 05:17:23 INFO - 2015-10-27 05:17:23,338 DEBUG : using testdate: 1445948243 05:17:23 INFO - 2015-10-27 05:17:23,338 DEBUG : actual date: 1445948243 05:17:23 INFO - 2015-10-27 05:17:23,385 INFO : starting webserver on 'localhost:49784' 05:17:23 INFO - 2015-10-27 05:17:23,388 INFO : Starting test suite T-W732-IX-078 05:17:23 INFO - 2015-10-27 05:17:23,388 INFO : Starting test dromaeo_css 05:17:23 INFO - 2015-10-27 05:17:23,388 DEBUG : operating with platform_type : w7_ 05:17:23 INFO - 2015-10-27 05:17:23,388 INFO : Initialising browser for dromaeo_css test... 05:17:23 INFO - 2015-10-27 05:17:23,480 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvvz3e5\profile http://localhost:49784/getInfo.html 05:17:31 INFO - 2015-10-27 05:17:31,634 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:17:31 INFO - 2015-10-27 05:17:31,634 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:17:31 INFO - 2015-10-27 05:17:31,634 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 05:17:31 INFO - 2015-10-27 05:17:31,634 DEBUG : BROWSER_OUTPUT: __metrics 05:17:31 INFO - 2015-10-27 05:17:31,730 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:17:33 INFO - 2015-10-27 05:17:33,743 INFO : Browser initialized. 05:17:33 INFO - 2015-10-27 05:17:33,743 INFO : Running cycle 1/1 for dromaeo_css test... 05:17:33 INFO - 2015-10-27 05:17:33,743 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvvz3e5\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:17:45 INFO - 2015-10-27 05:17:45,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 141774848 05:17:45 INFO - 2015-10-27 05:17:45,385 DEBUG : BROWSER_OUTPUT: 05:18:39 INFO - 2015-10-27 05:18:39,161 DEBUG : BROWSER_OUTPUT: 1445948319185 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] 05:20:49 INFO - 2015-10-27 05:20:49,480 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49784/tests/dromaeo/cssquery-ext.html) 05:20:50 INFO - 2015-10-27 05:20:50,500 DEBUG : BROWSER_OUTPUT: RSS: Main: 179757056 05:20:50 INFO - 2015-10-27 05:20:50,500 DEBUG : BROWSER_OUTPUT: 05:23:53 INFO - 2015-10-27 05:23:53,276 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-ext.html (next: http://localhost:49784/tests/dromaeo/cssquery-jquery.html) 05:23:53 INFO - 2015-10-27 05:23:53,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 201375744 05:23:53 INFO - 2015-10-27 05:23:53,838 DEBUG : BROWSER_OUTPUT: 05:26:56 INFO - 2015-10-27 05:26:56,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49784/tests/dromaeo/cssquery-mootools.html) 05:26:56 INFO - 2015-10-27 05:26:56,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 167309312 05:26:56 INFO - 2015-10-27 05:26:56,836 DEBUG : BROWSER_OUTPUT: 05:29:59 INFO - 2015-10-27 05:29:59,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49784/tests/dromaeo/cssquery-prototype.html) 05:29:59 INFO - 2015-10-27 05:29:59,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 144265216 05:29:59 INFO - 2015-10-27 05:29:59,585 DEBUG : BROWSER_OUTPUT: 05:33:02 INFO - 2015-10-27 05:33:02,632 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49784/tests/dromaeo/cssquery-yui.html) 05:33:03 INFO - 2015-10-27 05:33:03,207 DEBUG : BROWSER_OUTPUT: RSS: Main: 137482240 05:33:03 INFO - 2015-10-27 05:33:03,207 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,282 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/dromaeo/cssquery-yui.html (next: http://localhost:49784/tests/dromaeo/cssquery-dojo.html) 05:36:06 INFO - 2015-10-27 05:36:06,529 DEBUG : BROWSER_OUTPUT: RSS: Main: 193249280 05:36:06 INFO - 2015-10-27 05:36:06,529 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,530 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:36:06 INFO - 2015-10-27 05:36:06,530 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:36:06 INFO - 2015-10-27 05:36:06,530 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:36:06 INFO - 2015-10-27 05:36:06,530 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:36:06 INFO - 2015-10-27 05:36:06,532 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2308.4921317310195;2309.1802410144396;2362.870042147681;2380.726216485104;2380.9166679166233;4187.309093999494;4217.493900731909;4225.450691410117;4291.721038132523;4297.935530967038;4198.097409057053;4201.306784380581;4282.486101667804;4291.5922987316235;4297.355396690498;3602.585702644203;3657.7439579229463;3695.722820788438;3740.5137332146783;3747.86882459114;3717.34946384383;3739.682127019193;3741.5884252732176;3745.681617062553;3747;3718.814059297038;3720.627937206285;3732.234891847165;3733.9066523336996;3755.305268525318;3647.781133131994;3727.1613886875507;3744.04526845654;3750.19370835269;3756.192418629994;3428.0915557377316;3643.362411577963;3669.9633003669887;3722.013666378412;3744.344739670547;4078.857239996586;4161.313383291753;4187.392828039917;4222.577742225783;4228.703990720651;4358.476982762058;4433.224185767478;4465.598096171328;4477.955220447787;4530.932036019462;4275.251830929575;4302.634276086536;4475.641948644134;4527.864164075083;4550.908981820378;3903.941440878389;3950.8345038213797;3973.927039699265;3980.3631418972827;4004.1391100913443;4405;4501.68488205829;4528.909421811545;4582.656300777455;4624.421947256593;4121.52602450716;4180.916381672349;4379.189849877783;4467.73193608378;4477.19410506112;4238.936415953764;4320.200762858881;4402.427684401007;4518.051209246029;4544.522825103382;3900.7074469414906;4089.1412803311637;4146.91706165875;4289.120730250291;4303.8708838734265;4071.89820254496;4152.480939882515;4255.1915136124035;4282.721623094489;4424.579664931826;3071.8617662205256;3105.208171916147;3112.9533057004164;3170.223295292622;3177.2851108500404;2653.2438255097204;2653.3101393637794;2730.4675588260498;2808.255812209766;2850.8859645614357;4086.8569600063856;4210.136921930997;4224.831006759757;4241.34259189826;4252.638525725286;4479.507254202035;4512;4568.543145685471;4571.817127314936;4586.4266966629175;4054.675625949917;4086.8978275542754;4132.421460995463;4137.50349958001;4206.705530612828;3812.390017597171;3854.1713531590844;3854.807259637007;3881.1655494068345;3881.1849511602313;4367.825286988485;4407.647388208871;4566.109608626286;4584.88537786558;4662.114198302312;4270.914581708411;4455.933161002652;4466.865994020184;4475.4181956345465;4579.244424670025;4321;4505.617022552989;4551.36280920678;4593.609543188866;4633.72197668141;4393.714408563433;4409.801558929792;4456.621187198994;4493.77531123436;4496.9100617988115;4110.938335924904;4286.678499112517;4328.567143285646;4418.425604671372;4421.867343979686;4134.359174327984;4296.677749168887;4320.805563749702;4402.933955990727;4426.712263702978;4343.109662519113;4408.779561021872;4419.712718673276;4429.9778501107285;4433.2020236356475;4130.132672138884;4184.895377615584;4208.179405016115;4237.894052648709;4263;4275.123599662;4360.607545320936;4369.650427965692;4390.209762242827;4430.867073987785;4308.159908817751;4323.654107671442;4365.410669559696;4385.342198670225;4401.911961760811 05:36:06 INFO - 2015-10-27 05:36:06,532 DEBUG : BROWSER_OUTPUT: |1;ext.html;481.58343033276225;491.2483899633562;502.02105893507644;513.1789137380191;528.2287859724802;24358.269251922447;36140.277194456125;36320.81839590798;36531.63468365315;36532;37586.624133758654;38402.23195536095;38408.231835363345;38411.61588384115;38425;5863.677497737623;6219.844503887393;7707.730229441971;7899.999999999985;8077.111517733045;10071.34536255141;10305.587776488968;10309.432981186032;10322.690319290396;10327.173826093895;9847.556859941284;9951.800963980717;10100.949495252513;10112.797744045114;10119.342242754197;6187.628742275478;6277.0584412338285;9993.300468967174;10173.135283500911;10403.53184106717;9178.541072946326;9585.904140958572;9608.711738647851;9610.279229057778;9626.51867406634;35659;36009.63990360089;36041.63958360435;36045.279094417965;36114.27771444556;2853.343730941874;2856.1717102040384;2871.06690325645;3105.1257002731304;3372.5447064646205;26161;26726.732732672812;26902.5964610531;27287;27838.443231135265;2327.290176496168;2353.446939969106;2356.3873392917794;2371.8576885386933;2384.141708984764;232699;233817;234361;234395;234396.99999999828;40047;40910;48587;48973.265401019016;52378;50312;50323.496765032614;50400.49599504031;50563.49436505661;50596.74701626542;12432.440540175714;12532.624021279376;12571.371431428392;12586.496540138474;12628;9383.577739001761;9407.341486096044;9456.574454149442;9470.52647367629;9474.431534107976;15879.759679306033;15897.602559936095;16047.277872495766;16052.839471605135;16063.518094457186;6982.93017069833;7064.258252883476;7272.127344718668;7314.926850731531;7405.44459165554;714.332099486971;743.8958545803591;848.5841937450729;877.8507653698887;907.6340108137343;22886.42783930415;25810.483790324088;26035.112619253592;26260.474790504082;26531.734682653314;929.8884133903974;945.1257586732382;952.3761935931975;1019.8572199892021;1041.44282808697;13272.933635331761;13951.58145255644;13969.231692257023;14000.159990400609;14248.786268205986;290107;293762;296500.06999860314;296625;296674;44660.55339446629;44766.77616611896;44836;44923.77538112288;44967.55032449699;1059.2849826367017;1082.4749996251812;1089.6131873185063;1098.555085190499;1100.9174311926638;18875.62248755045;24408;25039.595897016043;25089.24732258035;25477.872610636827;37286.288468076;37462.250754984205;37480.625193747714;37485.4377184337;37488.62511374961;232897;232960;234193;234434;235004;21165.365039048855;22476.582727699493;25387.61918571186;26293.21120366392;26594.734052659227;32875.50686739749;34706.82646586751;34773;34808.82595587107;34993.65006349904;26907.596386054618;28456.85771571129;29331;30518.084457466313;31028.379432410773;24366.390840229134;24615.384615384042;30105.247368815955;31160.376792464478;32222;27169;27221;27240.182794516197;27245;27419.86290068537;25956.351091222114;26411.73588264093;27098.45803083967;27179.59230611582;27898.44203115886;21992.230271940407;22014.559708805413;22031.228906988177;22526.549469010857;22785.772142279027 05:36:06 INFO - 2015-10-27 05:36:06,532 DEBUG : BROWSER_OUTPUT: |2;jquery.html;302.1946512544072;309.09127166131583;311.6960963061014;312.45320688794607;319.7090647510765;15481.303341349638;15641;15912.920435397808;15970.84029159708;16489.34042638296;15309.617259568517;15350.616234594141;15760.054396736205;15951.361945522165;16022.839771602281;4945.293873613616;5030.446650868411;5161.17421212606;5187.507186817246;5189.896202075956;4255.17024180285;4341.044970106572;4347.934780978288;4354.303311470166;5138.537531622153;2973.2715484706277;2986.5072263076568;4284.914301713964;4289.656827453796;6159.568830181889;6297.559170858041;6316.273628532732;6317.147185129995;6340.999999999977;6357.205349331333;5747.051736463479;5763.9135412968835;5833.220867635369;5995.100734889757;6008.068749343859;15325;15909.124998125099;15981.36074557028;19006.904965475223;19686.80313196864;1799.3252530301138;1869.336385583112;1907.9696963639617;1928.7396201512756;1969.3304276546044;12278.754424911542;12404.13171078025;12501.624951251477;12561.811572826415;12910.677233069155;1608.9034657920563;1652.5372895589255;1685.1489997551157;1753.2723919573361;1797.5146710388121;60727.69636151835;63743.362566374206;65677.3432265676;66473.66763166201;66631.66684166549;14330.49843255481;14352.928235358966;14366.784498232535;14394.20831854258;14442.277886105652;20448;20450;20456.69314960277;20499.28252511155;20513.589728205352;8084.919150808534;8093.1502192270145;8102.230288122619;8148.144444833326;8175.550344731097;6871.14110736158;6914.861702765917;6938;6965.895511567331;6970.721171153198;6558.704858281389;6811.727530898807;6894.276101009322;6915.965420172867;6938.757143499954;2888.653361596622;2896.681365049843;2938.2654336415894;2944.528875379929;2999.9100026999226;321.6204878243695;360.1428599932145;386.68292000559836;387.3917948820325;402.3341370032601;8398.244158025807;10353.534090965924;10830;10873.401962892116;10946.562137514411;377.97921114338976;385.09502668728265;400.63542176619137;404.9453323801249;418.15323968962485;4738.64460165489;5453.400125986138;5712.6286791358425;5744.798932037359;5866.618669786536;285481;287346;304334;305075;308893;60038.39961600416;60647.09029364563;62587.11541730406;63055.369446305864;67256.32743672599;417.9582041795796;438.5504857521066;439.86144364525074;440.5000324631502;440.70032377982636;9832.410055396698;9892.901070989197;9897.604095836086;9908.504574771378;9912.851307230254;33378;33505.83247083749;34029.14927126842;34589.65410345864;36088.81955590205;42033.159336813704;43234.56765432305;43587.78206108949;43994.78002609967;44808;5506.614536982373;5528.723563821713;5565.666060036411;5650;5765.365809761007;10577.524011419351;10609;10980.945095274472;11167.329960202414;11246.931541503543;9337.486438245962;9338.813223735624;9528.142467177988;9565.617375305188;9618.230541556519;9104.317176211678;9277.814443710953;9317.534123293673;9346.11211934865;9789.31474796786;7657.425693072931;8337.541435221121;9146.31402644791;9673.661421850202;9674.564644591152;5663.348714897839;5668.178114173505;5689.943100568941;5689.971550142222;5876.911846322223;9837.950810245902;9870.161036311994;9898;9914.950425247827;9918.305718599917 05:36:06 INFO - 2015-10-27 05:36:06,535 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1132.6828488023357;1281.6795801049734;1352;1474.871723131804;1616.773651688763;6596.835079123019;7535.472516923817;7677.500962437438;7693.999999999986;7715.72994945177;7754.1470438251745;7799.766007019784;7879.645415956282;7891.013623297088;7908.683652653901;1894.3180455036177;2011.3664195778356;2034.5523984723336;2034.8880811555432;2035.328341647253;2085.593309304686;2103.663413853784;2119.2370746531237;2134.6691262854283;2141.5395689926663;2002.9699554506622;2065.8037486438766;2074.543600407915;2076.522399848036;2077.3248694174376;2013.657678194703;2129.137699231806;2132.2430537159394;2133.9039743211592;2137.9893100534396;1979.8515111366562;1982.8512861535298;2011.406635042666;2017.798220177985;2020.232311721551;6858.074159988388;6903.651728911474;7710.421718371144;7719.575423351714;7738.574378409186;4217.430646862665;4478.619317357996;4509.368688383629;4604.907901841944;4605.240135377659;2776.1532732516575;2809.6207012053314;2858.371158345161;2891.334992951633;2894.8552572371295;865.3336930563494;903.9231665308391;906.1889608800153;906.9501177435238;913.0413066280379;10875.347479151276;10935.234533582652;10978.231523793338;10985.395803230818;11012.779744405147;4130.690198235085;4164.208800327928;4277.465316835395;4289.120730250291;4290.806913688829;9195.678151264674;9276.443413395218;9286.860697089549;9289.34974551775;9295.070492950787;4469.597736203692;4484.75333856641;4488.798004089824;4532.79602417892;4534.8639540813165;3993.7803420812133;3999.24014437256;4016.4577781999055;4016.9397459038137;4021.3364794809113;3371.7302615790677;3377.054424761071;3414.607320158164;3424.366492198901;3451.1027132945615;1084.5336505302637;1099.8185299425666;1106.2919731371928;1112.8108221602185;1113.8440618313443;1089.7711480589007;1110.539126262592;1128.3060917535656;1129.7006293332274;1130.1862658885589;7162.283771622796;7278.526895751865;7313.414926805949;7316.707331706779;7318;422.1451560589769;438.1959105042283;439.9494058183286;441.1001556824043;448.5738548379053;5661.688607126647;5719.370869204384;5729.312482502127;5730.914036289459;5732.6847023413275;8574.485530868043;8682.131786821394;9499;9542;9575.377600455948;9450.57472413743;9475.384100033476;9493.525323733786;9530.95234523823;9535.761605959768;6836.384725374641;6865.588064716133;6909.481788865855;6910.170779506491;6914.861702765917;6637.502187335873;6734.730610775514;6786.830329241809;7038.542494737826;7044.366007059184;8258;8270.875936861072;8285.25432711059;8319;8346.499210047417;7940.682372705258;7995.240452157036;8390.580470976549;8653.35099867525;8670.696525621825;6808.761693340709;6843.965780171067;6849.554778939353;6868.347506986828;6875.931240687529;7670.156282808998;7679.424043196895;7699.076110866732;7729.1884352142215;7740.845183096194;7043.225245222919;7249.565026098451;7264.89102663471;7265.491415600857;7320.8535829282055;6803.965980170067;6949.75675851363;7073.787786366417;7147.1780745214965;7159.319864612853;6770.510487692896;6964.825879353057;7013.2986701329455;7223.386012189023;7233.023541821787;6043;6286.402791734778;6364.6181229126405;6412.903806443001;6452.193475815523;6068.423499767515;6138.171346868295;6522.771702990372;6543.770968016097;6546 05:36:06 INFO - 2015-10-27 05:36:06,559 DEBUG : BROWSER_OUTPUT: |4;prototype.html;288.5628273166153;291.58012462054626;295.1014161877084;296.91389497045856;297.1679297965695;4638.698484598503;4642.141203877277;4714.882127946798;4721.976390118053;4755.358026666399;4484.260097083986;4500.144972455231;4599.494055653875;4654.557817007388;4668.416447944007;3546.822658867059;3558.964410355902;3559.1458050067927;3560.5727312722443;3561.5904171020275;3381.374445727536;3454.4990976308422;3481.669241422061;3561.130406536576;3561.732870034745;3501.2647344057777;3528.700060494861;3531.0819187011434;3532.682058614724;3535.2575959048504;3505.5968563615;3515.437529995214;3529.0647978285774;3530.5939816920995;3540.309639620275;2959.3193565479846;2968.2430980100153;3047.283888286251;3108;3148.480500717379;4555.248384016633;4555.407796986404;4556.567126123046;4558.111168322179;4558.8632341029825;4180.414741936101;4193.643540299078;4197.874063778091;4200.096979149497;4203.033302340448;4568.223402021665;4911.042346742387;4955.083309587738;4973.825916092919;4989.101961646938;3551.9112022199392;3556.4309710446464;3560.2167523144867;3571.39286321326;3580.2660454606744;5170.1986192140275;5173.9741301293625;5174.327337446108;5185.325907631984;5187.429382767855;3514.9824250878582;3545.3972824619877;3616.2044350242904;3654.9948764089554;3659.963400365962;3514.191735900757;3581.606023337431;3674.430463278196;3706.703463722896;3707.9073023174637;4174.937375939363;4174.958250417457;4177.498700156001;4183.49798024239;4184.28867092595;4902.313676085351;4921.9507804922205;4927.605791536668;4928.802847886044;4941.085899108676;1286.7748144074749;1322.418136020148;1337.4382759241141;1339.437436276766;1340.631326385252;864.6757465950269;931.4318265857821;941.1200527506803;942.8491441369348;943.2218419803689;4705.576498115186;4709.529047095263;4715.316279139505;4716.905661886812;4717.315989181549;5134.691918484903;5141.871453213625;5146.4853514648985;5148.124818780741;5150.742462876841;4419.248727716232;4427.269500532376;4489.877530617346;4581.404417425713;4696.976515117402;2777.8472184030074;2810.339570201001;2814.521531339677;2817.0844475545528;2820.0975687780124;4760.714357138513;4950.851474455772;5187;5237.109691352403;5239.607029472805;4924.039812236651;4967.180415231519;5054.16606259963;5200.76596553156;5246.213068039825;5234.63357564982;5236;5237.6071794615555;5247.134222853263;5248.763805628756;5092.032513822362;5128.230765385222;5141.126008578625;5144.691318520751;5152.355955505562;4943.604511638989;5009.924851127309;5019.874503137305;5021.171506701354;5028.899422011613;5097.974510127426;5223.981323641853;5226.320578324793;5233.476652334736;5241.554467870121;3367;3399.983000084984;3589.8384572694813;3590.335787879251;3591.317649646559;4983.377077865267;5057.089723849743;5093.261477085876;5128.282040514406;5132.384113906355;4855.2717092435005;4932.556069953722;4945.381827271591;4946.975265123651;4947.109520286383;4656.67403281767;4708.916949101657;4741.762911854463;4784.497627749175;4928.704277743347;4791.448983366958;4795.616350692006;4826.541478559531;4829.154897892784;4833.540813622701;4766.356541866804;4963.379577552806;4972.403311602486;4979.203327467588;4982.526659967298;4711.882202944954;4815;4890.095332363524;4905.264210368473;4907.509249075207 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: |5;yui.html;641.9760482031161;681.8295426143466;721.4985585018414;724.9673764680589;724.9710011599536;10868.99999999999;11427;11516.481758320875;11538.596149134783;11596.768064638713;10619;10639.893601063986;10686.679399618004;10689.893101068987;10710.571577136905;598.2701104652328;612.6569121292091;616.1158737212115;617.7899514165183;619.1053927075378;614.1402037148006;615.8552740106094;618.1994317359008;618.6195489773802;618.6690120785381;630.2185290240092;638.6902352359102;639.3670266436218;640.0431355124095;640.5900223856736;615.5137441421272;635.520182262389;635.6345101566594;636.2079211381836;636.3795299582893;436.9978150109261;460.01786186491745;460.7627072057893;461.58457388350416;463.45312531213153;10763.78472430547;11019.504122314514;11033.227674062739;11043.226974111816;11068.833967490495;8454.08334124726;8594.742157735278;8624.353173511949;8943.418677785923;8986.820263594691;8684.744475207166;8688.609012594385;8698.39111262212;8701.17338852808;9050.27597792175;2688.314479807656;2734.3164208947765;2758.558630619111;2795.24528377337;2836.1916853696803;26429.04106313882;27075.72924270752;32236.6776332236;33408.3318333632;33834.15414614605;6288.591241569284;6313.873722525524;6696.5647232929705;6700.564463309869;6757.831054223586;9068.954655226682;9198.586063627154;9548.283878709124;9649.421034737938;9707.320487565803;5298.761555729924;5492.80775172867;5565.220869078333;5649.209110724501;5665.603407761417;3305.652906444837;3380.6112297085656;3382.52644629752;3490.3193877194212;3531.3113942781424;2176.8040876321206;2200.3288996856113;2201.1085510368325;2213.1700612270397;2214.6235140026233;381.4126245581828;386.8085297777618;387.2410076250524;430.8255156661557;445.11866504321625;558.4694540186839;564.7769131193156;565.6634302589953;571.7370009795458;576.9278840144982;7316.487845850846;7412.481126321213;7504.962475187589;7726.420518461138;7734.922650773533;545.6835035679296;593.474774824275;596.9044952807529;617.3918690090258;618.9845253868689;3483.7561370703806;3579.319929213441;3589.8743543975834;3723.106454450912;3734.2531493700826;42877.78561107175;43031.56968430338;45150.54849451529;45167.322490162675;45494.77252613782;30584.847075764923;34293.314133717184;35263;35280.647193528246;35312;26180.34549136249;29237.391943443316;31097.68902310948;31168;31419.371612567164;6996.755113571001;7313.744018959311;7345.265473452612;7447.851042979001;7460.664270107966;21079.8946005269;23138.07447702073;23496.295111146694;23534.17630382997;23594.410139745956;28560.285992849513;28637.28406789847;32076.5188522177;32168.03495895127;32594.51108233426;6612.801615951529;6616.834579135368;6717.630530320879;6785.694643740945;6895.931040689529;10193.184545236216;10437.582496700046;10609.310394824312;10633.946830265799;11146.554137834395;9919.305648604817;10229.232807539316;10246.6413675521;10325.225608079274;10627.309224900357;9643.324967252505;9809.607615695293;10192.623995760478;10193.74515637115;10472.005159509834;9287.349885507949;9485.620575177192;9605.759856003377;10103.343282686601;10118.494075296352;8287.668493260444;8455.408121431687;8469.166021430276;8594.31245500371;8892.555372231232;8314.210150035737;8322.583870806315;8380.622871970654;8433.704820331259;8609.87085193735 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,832 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: __startTimestamp1445949366557__endTimestamp 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,562 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4076.63 Median:4241.34 stddev:514.52 (12.1%) stddev-sans-first:497.05 05:36:06 INFO - 2015-10-27 05:36:06,563 DEBUG : BROWSER_OUTPUT: Values: 2308.5 2309.2 2362.9 2380.7 2380.9 4187.3 4217.5 4225.5 4291.7 4297.9 4198.1 4201.3 4282.5 4291.6 4297.4 3602.6 3657.7 3695.7 3740.5 3747.9 3717.3 3739.7 3741.6 3745.7 3747.0 3718.8 3720.6 3732.2 3733.9 3755.3 3647.8 3727.2 3744.0 3750.2 3756.2 3428.1 3643.4 3670.0 3722.0 3744.3 4078.9 4161.3 4187.4 4222.6 4228.7 4358.5 4433.2 4465.6 4478.0 4530.9 4275.3 4302.6 4475.6 4527.9 4550.9 3903.9 3950.8 3973.9 3980.4 4004.1 4405.0 4501.7 4528.9 4582.7 4624.4 4121.5 4180.9 4379.2 4467.7 4477.2 4238.9 4320.2 4402.4 4518.1 4544.5 3900.7 4089.1 4146.9 4289.1 4303.9 4071.9 4152.5 4255.2 4282.7 4424.6 3071.9 3105.2 3113.0 3170.2 3177.3 2653.2 2653.3 2730.5 2808.3 2850.9 4086.9 4210.1 4224.8 4241.3 4252.6 4479.5 4512.0 4568.5 4571.8 4586.4 4054.7 4086.9 4132.4 4137.5 4206.7 3812.4 3854.2 3854.8 3881.2 3881.2 4367.8 4407.6 4566.1 4584.9 4662.1 4270.9 4455.9 4466.9 4475.4 4579.2 4321.0 4505.6 4551.4 4593.6 4633.7 4393.7 4409.8 4456.6 4493.8 4496.9 4110.9 4286.7 4328.6 4418.4 4421.9 4134.4 4296.7 4320.8 4402.9 4426.7 4343.1 4408.8 4419.7 4430.0 4433.2 4130.1 4184.9 4208.2 4237.9 4263.0 4275.1 4360.6 4369.7 4390.2 4430.9 4308.2 4323.7 4365.4 4385.3 4401.9 05:36:06 INFO - 2015-10-27 05:36:06,572 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,572 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39786.64 Median:159719.00 stddev:66840.37 (41.8%) stddev-sans-first:66962.73 05:36:06 INFO - 2015-10-27 05:36:06,572 DEBUG : BROWSER_OUTPUT: Values: 481.6 491.2 502.0 513.2 528.2 24358.3 36140.3 36320.8 36531.6 36532.0 37586.6 38402.2 38408.2 38411.6 38425.0 5863.7 6219.8 7707.7 7900.0 8077.1 10071.3 10305.6 10309.4 10322.7 10327.2 9847.6 9951.8 10100.9 10112.8 10119.3 6187.6 6277.1 9993.3 10173.1 10403.5 9178.5 9585.9 9608.7 9610.3 9626.5 35659.0 36009.6 36041.6 36045.3 36114.3 2853.3 2856.2 2871.1 3105.1 3372.5 26161.0 26726.7 26902.6 27287.0 27838.4 2327.3 2353.4 2356.4 2371.9 2384.1 232699.0 233817.0 234361.0 234395.0 234397.0 40047.0 40910.0 48587.0 48973.3 52378.0 50312.0 50323.5 50400.5 50563.5 50596.7 12432.4 12532.6 12571.4 12586.5 12628.0 9383.6 9407.3 9456.6 9470.5 9474.4 15879.8 15897.6 16047.3 16052.8 16063.5 6982.9 7064.3 7272.1 7314.9 7405.4 714.3 743.9 848.6 877.9 907.6 22886.4 25810.5 26035.1 26260.5 26531.7 929.9 945.1 952.4 1019.9 1041.4 13272.9 13951.6 13969.2 14000.2 14248.8 290107.0 293762.0 296500.1 296625.0 296674.0 44660.6 44766.8 44836.0 44923.8 44967.6 1059.3 1082.5 1089.6 1098.6 1100.9 18875.6 24408.0 25039.6 25089.2 25477.9 37286.3 37462.3 37480.6 37485.4 37488.6 232897.0 232960.0 234193.0 234434.0 235004.0 21165.4 22476.6 25387.6 26293.2 26594.7 32875.5 34706.8 34773.0 34808.8 34993.7 26907.6 28456.9 29331.0 30518.1 31028.4 24366.4 24615.4 30105.2 31160.4 32222.0 27169.0 27221.0 27240.2 27245.0 27419.9 25956.4 26411.7 27098.5 27179.6 27898.4 21992.2 22014.6 22031.2 22526.5 22785.8 05:36:06 INFO - 2015-10-27 05:36:06,572 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,573 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:20582.51 Median:22013.17 stddev:49480.97 (224.8%) stddev-sans-first:49596.35 05:36:06 INFO - 2015-10-27 05:36:06,576 DEBUG : BROWSER_OUTPUT: Values: 302.2 309.1 311.7 312.5 319.7 15481.3 15641.0 15912.9 15970.8 16489.3 15309.6 15350.6 15760.1 15951.4 16022.8 4945.3 5030.4 5161.2 5187.5 5189.9 4255.2 4341.0 4347.9 4354.3 5138.5 2973.3 2986.5 4284.9 4289.7 6159.6 6297.6 6316.3 6317.1 6341.0 6357.2 5747.1 5763.9 5833.2 5995.1 6008.1 15325.0 15909.1 15981.4 19006.9 19686.8 1799.3 1869.3 1908.0 1928.7 1969.3 12278.8 12404.1 12501.6 12561.8 12910.7 1608.9 1652.5 1685.1 1753.3 1797.5 60727.7 63743.4 65677.3 66473.7 66631.7 14330.5 14352.9 14366.8 14394.2 14442.3 20448.0 20450.0 20456.7 20499.3 20513.6 8084.9 8093.2 8102.2 8148.1 8175.6 6871.1 6914.9 6938.0 6965.9 6970.7 6558.7 6811.7 6894.3 6916.0 6938.8 2888.7 2896.7 2938.3 2944.5 2999.9 321.6 360.1 386.7 387.4 402.3 8398.2 10353.5 10830.0 10873.4 10946.6 378.0 385.1 400.6 404.9 418.2 4738.6 5453.4 5712.6 5744.8 5866.6 285481.0 287346.0 304334.0 305075.0 308893.0 60038.4 60647.1 62587.1 63055.4 67256.3 418.0 438.6 439.9 440.5 440.7 9832.4 9892.9 9897.6 9908.5 9912.9 33378.0 33505.8 34029.1 34589.7 36088.8 42033.2 43234.6 43587.8 43994.8 44808.0 5506.6 5528.7 5565.7 5650.0 5765.4 10577.5 10609.0 10980.9 11167.3 11246.9 9337.5 9338.8 9528.1 9565.6 9618.2 9104.3 9277.8 9317.5 9346.1 9789.3 7657.4 8337.5 9146.3 9673.7 9674.6 5663.3 5668.2 5689.9 5690.0 5876.9 9838.0 9870.2 9898.0 9915.0 9918.3 05:36:06 INFO - 2015-10-27 05:36:06,581 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,581 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5337.59 Median:6432.55 stddev:2912.59 (45.3%) stddev-sans-first:2903.61 05:36:06 INFO - 2015-10-27 05:36:06,581 DEBUG : BROWSER_OUTPUT: Values: 1132.7 1281.7 1352.0 1474.9 1616.8 6596.8 7535.5 7677.5 7694.0 7715.7 7754.1 7799.8 7879.6 7891.0 7908.7 1894.3 2011.4 2034.6 2034.9 2035.3 2085.6 2103.7 2119.2 2134.7 2141.5 2003.0 2065.8 2074.5 2076.5 2077.3 2013.7 2129.1 2132.2 2133.9 2138.0 1979.9 1982.9 2011.4 2017.8 2020.2 6858.1 6903.7 7710.4 7719.6 7738.6 4217.4 4478.6 4509.4 4604.9 4605.2 2776.2 2809.6 2858.4 2891.3 2894.9 865.3 903.9 906.2 907.0 913.0 10875.3 10935.2 10978.2 10985.4 11012.8 4130.7 4164.2 4277.5 4289.1 4290.8 9195.7 9276.4 9286.9 9289.3 9295.1 4469.6 4484.8 4488.8 4532.8 4534.9 3993.8 3999.2 4016.5 4016.9 4021.3 3371.7 3377.1 3414.6 3424.4 3451.1 1084.5 1099.8 1106.3 1112.8 1113.8 1089.8 1110.5 1128.3 1129.7 1130.2 7162.3 7278.5 7313.4 7316.7 7318.0 422.1 438.2 439.9 441.1 448.6 5661.7 5719.4 5729.3 5730.9 5732.7 8574.5 8682.1 9499.0 9542.0 9575.4 9450.6 9475.4 9493.5 9531.0 9535.8 6836.4 6865.6 6909.5 6910.2 6914.9 6637.5 6734.7 6786.8 7038.5 7044.4 8258.0 8270.9 8285.3 8319.0 8346.5 7940.7 7995.2 8390.6 8653.4 8670.7 6808.8 6844.0 6849.6 6868.3 6875.9 7670.2 7679.4 7699.1 7729.2 7740.8 7043.2 7249.6 7264.9 7265.5 7320.9 6804.0 6949.8 7073.8 7147.2 7159.3 6770.5 6964.8 7013.3 7223.4 7233.0 6043.0 6286.4 6364.6 6412.9 6452.2 6068.4 6138.2 6522.8 6543.8 6546.0 05:36:06 INFO - 2015-10-27 05:36:06,581 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,582 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4124.10 Median:4709.22 stddev:1213.04 (25.8%) stddev-sans-first:1181.79 05:36:06 INFO - 2015-10-27 05:36:06,582 DEBUG : BROWSER_OUTPUT: Values: 288.6 291.6 295.1 296.9 297.2 4638.7 4642.1 4714.9 4722.0 4755.4 4484.3 4500.1 4599.5 4654.6 4668.4 3546.8 3559.0 3559.1 3560.6 3561.6 3381.4 3454.5 3481.7 3561.1 3561.7 3501.3 3528.7 3531.1 3532.7 3535.3 3505.6 3515.4 3529.1 3530.6 3540.3 2959.3 2968.2 3047.3 3108.0 3148.5 4555.2 4555.4 4556.6 4558.1 4558.9 4180.4 4193.6 4197.9 4200.1 4203.0 4568.2 4911.0 4955.1 4973.8 4989.1 3551.9 3556.4 3560.2 3571.4 3580.3 5170.2 5174.0 5174.3 5185.3 5187.4 3515.0 3545.4 3616.2 3655.0 3660.0 3514.2 3581.6 3674.4 3706.7 3707.9 4174.9 4175.0 4177.5 4183.5 4184.3 4902.3 4922.0 4927.6 4928.8 4941.1 1286.8 1322.4 1337.4 1339.4 1340.6 864.7 931.4 941.1 942.8 943.2 4705.6 4709.5 4715.3 4716.9 4717.3 5134.7 5141.9 5146.5 5148.1 5150.7 4419.2 4427.3 4489.9 4581.4 4697.0 2777.8 2810.3 2814.5 2817.1 2820.1 4760.7 4950.9 5187.0 5237.1 5239.6 4924.0 4967.2 5054.2 5200.8 5246.2 5234.6 5236.0 5237.6 5247.1 5248.8 5092.0 5128.2 5141.1 5144.7 5152.4 4943.6 5009.9 5019.9 5021.2 5028.9 5098.0 5224.0 5226.3 5233.5 5241.6 3367.0 3400.0 3589.8 3590.3 3591.3 4983.4 5057.1 5093.3 5128.3 5132.4 4855.3 4932.6 4945.4 4947.0 4947.1 4656.7 4708.9 4741.8 4784.5 4928.7 4791.4 4795.6 4826.5 4829.2 4833.5 4766.4 4963.4 4972.4 4979.2 4982.5 4711.9 4815.0 4890.1 4905.3 4907.5 05:36:06 INFO - 2015-10-27 05:36:06,582 DEBUG : BROWSER_OUTPUT: 05:36:06 INFO - 2015-10-27 05:36:06,582 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10031.88 Median:3019.25 stddev:10918.85 (361.6%) stddev-sans-first:10926.71 05:36:06 INFO - 2015-10-27 05:36:06,582 DEBUG : BROWSER_OUTPUT: Values: 642.0 681.8 721.5 725.0 725.0 10869.0 11427.0 11516.5 11538.6 11596.8 10619.0 10639.9 10686.7 10689.9 10710.6 598.3 612.7 616.1 617.8 619.1 614.1 615.9 618.2 618.6 618.7 630.2 638.7 639.4 640.0 640.6 615.5 635.5 635.6 636.2 636.4 437.0 460.0 460.8 461.6 463.5 10763.8 11019.5 11033.2 11043.2 11068.8 8454.1 8594.7 8624.4 8943.4 8986.8 8684.7 8688.6 8698.4 8701.2 9050.3 2688.3 2734.3 2758.6 2795.2 2836.2 26429.0 27075.7 32236.7 33408.3 33834.2 6288.6 6313.9 6696.6 6700.6 6757.8 9069.0 9198.6 9548.3 9649.4 9707.3 5298.8 5492.8 5565.2 5649.2 5665.6 3305.7 3380.6 3382.5 3490.3 3531.3 2176.8 2200.3 2201.1 2213.2 2214.6 381.4 386.8 387.2 430.8 445.1 558.5 564.8 565.7 571.7 576.9 7316.5 7412.5 7505.0 7726.4 7734.9 545.7 593.5 596.9 617.4 619.0 3483.8 3579.3 3589.9 3723.1 3734.3 42877.8 43031.6 45150.5 45167.3 45494.8 30584.8 34293.3 35263.0 35280.6 35312.0 26180.3 29237.4 31097.7 31168.0 31419.4 6996.8 7313.7 7345.3 7447.9 7460.7 21079.9 23138.1 23496.3 23534.2 23594.4 28560.3 28637.3 32076.5 32168.0 32594.5 6612.8 6616.8 6717.6 6785.7 6895.9 10193.2 10437.6 10609.3 10633.9 11146.6 9919.3 10229.2 10246.6 10325.2 10627.3 9643.3 9809.6 10192.6 10193.7 10472.0 9287.3 9485.6 9605.8 10103.3 10118.5 8287.7 8455.4 8469.2 8594.3 8892.6 8314.2 8322.6 8380.6 8433.7 8609.9 05:36:06 INFO - 2015-10-27 05:36:06,591 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:36:06 INFO - 2015-10-27 05:36:06,591 DEBUG : BROWSER_OUTPUT: 05:36:07 INFO - 2015-10-27 05:36:07,111 INFO : Browser exited with error code: 0 05:36:07 INFO - 2015-10-27 05:36:07,901 INFO : Completed test dromaeo_css (00:18:44) 05:36:07 INFO - 2015-10-27 05:36:07,901 INFO : Starting test kraken 05:36:07 INFO - 2015-10-27 05:36:07,901 DEBUG : operating with platform_type : w7_ 05:36:07 INFO - 2015-10-27 05:36:07,901 INFO : Initialising browser for kraken test... 05:36:07 INFO - 2015-10-27 05:36:07,927 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkf6dwg\profile http://localhost:49784/getInfo.html 05:36:13 INFO - 2015-10-27 05:36:13,118 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:36:13 INFO - 2015-10-27 05:36:13,118 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:36:13 INFO - 2015-10-27 05:36:13,118 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 05:36:13 INFO - 2015-10-27 05:36:13,118 DEBUG : BROWSER_OUTPUT: __metrics 05:36:13 INFO - 2015-10-27 05:36:13,226 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:36:14 INFO - 2015-10-27 05:36:14,292 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. 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: console.error: 05:36:14 INFO - 2015-10-27 05:36:14,487 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] 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: Stack: 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:36:14 INFO - 2015-10-27 05:36:14,487 DEBUG : BROWSER_OUTPUT: setAncestorsLastModified<@resource://gre/modules/Bookmarks.jsm:1347:9 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:808:13 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:934:23 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 05:36:14 INFO - 2015-10-27 05:36:14,489 DEBUG : BROWSER_OUTPUT: 05:36:15 INFO - 2015-10-27 05:36:15,098 INFO : Browser initialized. 05:36:15 INFO - 2015-10-27 05:36:15,098 INFO : Running cycle 1/1 for kraken test... 05:36:15 INFO - 2015-10-27 05:36:15,098 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpkf6dwg\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:36:26 INFO - 2015-10-27 05:36:26,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 139116544 05:36:26 INFO - 2015-10-27 05:36:26,808 DEBUG : BROWSER_OUTPUT: 05:36:29 INFO - 2015-10-27 05:36:29,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49784/tests/kraken/driver.html?testName=audio-beat-detection) 05:36:29 INFO - 2015-10-27 05:36:29,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 172920832 05:36:29 INFO - 2015-10-27 05:36:29,923 DEBUG : BROWSER_OUTPUT: 05:36:32 INFO - 2015-10-27 05:36:32,786 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49784/tests/kraken/driver.html?testName=audio-dft) 05:36:33 INFO - 2015-10-27 05:36:33,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 175439872 05:36:33 INFO - 2015-10-27 05:36:33,361 DEBUG : BROWSER_OUTPUT: 05:36:36 INFO - 2015-10-27 05:36:36,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49784/tests/kraken/driver.html?testName=audio-fft) 05:36:36 INFO - 2015-10-27 05:36:36,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 170917888 05:36:36 INFO - 2015-10-27 05:36:36,756 DEBUG : BROWSER_OUTPUT: 05:36:38 INFO - 2015-10-27 05:36:38,891 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49784/tests/kraken/driver.html?testName=audio-oscillator) 05:36:39 INFO - 2015-10-27 05:36:39,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 165908480 05:36:39 INFO - 2015-10-27 05:36:39,457 DEBUG : BROWSER_OUTPUT: 05:36:41 INFO - 2015-10-27 05:36:41,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49784/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:36:42 INFO - 2015-10-27 05:36:42,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 165371904 05:36:42 INFO - 2015-10-27 05:36:42,361 DEBUG : BROWSER_OUTPUT: 05:36:45 INFO - 2015-10-27 05:36:45,601 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49784/tests/kraken/driver.html?testName=imaging-darkroom) 05:36:46 INFO - 2015-10-27 05:36:46,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 162533376 05:36:46 INFO - 2015-10-27 05:36:46,184 DEBUG : BROWSER_OUTPUT: 05:36:49 INFO - 2015-10-27 05:36:49,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49784/tests/kraken/driver.html?testName=imaging-desaturate) 05:36:50 INFO - 2015-10-27 05:36:50,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 161042432 05:36:50 INFO - 2015-10-27 05:36:50,305 DEBUG : BROWSER_OUTPUT: 05:36:53 INFO - 2015-10-27 05:36:53,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49784/tests/kraken/driver.html?testName=json-parse-financial) 05:36:53 INFO - 2015-10-27 05:36:53,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 162471936 05:36:53 INFO - 2015-10-27 05:36:53,880 DEBUG : BROWSER_OUTPUT: 05:36:55 INFO - 2015-10-27 05:36:55,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49784/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:36:56 INFO - 2015-10-27 05:36:56,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 191168512 05:36:56 INFO - 2015-10-27 05:36:56,387 DEBUG : BROWSER_OUTPUT: 05:36:58 INFO - 2015-10-27 05:36:58,453 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:36:59 INFO - 2015-10-27 05:36:59,030 DEBUG : BROWSER_OUTPUT: RSS: Main: 196198400 05:36:59 INFO - 2015-10-27 05:36:59,030 DEBUG : BROWSER_OUTPUT: 05:37:01 INFO - 2015-10-27 05:37:01,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:37:02 INFO - 2015-10-27 05:37:02,180 DEBUG : BROWSER_OUTPUT: RSS: Main: 200507392 05:37:02 INFO - 2015-10-27 05:37:02,180 DEBUG : BROWSER_OUTPUT: 05:37:04 INFO - 2015-10-27 05:37:04,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:37:05 INFO - 2015-10-27 05:37:05,519 DEBUG : BROWSER_OUTPUT: RSS: Main: 203321344 05:37:05 INFO - 2015-10-27 05:37:05,519 DEBUG : BROWSER_OUTPUT: 05:37:08 INFO - 2015-10-27 05:37:08,799 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:37:09 INFO - 2015-10-27 05:37:09,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 202772480 05:37:09 INFO - 2015-10-27 05:37:09,375 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49784/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49784/tests/kraken/driver.html?testName=ai-astar) 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 250232832 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |0;ai-astar;98;89;110;98;97;98;99;98;98;108 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;144;168;170;141;151;159;173;175;144 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |2;audio-dft;148;150;147;150;150;151;152;154;158;156 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |3;audio-fft;83;94;81;106;84;79;80;80;107;84 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;101;102;124;101;101;108;128;102;106;130 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;118;118;117;117;119;120;118;119;118;118 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;143;140;142;143;143;143;140;140;147;138 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;93;93;93;92;94;95;93;94;97;97 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;85;82;83;85;88;89;88;85;80;91 05:37:11 INFO - 2015-10-27 05:37:11,769 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;79;85;80;77;82;85;85;93;85;85 05:37:11 INFO - 2015-10-27 05:37:11,770 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;103;105;109;108;124;124;125;111;111;103 05:37:11 INFO - 2015-10-27 05:37:11,770 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;139;140;146;143;134;132;137;128;140;145 05:37:11 INFO - 2015-10-27 05:37:11,770 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;198;207;203;200;207;199;207;208;207;197 05:37:11 INFO - 2015-10-27 05:37:11,770 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;99;97;93;98;110;110;96;95;94;96 05:37:11 INFO - 2015-10-27 05:37:11,770 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:37:11 INFO - 2015-10-27 05:37:11,772 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:37:11 INFO - 2015-10-27 05:37:11,772 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,976 05:37:11 INFO - 2015-10-27 05:37:11,772 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:37:11 INFO - 2015-10-27 05:37:11,772 DEBUG : BROWSER_OUTPUT: __startTimestamp1445949431770__endTimestamp 05:37:11 INFO - 2015-10-27 05:37:11,772 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:99.30 Median:98.00 stddev:5.87 (6.0%) stddev-sans-first:6.21 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: Values: 98.0 89.0 110.0 98.0 97.0 98.0 99.0 98.0 98.0 108.0 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:156.90 Median:163.50 stddev:13.63 (8.3%) stddev-sans-first:13.64 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: Values: 144.0 144.0 168.0 170.0 141.0 151.0 159.0 173.0 175.0 144.0 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:151.60 Median:151.50 stddev:3.47 (2.3%) stddev-sans-first:3.43 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: Values: 148.0 150.0 147.0 150.0 150.0 151.0 152.0 154.0 158.0 156.0 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.80 Median:82.00 stddev:10.73 (13.1%) stddev-sans-first:11.24 05:37:11 INFO - 2015-10-27 05:37:11,773 DEBUG : BROWSER_OUTPUT: Values: 83.0 94.0 81.0 106.0 84.0 79.0 80.0 80.0 107.0 84.0 05:37:11 INFO - 2015-10-27 05:37:11,775 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,775 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:110.30 Median:107.00 stddev:12.07 (11.3%) stddev-sans-first:12.32 05:37:11 INFO - 2015-10-27 05:37:11,775 DEBUG : BROWSER_OUTPUT: Values: 101.0 102.0 124.0 101.0 101.0 108.0 128.0 102.0 106.0 130.0 05:37:11 INFO - 2015-10-27 05:37:11,778 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,778 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: Values: 118.0 118.0 117.0 117.0 119.0 120.0 118.0 119.0 118.0 118.0 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:141.90 Median:143.00 stddev:2.51 (1.8%) stddev-sans-first:2.64 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: Values: 143.0 140.0 142.0 143.0 143.0 143.0 140.0 140.0 147.0 138.0 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:1.73 (1.8%) stddev-sans-first:1.79 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 93.0 92.0 94.0 95.0 93.0 94.0 97.0 97.0 05:37:11 INFO - 2015-10-27 05:37:11,779 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,780 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:85.60 Median:86.50 stddev:3.41 (3.9%) stddev-sans-first:3.61 05:37:11 INFO - 2015-10-27 05:37:11,780 DEBUG : BROWSER_OUTPUT: Values: 85.0 82.0 83.0 85.0 88.0 89.0 88.0 85.0 80.0 91.0 05:37:11 INFO - 2015-10-27 05:37:11,780 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,780 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:83.60 Median:85.00 stddev:4.45 (5.2%) stddev-sans-first:4.40 05:37:11 INFO - 2015-10-27 05:37:11,780 DEBUG : BROWSER_OUTPUT: Values: 79.0 85.0 80.0 77.0 82.0 85.0 85.0 93.0 85.0 85.0 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:112.30 Median:111.00 stddev:8.78 (7.9%) stddev-sans-first:8.65 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: Values: 103.0 105.0 109.0 108.0 124.0 124.0 125.0 111.0 111.0 103.0 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:140.00 stddev:5.76 (4.1%) stddev-sans-first:6.10 05:37:11 INFO - 2015-10-27 05:37:11,782 DEBUG : BROWSER_OUTPUT: Values: 139.0 140.0 146.0 143.0 134.0 132.0 137.0 128.0 140.0 145.0 05:37:11 INFO - 2015-10-27 05:37:11,783 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,783 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.30 Median:207.00 stddev:4.40 (2.1%) stddev-sans-first:4.23 05:37:11 INFO - 2015-10-27 05:37:11,783 DEBUG : BROWSER_OUTPUT: Values: 198.0 207.0 203.0 200.0 207.0 199.0 207.0 208.0 207.0 197.0 05:37:11 INFO - 2015-10-27 05:37:11,783 DEBUG : BROWSER_OUTPUT: 05:37:11 INFO - 2015-10-27 05:37:11,783 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:98.80 Median:96.00 stddev:6.16 (6.4%) stddev-sans-first:6.53 05:37:11 INFO - 2015-10-27 05:37:11,785 DEBUG : BROWSER_OUTPUT: Values: 99.0 97.0 93.0 98.0 110.0 110.0 96.0 95.0 94.0 96.0 05:37:11 INFO - 2015-10-27 05:37:11,785 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:37:11 INFO - 2015-10-27 05:37:11,785 DEBUG : BROWSER_OUTPUT: 05:37:12 INFO - 2015-10-27 05:37:12,486 INFO : Browser exited with error code: 0 05:37:13 INFO - 2015-10-27 05:37:13,283 INFO : Completed test kraken (00:01:05) 05:37:13 INFO - 2015-10-27 05:37:13,283 INFO : Starting test v8_7 05:37:13 INFO - 2015-10-27 05:37:13,283 DEBUG : operating with platform_type : w7_ 05:37:13 INFO - 2015-10-27 05:37:13,283 INFO : Initialising browser for v8_7 test... 05:37:13 INFO - 2015-10-27 05:37:13,352 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmprdw2wo\profile http://localhost:49784/getInfo.html 05:37:18 INFO - 2015-10-27 05:37:18,016 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:37:18 INFO - 2015-10-27 05:37:18,016 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:37:18 INFO - 2015-10-27 05:37:18,016 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 05:37:18 INFO - 2015-10-27 05:37:18,016 DEBUG : BROWSER_OUTPUT: __metrics 05:37:18 INFO - 2015-10-27 05:37:18,164 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:19 INFO - 2015-10-27 05:37:19,230 DEBUG : BROWSER_OUTPUT: console.error: 05:37:19 INFO - 2015-10-27 05:37:19,232 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] 05:37:19 INFO - 2015-10-27 05:37:19,232 DEBUG : BROWSER_OUTPUT: Stack: 05:37:19 INFO - 2015-10-27 05:37:19,232 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 05:37:19 INFO - 2015-10-27 05:37:19,232 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 05:37:19 INFO - 2015-10-27 05:37:19,232 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 05:37:19 INFO - 2015-10-27 05:37:19,233 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5746.15 05:37:54 INFO - RETURN: kraken: 1682.10 05:37:54 INFO - RETURN: v8_7: 19723.08 05:37:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,131,25]]", "result": "19723.08"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,131,25]]", "result": "1682.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,131,25]]", "result": "5746.15"}}} 05:37:54 INFO - Return code: 0 05:37:54 INFO - # TBPL SUCCESS # 05:37:54 INFO - Running post-action listener: _resource_record_post_action 05:37:54 INFO - Running post-run listener: _resource_record_post_run 05:37:56 INFO - Total resource usage - Wall time: 1237s; CPU: 13.0%; Read bytes: 750747136; Write bytes: 845455360; Read time: 8047730; Write time: 6058020 05:37:56 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 395264; Read time: 0; Write time: 8600 05:37:56 INFO - run-tests - Wall time: 1236s; CPU: 13.0%; Read bytes: 750747136; Write bytes: 823875584; Read time: 8047730; Write time: 6034100 05:37:56 INFO - Running post-run listener: _upload_blobber_files 05:37:56 INFO - Blob upload gear active. 05:37:56 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:37:56 INFO - Copying logs to upload dir... 05:37:56 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1443.851000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 28 secs) (at 2015-10-27 05:38:20.303031) ========= ========= Started set props: build_url (results: 0, elapsed: 35 secs) (at 2015-10-27 05:38:20.306747) ========= '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-078 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-078 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-078 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/3ss_3KF-TJC2gc05DVU7Pg/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/3ss_3KF-TJC2gc05DVU7Pg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 35 secs) (at 2015-10-27 05:38:55.370657) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 25 secs) (at 2015-10-27 05:38:55.371008) ========= '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-078 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-078 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-078 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: 25 secs) (at 2015-10-27 05:39:20.700023) ========= ========= Started reboot slave lost (results: 0, elapsed: 33 secs) (at 2015-10-27 05:39:20.700357) ========= ========= Finished reboot slave lost (results: 0, elapsed: 33 secs) (at 2015-10-27 05:39:53.756059) =========