builder: mozilla-inbound_win7-ix_test-dromaeojs
slave: t-w732-ix-128
starttime: 1445510678.98
results: success (0)
buildid: 20151022012331
builduid: 4eddf729df944171bcb0c9c8b024d735
revision: 37f496398521e93ebbea912b1c104e1ea07a6fbc
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 03:44:38.982832) =========
master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 03:44:38.984184) =========
========= Started set props: basedir (results: 0, elapsed: 10 secs) (at 2015-10-22 03:44:38.984572) =========
'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-128
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-128
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-128
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.638000
basedir: 'C:\\slave\\test'
========= Finished set props: basedir (results: 0, elapsed: 10 secs) (at 2015-10-22 03:44:49.511442) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 48 secs) (at 2015-10-22 03:44:49.511850) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 48 secs) (at 2015-10-22 03:45:37.879511) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 03:45:37.879850) =========
'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-128
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-128
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-128
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=1.401000
========= Finished 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 03:45:49.710641) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 03:45:49.711093) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 03:45:49.711862) =========
========= Started 'bash -c ...' (results: 0, elapsed: 20 secs) (at 2015-10-22 03:45:49.712202) =========
'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-128
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-128
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-128
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
--03:45:53-- 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% 9.88 MB/s
03:45:55 (9.88 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=3.922000
========= Finished 'bash -c ...' (results: 0, elapsed: 20 secs) (at 2015-10-22 03:46:10.242568) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 25 secs) (at 2015-10-22 03:46:10.242892) =========
'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-128
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-128
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-128
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=13.401000
========= Finished 'rm -rf ...' (results: 0, elapsed: 25 secs) (at 2015-10-22 03:46:35.629737) =========
========= Started 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2015-10-22 03:46:35.630160) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 37f496398521e93ebbea912b1c104e1ea07a6fbc --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 37f496398521e93ebbea912b1c104e1ea07a6fbc --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-128
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-128
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-128
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-22 03:46:36,851 truncating revision to first 12 chars
2015-10-22 03:46:36,851 Setting DEBUG logging.
2015-10-22 03:46:36,851 attempt 1/10
2015-10-22 03:46:36,851 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/37f496398521?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-22 03:46:39,418 unpacking tar archive at: mozilla-inbound-37f496398521/testing/mozharness/
program finished with exit code 0
elapsedTime=4.574000
========= Finished 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2015-10-22 03:46:50.110603) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2015-10-22 03:46:50.110901) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2015-10-22 03:46:52.401615) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 03:46:52.401988) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 03:46:52.402440) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 10 secs) (at 2015-10-22 03:46:52.402738) =========
'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-128
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-128
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-128
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
03:46:53 INFO - MultiFileLogger online at 20151022 03:46:53 in C:\slave\test
03:46:53 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
03:46:53 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
03:46:53 INFO - {'append_to_log': False,
03:46:53 INFO - 'base_work_dir': 'C:\\slave\\test',
03:46:53 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO',
03:46:53 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
03:46:53 INFO - 'branch': 'Mozilla-Inbound-Non-PGO',
03:46:53 INFO - 'buildbot_json_path': 'buildprops.json',
03:46:53 INFO - 'config_files': ('talos/windows_config.py',),
03:46:53 INFO - 'default_actions': ('clobber',
03:46:53 INFO - 'read-buildbot-config',
03:46:53 INFO - 'download-and-extract',
03:46:53 INFO - 'populate-webroot',
03:46:53 INFO - 'create-virtualenv',
03:46:53 INFO - 'install',
03:46:53 INFO - 'run-tests'),
03:46:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:46:53 INFO - 'download_minidump_stackwalk': True,
03:46:53 INFO - 'download_symbols': 'ondemand',
03:46:53 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
03:46:53 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
03:46:53 INFO - 'hg': 'c:/mozilla-build/hg/hg',
03:46:53 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
03:46:53 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
03:46:53 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
03:46:53 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
03:46:53 INFO - 'C:/mozilla-build/tooltool.py'),
03:46:53 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
03:46:53 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
03:46:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:46:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:46:53 INFO - 'installer_path': 'installer.exe',
03:46:53 INFO - 'log_level': 'info',
03:46:53 INFO - 'log_name': 'talos',
03:46:53 INFO - 'log_to_console': True,
03:46:53 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
03:46:53 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
03:46:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
03:46:53 INFO - 'opt_config_files': (),
03:46:53 INFO - 'pip_index': False,
03:46:53 INFO - 'sps_profile': False,
03:46:53 INFO - 'sps_profile_interval': 0,
03:46:53 INFO - 'suite': 'dromaeojs',
03:46:53 INFO - 'system_bits': '32',
03:46:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:46:53 INFO - 'title': 'T-W732-IX-128',
03:46:53 INFO - 'use_talos_json': True,
03:46:53 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
03:46:53 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
03:46:53 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
03:46:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:46:53 INFO - 'work_dir': 'build'}
03:46:53 INFO - #####
03:46:53 INFO - ##### Running clobber step.
03:46:53 INFO - #####
03:46:53 INFO - Running pre-action listener: _resource_record_pre_action
03:46:53 INFO - Running main action method: clobber
03:46:53 INFO - rmtree: C:\slave\test\build
03:46:53 INFO - Using _rmtree_windows ...
03:46:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
03:48:11 INFO - Running post-action listener: _resource_record_post_action
03:48:11 INFO - #####
03:48:11 INFO - ##### Running read-buildbot-config step.
03:48:11 INFO - #####
03:48:11 INFO - Running pre-action listener: _resource_record_pre_action
03:48:11 INFO - Running main action method: read_buildbot_config
03:48:11 INFO - Using buildbot properties:
03:48:11 INFO - {
03:48:11 INFO - "properties": {
03:48:11 INFO - "buildnumber": 2239,
03:48:11 INFO - "product": "firefox",
03:48:11 INFO - "script_repo_revision": "production",
03:48:11 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs",
03:48:11 INFO - "repository": "",
03:48:11 INFO - "buildername": "Windows 7 32-bit mozilla-inbound talos dromaeojs",
03:48:11 INFO - "buildid": "20151022012331",
03:48:11 INFO - "slavename": "t-w732-ix-128",
03:48:11 INFO - "pgo_build": "False",
03:48:11 INFO - "basedir": "C:\\slave\\test",
03:48:11 INFO - "project": "",
03:48:11 INFO - "platform": "win7-ix",
03:48:11 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/",
03:48:11 INFO - "slavebuilddir": "test",
03:48:11 INFO - "scheduler": "tests-mozilla-inbound-win32-talos",
03:48:11 INFO - "branch": "mozilla-inbound",
03:48:11 INFO - "repo_path": "integration/mozilla-inbound",
03:48:11 INFO - "stage_platform": "win32",
03:48:11 INFO - "builduid": "4eddf729df944171bcb0c9c8b024d735",
03:48:11 INFO - "revision": "37f496398521e93ebbea912b1c104e1ea07a6fbc"
03:48:11 INFO - },
03:48:11 INFO - "sourcestamp": {
03:48:11 INFO - "repository": "",
03:48:11 INFO - "hasPatch": false,
03:48:11 INFO - "project": "",
03:48:11 INFO - "branch": "mozilla-inbound-win32-talos",
03:48:11 INFO - "changes": [
03:48:11 INFO - {
03:48:11 INFO - "category": null,
03:48:11 INFO - "files": [
03:48:11 INFO - {
03:48:11 INFO - "url": null,
03:48:11 INFO - "name": "https://queue.taskcluster.net/v1/task/OYot7ac_TZq7CPM6NGEd2g/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
03:48:11 INFO - }
03:48:11 INFO - ],
03:48:11 INFO - "repository": "",
03:48:11 INFO - "rev": "24653ad483de41bc8e84fad1b4e9f83d2bf4b38f",
03:48:11 INFO - "who": "danderson@mozilla.com",
03:48:11 INFO - "when": 1445508597,
03:48:11 INFO - "number": 6558830,
03:48:11 INFO - "comments": "Fix wheel events not working on scrollbars of inactive subframes. (bug 1216488, r=tn)",
03:48:11 INFO - "project": "",
03:48:11 INFO - "at": "Thu 22 Oct 2015 03:09:57",
03:48:11 INFO - "branch": "mozilla-inbound-win32-talos",
03:48:11 INFO - "revlink": "",
03:48:11 INFO - "properties": [
03:48:11 INFO - [
03:48:11 INFO - "buildid",
03:48:11 INFO - "20151022011617",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "builduid",
03:48:11 INFO - "a2ded5f720c342d0b451a1d221289be8",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "pgo_build",
03:48:11 INFO - "False",
03:48:11 INFO - "Change"
03:48:11 INFO - ]
03:48:11 INFO - ],
03:48:11 INFO - "revision": "24653ad483de41bc8e84fad1b4e9f83d2bf4b38f"
03:48:11 INFO - },
03:48:11 INFO - {
03:48:11 INFO - "category": null,
03:48:11 INFO - "files": [
03:48:11 INFO - {
03:48:11 INFO - "url": null,
03:48:11 INFO - "name": "https://queue.taskcluster.net/v1/task/28EZEgKxTnKFcOJgxpinJg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
03:48:11 INFO - }
03:48:11 INFO - ],
03:48:11 INFO - "repository": "",
03:48:11 INFO - "rev": "6b4a97d6111ac549d6a99f3ffa66934daa0aaca5",
03:48:11 INFO - "who": "ferjmoreno@gmail.com",
03:48:11 INFO - "when": 1445507235,
03:48:11 INFO - "number": 6558706,
03:48:11 INFO - "comments": "Bug 1215509 - Allow signUp in FxAccountsClient to use ?key=true. r=markh",
03:48:11 INFO - "project": "",
03:48:11 INFO - "at": "Thu 22 Oct 2015 02:47:15",
03:48:11 INFO - "branch": "mozilla-inbound-win32-talos",
03:48:11 INFO - "revlink": "",
03:48:11 INFO - "properties": [
03:48:11 INFO - [
03:48:11 INFO - "buildid",
03:48:11 INFO - "20151022012121",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "builduid",
03:48:11 INFO - "a282853cb3284bf08bf21f612e287a55",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "pgo_build",
03:48:11 INFO - "False",
03:48:11 INFO - "Change"
03:48:11 INFO - ]
03:48:11 INFO - ],
03:48:11 INFO - "revision": "6b4a97d6111ac549d6a99f3ffa66934daa0aaca5"
03:48:11 INFO - },
03:48:11 INFO - {
03:48:11 INFO - "category": null,
03:48:11 INFO - "files": [
03:48:11 INFO - {
03:48:11 INFO - "url": null,
03:48:11 INFO - "name": "https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
03:48:11 INFO - }
03:48:11 INFO - ],
03:48:11 INFO - "repository": "",
03:48:11 INFO - "rev": "37f496398521e93ebbea912b1c104e1ea07a6fbc",
03:48:11 INFO - "who": "cmccormack@mozilla.com",
03:48:11 INFO - "when": 1445507192,
03:48:11 INFO - "number": 6558697,
03:48:11 INFO - "comments": "Bug 1216872 - Make 100% Keyframe returned by getFrames() have easing:linear. r=birtles",
03:48:11 INFO - "project": "",
03:48:11 INFO - "at": "Thu 22 Oct 2015 02:46:32",
03:48:11 INFO - "branch": "mozilla-inbound-win32-talos",
03:48:11 INFO - "revlink": "",
03:48:11 INFO - "properties": [
03:48:11 INFO - [
03:48:11 INFO - "buildid",
03:48:11 INFO - "20151022012331",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "builduid",
03:48:11 INFO - "4eddf729df944171bcb0c9c8b024d735",
03:48:11 INFO - "Change"
03:48:11 INFO - ],
03:48:11 INFO - [
03:48:11 INFO - "pgo_build",
03:48:11 INFO - "False",
03:48:11 INFO - "Change"
03:48:11 INFO - ]
03:48:11 INFO - ],
03:48:11 INFO - "revision": "37f496398521e93ebbea912b1c104e1ea07a6fbc"
03:48:11 INFO - }
03:48:11 INFO - ],
03:48:11 INFO - "revision": "37f496398521e93ebbea912b1c104e1ea07a6fbc"
03:48:11 INFO - }
03:48:11 INFO - }
03:48:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip.
03:48:11 INFO - Running post-action listener: _resource_record_post_action
03:48:11 INFO - #####
03:48:11 INFO - ##### Running download-and-extract step.
03:48:11 INFO - #####
03:48:11 INFO - Running pre-action listener: _resource_record_pre_action
03:48:11 INFO - Running main action method: download_and_extract
03:48:11 INFO - mkdir: C:\slave\test\build\tests
03:48:11 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')]}
03:48:11 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
03:48:11 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/test_packages.json
03:48:11 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
03:48:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
03:48:13 INFO - Downloaded 1221 bytes.
03:48:13 INFO - Reading from file C:\slave\test\build\test_packages.json
03:48:13 INFO - Using the following test package requirements:
03:48:13 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
03:48:13 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'],
03:48:13 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'jsshell-win32.zip'],
03:48:13 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'],
03:48:13 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
03:48:13 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'],
03:48:13 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'],
03:48:13 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'],
03:48:13 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
03:48:13 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
03:48:13 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']}
03:48:13 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common
03:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:48:13 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
03:48:13 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
03:48:13 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:48:23 INFO - Downloaded 18005105 bytes.
03:48:23 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
03:48:23 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip
03:48:23 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
03:49:06 INFO - Return code: 0
03:49:06 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
03:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:49:06 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
03:49:06 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
03:49:06 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:13 INFO - Downloaded 18005105 bytes.
03:49:13 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
03:49:13 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip
03:49:13 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
03:49:36 INFO - Return code: 0
03:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:49:36 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
03:49:36 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip
03:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:38 INFO - Downloaded 11131307 bytes.
03:49:38 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests
03:49:38 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip
03:49:38 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
03:49:39 INFO - Return code: 0
03:49:39 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')]}
03:49:39 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net
03:49:39 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
03:49:39 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip
03:49:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:41 INFO - Downloaded 51458468 bytes.
03:49:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
03:49:41 INFO - mkdir: C:\slave\test\properties
03:49:41 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
03:49:41 INFO - Writing to file C:\slave\test\properties\build_url
03:49:41 INFO - Contents:
03:49:41 INFO - build_url:https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
03:49:41 INFO - Running post-action listener: _resource_record_post_action
03:49:41 INFO - Running post-action listener: set_extra_try_arguments
03:49:41 INFO - #####
03:49:41 INFO - ##### Running populate-webroot step.
03:49:41 INFO - #####
03:49:41 INFO - Running pre-action listener: _resource_record_pre_action
03:49:41 INFO - Running main action method: populate_webroot
03:49:41 INFO - Guessing talos json url...
03:49:41 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')]}
03:49:41 INFO - https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net
03:49:41 INFO - trying https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt
03:49:41 INFO - Downloading https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt
03:49:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/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
03:49:41 INFO - Downloaded 111 bytes.
03:49:41 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt
03:49:41 INFO - Contents:
03:49:41 INFO - 20151022012331
03:49:41 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/37f496398521e93ebbea912b1c104e1ea07a6fbc
03:49:41 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')]}
03:49:41 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/37f4963985/testing/talos/talos.json
03:49:41 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/37f4963985/testing/talos/talos.json to C:\slave\test\build\talos.json
03:49:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/37f4963985/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1
03:49:42 INFO - Downloaded 4264 bytes.
03:49:42 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
03:49:42 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
03:49:42 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
03:49:42 INFO - u'../fennec_ids.txt'],
03:49:42 INFO - u'tests': [u'tcheck2']},
03:49:42 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
03:49:42 INFO - u'--tppagecycles',
03:49:42 INFO - u'7'],
03:49:42 INFO - u'tests': [u'tsvgm']}},
03:49:42 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
03:49:42 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'tresize', u'tcanvasmark']},
03:49:42 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
03:49:42 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'dromaeo_css',
03:49:42 INFO - u'kraken',
03:49:42 INFO - u'v8_7']},
03:49:42 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
03:49:42 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
03:49:42 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'tests': [u'damp', u'tps']},
03:49:42 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'damp', u'tps']},
03:49:42 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
03:49:42 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
03:49:42 INFO - u'other': {u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'other_l64': {u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'other_nol64': {u'tests': [u'a11yr',
03:49:42 INFO - u'ts_paint',
03:49:42 INFO - u'tpaint',
03:49:42 INFO - u'sessionrestore',
03:49:42 INFO - u'sessionrestore_no_auto_restore']},
03:49:42 INFO - u'svgr': {u'tests': [u'tsvgx',
03:49:42 INFO - u'tsvgr_opacity',
03:49:42 INFO - u'tart',
03:49:42 INFO - u'tscrollx',
03:49:42 INFO - u'cart']},
03:49:42 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'tsvgx',
03:49:42 INFO - u'tsvgr_opacity',
03:49:42 INFO - u'tart',
03:49:42 INFO - u'tscrollx',
03:49:42 INFO - u'cart']},
03:49:42 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'tests': [u'tp5o']},
03:49:42 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'talos_options': [u'--e10s'],
03:49:42 INFO - u'tests': [u'tp5o']},
03:49:42 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'talos_options': [u'--xperf_path',
03:49:42 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:49:42 INFO - u'tests': [u'tp5n']},
03:49:42 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:49:42 INFO - u'talos_options': [u'--e10s',
03:49:42 INFO - u'--xperf_path',
03:49:42 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
03:49:42 INFO - u'C:/slave/talos-data/talos/xperf.config'],
03:49:42 INFO - u'tests': [u'tp5n']}},
03:49:42 INFO - u'talos.zip': {u'path': u'',
03:49:42 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
03:49:42 INFO - Running post-action listener: _resource_record_post_action
03:49:42 INFO - #####
03:49:42 INFO - ##### Running create-virtualenv step.
03:49:42 INFO - #####
03:49:42 INFO - Running pre-action listener: _resource_record_pre_action
03:49:42 INFO - Running main action method: create_virtualenv
03:49:42 INFO - Creating virtualenv C:\slave\test\build/venv
03:49:42 INFO - mkdir: C:\slave\test\build\venv\Scripts
03:49:42 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
03:49:42 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'!
03:49:42 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
03:49:42 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
03:49:45 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
03:49:51 INFO - Installing distribute......................................................................................................................................................................................done.
03:49:52 INFO - Return code: 0
03:49:52 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
03:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:49:52 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 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:49:53 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
03:49:53 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
03:49:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:49:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:49:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:49:53 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:49:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:49:53 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:49:53 INFO - 'DCLOCATION': 'SCL3',
03:49:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:49:53 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:49:53 INFO - 'HOMEDRIVE': 'C:',
03:49:53 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:49:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:49:53 INFO - 'KTS_VERSION': '1.19c',
03:49:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:49:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:49:53 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:49:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:49:53 INFO - 'MOZ_NO_REMOTE': '1',
03:49:53 INFO - 'NO_EM_RESTART': '1',
03:49:53 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:49:53 INFO - 'OS': 'Windows_NT',
03:49:53 INFO - 'OURDRIVE': 'C:',
03:49:53 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:49:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:49:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:49:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:49:53 INFO - 'PROCESSOR_LEVEL': '6',
03:49:53 INFO - 'PROCESSOR_REVISION': '1e05',
03:49:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:49:53 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:49:53 INFO - 'PROMPT': '$P$G',
03:49:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:49:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:49:53 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:49:53 INFO - 'PWD': 'C:\\slave\\test',
03:49:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:49:53 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:49:53 INFO - 'SYSTEMDRIVE': 'C:',
03:49:53 INFO - 'SYSTEMROOT': 'C:\\windows',
03:49:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:49:53 INFO - 'TEST1': 'testie',
03:49:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:49:53 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:49:53 INFO - 'USERNAME': 'cltbld',
03:49:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:49:53 INFO - 'WINDIR': 'C:\\windows',
03:49:53 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:49:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:49:54 INFO - Ignoring indexes: http://pypi.python.org/simple/
03:49:54 INFO - Downloading/unpacking pip>=1.5
03:49:54 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
03:50:00 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
03:50:00 INFO - Running setup.py egg_info for package pip
03:50:00 INFO - warning: no files found matching 'pip\cacert.pem'
03:50:00 INFO - warning: no files found matching '*.html' under directory 'docs'
03:50:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
03:50:00 INFO - no previously-included directories found matching 'docs\_build\_sources'
03:50:00 INFO - Installing collected packages: pip
03:50:00 INFO - Found existing installation: pip 0.8.2
03:50:00 INFO - Uninstalling pip:
03:50:00 INFO - Successfully uninstalled pip
03:50:00 INFO - Running setup.py install for pip
03:50:00 INFO - warning: no files found matching 'pip\cacert.pem'
03:50:00 INFO - warning: no files found matching '*.html' under directory 'docs'
03:50:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
03:50:00 INFO - no previously-included directories found matching 'docs\_build\_sources'
03:50:00 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
03:50:00 INFO - Successfully installed pip
03:50:00 INFO - Cleaning up...
03:50:00 INFO - Return code: 0
03:50:00 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
03:50:00 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')]}
03:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50:00 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')]}
03:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:00 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 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:00 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
03:50:00 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
03:50:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:00 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:00 INFO - 'DCLOCATION': 'SCL3',
03:50:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:00 INFO - 'HOMEDRIVE': 'C:',
03:50:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:00 INFO - 'KTS_VERSION': '1.19c',
03:50:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:00 INFO - 'MOZ_NO_REMOTE': '1',
03:50:00 INFO - 'NO_EM_RESTART': '1',
03:50:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:00 INFO - 'OS': 'Windows_NT',
03:50:00 INFO - 'OURDRIVE': 'C:',
03:50:00 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;',
03:50:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:00 INFO - 'PROCESSOR_LEVEL': '6',
03:50:00 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:00 INFO - 'PROMPT': '$P$G',
03:50:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:00 INFO - 'PWD': 'C:\\slave\\test',
03:50:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:00 INFO - 'SYSTEMDRIVE': 'C:',
03:50:00 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:00 INFO - 'TEST1': 'testie',
03:50:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:00 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:00 INFO - 'USERNAME': 'cltbld',
03:50:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:00 INFO - 'WINDIR': 'C:\\windows',
03:50:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:02 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:02 INFO - Downloading/unpacking psutil>=0.7.1
03:50:02 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
03:50:02 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
03:50:04 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
03:50:04 INFO - Installing collected packages: psutil
03:50:04 INFO - Successfully installed psutil
03:50:04 INFO - Cleaning up...
03:50:04 INFO - Return code: 0
03:50:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
03:50:04 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')]}
03:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50:04 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')]}
03:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:04 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 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:04 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
03:50:04 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
03:50:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:04 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:04 INFO - 'DCLOCATION': 'SCL3',
03:50:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:04 INFO - 'HOMEDRIVE': 'C:',
03:50:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:04 INFO - 'KTS_VERSION': '1.19c',
03:50:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:04 INFO - 'MOZ_NO_REMOTE': '1',
03:50:04 INFO - 'NO_EM_RESTART': '1',
03:50:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:04 INFO - 'OS': 'Windows_NT',
03:50:04 INFO - 'OURDRIVE': 'C:',
03:50:04 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;',
03:50:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:04 INFO - 'PROCESSOR_LEVEL': '6',
03:50:04 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:04 INFO - 'PROMPT': '$P$G',
03:50:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:04 INFO - 'PWD': 'C:\\slave\\test',
03:50:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:04 INFO - 'SYSTEMDRIVE': 'C:',
03:50:04 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:04 INFO - 'TEST1': 'testie',
03:50:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:04 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:04 INFO - 'USERNAME': 'cltbld',
03:50:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:04 INFO - 'WINDIR': 'C:\\windows',
03:50:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
03:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:50:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:50:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz
03:50:05 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
03:50:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
03:50:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0)
03:50:06 INFO - Installing collected packages: mozsystemmonitor
03:50:06 INFO - Running setup.py install for mozsystemmonitor
03:50:06 INFO - Successfully installed mozsystemmonitor
03:50:06 INFO - Cleaning up...
03:50:06 INFO - Return code: 0
03:50:06 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
03:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
03:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:06 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 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:06 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
03:50:06 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
03:50:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:06 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:06 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:06 INFO - 'DCLOCATION': 'SCL3',
03:50:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:06 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:06 INFO - 'HOMEDRIVE': 'C:',
03:50:06 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:06 INFO - 'KTS_VERSION': '1.19c',
03:50:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:06 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:06 INFO - 'MOZ_NO_REMOTE': '1',
03:50:06 INFO - 'NO_EM_RESTART': '1',
03:50:06 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:06 INFO - 'OS': 'Windows_NT',
03:50:06 INFO - 'OURDRIVE': 'C:',
03:50:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:50:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:06 INFO - 'PROCESSOR_LEVEL': '6',
03:50:06 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:06 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:06 INFO - 'PROMPT': '$P$G',
03:50:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:06 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:06 INFO - 'PWD': 'C:\\slave\\test',
03:50:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:06 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:06 INFO - 'SYSTEMDRIVE': 'C:',
03:50:06 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:06 INFO - 'TEST1': 'testie',
03:50:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:06 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:06 INFO - 'USERNAME': 'cltbld',
03:50:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:06 INFO - 'WINDIR': 'C:\\windows',
03:50:06 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:06 INFO - Downloading/unpacking blobuploader==1.2.4
03:50:06 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
03:50:06 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
03:50:08 INFO - Downloading blobuploader-1.2.4.tar.gz
03:50:08 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz
03:50:08 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
03:50:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
03:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:50:09 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
03:50:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
03:50:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
03:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:50:09 INFO - Downloading docopt-0.6.1.tar.gz
03:50:09 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz
03:50:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
03:50:10 INFO - Installing collected packages: blobuploader, requests, docopt
03:50:10 INFO - Running setup.py install for blobuploader
03:50:10 INFO - Running setup.py install for requests
03:50:10 INFO - Running setup.py install for docopt
03:50:11 INFO - Successfully installed blobuploader requests docopt
03:50:11 INFO - Cleaning up...
03:50:12 INFO - Return code: 0
03:50:12 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
03:50:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
03:50:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:12 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:12 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:12 INFO - 'DCLOCATION': 'SCL3',
03:50:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:12 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:12 INFO - 'HOMEDRIVE': 'C:',
03:50:12 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:12 INFO - 'KTS_VERSION': '1.19c',
03:50:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:12 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:12 INFO - 'MOZ_NO_REMOTE': '1',
03:50:12 INFO - 'NO_EM_RESTART': '1',
03:50:12 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:12 INFO - 'OS': 'Windows_NT',
03:50:12 INFO - 'OURDRIVE': 'C:',
03:50:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:50:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:12 INFO - 'PROCESSOR_LEVEL': '6',
03:50:12 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:12 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:12 INFO - 'PROMPT': '$P$G',
03:50:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:12 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:12 INFO - 'PWD': 'C:\\slave\\test',
03:50:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:12 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:12 INFO - 'SYSTEMDRIVE': 'C:',
03:50:12 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:12 INFO - 'TEST1': 'testie',
03:50:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:12 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:12 INFO - 'USERNAME': 'cltbld',
03:50:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:12 INFO - 'WINDIR': 'C:\\windows',
03:50:12 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
03:50:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u7czpq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
03:50:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
03:50:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x3zljj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
03:50:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
03:50:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjf90s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
03:50:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
03:50:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dco2bn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
03:50:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
03:50:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z8mnjb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
03:50:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
03:50:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jc862h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
03:50:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
03:50:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fnvfik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
03:50:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
03:50:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uchyb7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ubyqs2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-et35e1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dvisb5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-my0j1a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fiblkk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
03:50:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
03:50:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p8kvxt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
03:50:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
03:50:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w08tfv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
03:50:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
03:50:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zuz4ji-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
03:50:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
03:50:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ppsghn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
03:50:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:50:15 INFO - Running setup.py install for manifestparser
03:50:15 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
03:50:15 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
03:50:16 INFO - Running setup.py install for mozcrash
03:50:16 INFO - Running setup.py install for mozdebug
03:50:17 INFO - Running setup.py install for mozdevice
03:50:17 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
03:50:17 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
03:50:17 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
03:50:17 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
03:50:17 INFO - Running setup.py install for mozfile
03:50:18 INFO - Running setup.py install for mozhttpd
03:50:18 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Running setup.py install for mozinfo
03:50:18 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Running setup.py install for mozInstall
03:50:18 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
03:50:18 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
03:50:19 INFO - Running setup.py install for mozleak
03:50:19 INFO - Running setup.py install for mozlog
03:50:19 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
03:50:19 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
03:50:20 INFO - Running setup.py install for moznetwork
03:50:20 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
03:50:20 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
03:50:20 INFO - Running setup.py install for mozprocess
03:50:21 INFO - Running setup.py install for mozprofile
03:50:21 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
03:50:21 INFO - Running setup.py install for mozrunner
03:50:22 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
03:50:22 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
03:50:22 INFO - Running setup.py install for mozscreenshot
03:50:22 INFO - Running setup.py install for moztest
03:50:23 INFO - Running setup.py install for mozversion
03:50:23 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
03:50:23 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
03:50:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
03:50:23 INFO - Cleaning up...
03:50:23 INFO - Return code: 0
03:50:23 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
03:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
03:50:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
03:50:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:23 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:23 INFO - 'DCLOCATION': 'SCL3',
03:50:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:23 INFO - 'HOMEDRIVE': 'C:',
03:50:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:23 INFO - 'KTS_VERSION': '1.19c',
03:50:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:23 INFO - 'MOZ_NO_REMOTE': '1',
03:50:23 INFO - 'NO_EM_RESTART': '1',
03:50:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:23 INFO - 'OS': 'Windows_NT',
03:50:23 INFO - 'OURDRIVE': 'C:',
03:50:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:50:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:23 INFO - 'PROCESSOR_LEVEL': '6',
03:50:23 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:23 INFO - 'PROMPT': '$P$G',
03:50:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:23 INFO - 'PWD': 'C:\\slave\\test',
03:50:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:23 INFO - 'SYSTEMDRIVE': 'C:',
03:50:23 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:23 INFO - 'TEST1': 'testie',
03:50:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:23 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:23 INFO - 'USERNAME': 'cltbld',
03:50:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:23 INFO - 'WINDIR': 'C:\\windows',
03:50:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-al8yiy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
03:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nlo8kk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
03:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rnzpqg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
03:50:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqff2f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
03:50:24 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))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zbhowb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
03:50:24 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))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bpmcsm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
03:50:24 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))
03:50:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
03:50:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-10klvf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
03:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-taecmr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
03:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-woj4u1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
03:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ppi15p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
03:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w2uf3a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
03:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wswze0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
03:50:25 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))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3p3kve-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
03:50:25 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))
03:50:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
03:50:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ct7j3k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
03:50:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
03:50:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-spvpf0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
03:50:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
03:50:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ppc5re-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
03:50:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
03:50:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t_9o_z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
03:50:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
03:50:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
03:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:50:28 INFO - Downloading blessings-1.5.1.tar.gz
03:50:28 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
03:50:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
03:50:28 INFO - Installing collected packages: blessings
03:50:28 INFO - Running setup.py install for blessings
03:50:28 INFO - Successfully installed blessings
03:50:28 INFO - Cleaning up...
03:50:31 INFO - Return code: 0
03:50:31 INFO - Done creating virtualenv C:\slave\test\build/venv.
03:50:31 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
03:50:31 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
03:50:32 INFO - Reading from file tmpfile_stdout
03:50:32 INFO - Using _rmtree_windows ...
03:50:32 INFO - Using _rmtree_windows ...
03:50:32 INFO - Current package versions:
03:50:32 INFO - blessings == 1.5.1
03:50:32 INFO - blobuploader == 1.2.4
03:50:32 INFO - docopt == 0.6.1
03:50:32 INFO - manifestparser == 1.1
03:50:32 INFO - mozInstall == 1.12
03:50:32 INFO - mozcrash == 0.16
03:50:32 INFO - mozdebug == 0.1
03:50:32 INFO - mozdevice == 0.46
03:50:32 INFO - mozfile == 1.2
03:50:32 INFO - mozhttpd == 0.7
03:50:32 INFO - mozinfo == 0.8
03:50:32 INFO - mozleak == 0.1
03:50:32 INFO - mozlog == 3.0
03:50:32 INFO - moznetwork == 0.27
03:50:32 INFO - mozprocess == 0.22
03:50:32 INFO - mozprofile == 0.27
03:50:32 INFO - mozrunner == 6.10
03:50:32 INFO - mozscreenshot == 0.1
03:50:32 INFO - mozsystemmonitor == 0.0
03:50:32 INFO - moztest == 0.7
03:50:32 INFO - mozversion == 1.4
03:50:32 INFO - psutil == 3.1.1
03:50:32 INFO - requests == 1.2.3
03:50:32 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:50: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')]}
03:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:50: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')]}
03:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:50:32 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 0x0190DB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D4EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0181B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-128', 'USERDOMAIN': 'T-W732-IX-128', '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-128', '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
03:50:32 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
03:50:32 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
03:50:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:32 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:32 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:32 INFO - 'DCLOCATION': 'SCL3',
03:50:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:32 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:32 INFO - 'HOMEDRIVE': 'C:',
03:50:32 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:32 INFO - 'KTS_VERSION': '1.19c',
03:50:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:32 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:32 INFO - 'MOZ_NO_REMOTE': '1',
03:50:32 INFO - 'NO_EM_RESTART': '1',
03:50:32 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:32 INFO - 'OS': 'Windows_NT',
03:50:32 INFO - 'OURDRIVE': 'C:',
03:50:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:50:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:32 INFO - 'PROCESSOR_LEVEL': '6',
03:50:32 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:32 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:32 INFO - 'PROMPT': '$P$G',
03:50:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:32 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:32 INFO - 'PWD': 'C:\\slave\\test',
03:50:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:32 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:32 INFO - 'SYSTEMDRIVE': 'C:',
03:50:32 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:32 INFO - 'TEST1': 'testie',
03:50:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:32 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:32 INFO - 'USERNAME': 'cltbld',
03:50:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:32 INFO - 'WINDIR': 'C:\\windows',
03:50:32 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
03:50:32 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))
03:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 3))
03:50:32 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))
03:50:32 INFO - Cleaning up...
03:50:32 INFO - Return code: 0
03:50:32 INFO - Running post-action listener: _resource_record_post_action
03:50:32 INFO - Running post-action listener: _start_resource_monitoring
03:50:32 INFO - Starting resource monitoring.
03:50:32 INFO - #####
03:50:32 INFO - ##### Running install step.
03:50:32 INFO - #####
03:50:32 INFO - Running pre-action listener: _resource_record_pre_action
03:50:32 INFO - Running main action method: install
03:50:32 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
03:50:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
03:50:33 INFO - Reading from file tmpfile_stdout
03:50:33 INFO - Using _rmtree_windows ...
03:50:33 INFO - Using _rmtree_windows ...
03:50:33 INFO - Detecting whether we're running mozinstall >=1.0...
03:50:33 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
03:50:33 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
03:50:33 INFO - Reading from file tmpfile_stdout
03:50:33 INFO - Output received:
03:50:33 INFO - Usage: mozinstall-script.py [options] installer
03:50:33 INFO - Options:
03:50:33 INFO - -h, --help show this help message and exit
03:50:33 INFO - -d DEST, --destination=DEST
03:50:33 INFO - Directory to install application into. [default:
03:50:33 INFO - "C:\slave\test"]
03:50:33 INFO - --app=APP Application being installed. [default: firefox]
03:50:33 INFO - Using _rmtree_windows ...
03:50:33 INFO - Using _rmtree_windows ...
03:50:33 INFO - mkdir: C:\slave\test\build\application
03:50:33 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
03:50:33 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test\build\application
03:50:34 INFO - Reading from file tmpfile_stdout
03:50:34 INFO - Output received:
03:50:34 INFO - C:\slave\test\build\application\firefox\firefox.exe
03:50:34 INFO - Using _rmtree_windows ...
03:50:34 INFO - Using _rmtree_windows ...
03:50:34 INFO - Running post-action listener: _resource_record_post_action
03:50:34 INFO - #####
03:50:34 INFO - ##### Running run-tests step.
03:50:34 INFO - #####
03:50:34 INFO - Running pre-action listener: _resource_record_pre_action
03:50:34 INFO - Running main action method: run_tests
03:50:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
03:50:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
03:50:34 INFO - Python 2.7.3
03:50:34 INFO - Return code: 0
03:50:34 INFO - grabbing minidump binary from tooltool
03:50:34 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')]}
03:50:34 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 0x012D8B48>, '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 0x012FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0199C660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
03:50:34 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
03:50:34 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
03:50:34 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
03:50:41 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpcfzr2e
03:50:41 INFO - INFO - File integrity verified, renaming tmpcfzr2e to win32-minidump_stackwalk.exe
03:50:41 INFO - Return code: 0
03:50:41 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
03:50:41 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
03:50:41 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
03:50:41 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
03:50:41 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
03:50:41 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/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-128', '--webServer', 'localhost'] in C:\slave\test\build
03:50:41 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/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-128 --webServer localhost
03:50:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:50:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:50:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:50:41 INFO - 'COMPUTERNAME': 'T-W732-IX-128',
03:50:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:50:41 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:50:41 INFO - 'DCLOCATION': 'SCL3',
03:50:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:50:41 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:50:41 INFO - 'HOMEDRIVE': 'C:',
03:50:41 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:50:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:50:41 INFO - 'KTS_VERSION': '1.19c',
03:50:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:50:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-128',
03:50:41 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
03:50:41 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
03:50:41 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:50:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:50:41 INFO - 'MOZ_NO_REMOTE': '1',
03:50:41 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
03:50:41 INFO - 'NO_EM_RESTART': '1',
03:50:41 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:50:41 INFO - 'OS': 'Windows_NT',
03:50:41 INFO - 'OURDRIVE': 'C:',
03:50:41 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;',
03:50:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:50:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:50:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:50:41 INFO - 'PROCESSOR_LEVEL': '6',
03:50:41 INFO - 'PROCESSOR_REVISION': '1e05',
03:50:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:50:41 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:50:41 INFO - 'PROMPT': '$P$G',
03:50:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:50:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:50:41 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:50:41 INFO - 'PWD': 'C:\\slave\\test',
03:50:41 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
03:50:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:50:41 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:50:41 INFO - 'SYSTEMDRIVE': 'C:',
03:50:41 INFO - 'SYSTEMROOT': 'C:\\windows',
03:50:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:41 INFO - 'TEST1': 'testie',
03:50:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:50:41 INFO - 'USERDOMAIN': 'T-W732-IX-128',
03:50:41 INFO - 'USERNAME': 'cltbld',
03:50:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:50:41 INFO - 'WINDIR': 'C:\\windows',
03:50:41 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:50:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:50:41 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/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-128', '--webServer', 'localhost'] with output_timeout 3600
03:50:42 INFO - mozversion INFO | application_buildid: 20151022012331
03:50:42 INFO - mozversion INFO | application_changeset: 37f496398521e93ebbea912b1c104e1ea07a6fbc
03:50:42 INFO - mozversion INFO | application_display_name: Nightly
03:50:42 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:50:42 INFO - mozversion INFO | application_name: Firefox
03:50:42 INFO - mozversion INFO | application_remotingname: firefox
03:50:42 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
03:50:42 INFO - mozversion INFO | application_vendor: Mozilla
03:50:42 INFO - mozversion INFO | application_version: 44.0a1
03:50:42 INFO - mozversion INFO | platform_buildid: 20151022012331
03:50:42 INFO - mozversion INFO | platform_changeset: 37f496398521e93ebbea912b1c104e1ea07a6fbc
03:50:42 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
03:50:42 INFO - mozversion INFO | platform_version: 44.0a1
03:50:42 INFO - 2015-10-22 03:50:42,930 DEBUG : using testdate: 1445511042
03:50:42 INFO - 2015-10-22 03:50:42,930 DEBUG : actual date: 1445511042
03:50:42 INFO - 2015-10-22 03:50:42,956 INFO : starting webserver on 'localhost:49779'
03:50:42 INFO - 2015-10-22 03:50:42,957 INFO : Starting test suite T-W732-IX-128
03:50:42 INFO - 2015-10-22 03:50:42,957 INFO : Starting test dromaeo_css
03:50:42 INFO - 2015-10-22 03:50:42,957 DEBUG : operating with platform_type : w7_
03:50:42 INFO - 2015-10-22 03:50:42,959 INFO : Initialising browser for dromaeo_css test...
03:50:43 INFO - 2015-10-22 03:50:43,059 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpx6rnto\profile http://localhost:49779/getInfo.html
03:50:51 INFO - 2015-10-22 03:50:51,125 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
03:50:51 INFO - 2015-10-22 03:50:51,125 DEBUG : BROWSER_OUTPUT: colorDepth:24
03:50:51 INFO - 2015-10-22 03:50:51,125 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
03:50:51 INFO - 2015-10-22 03:50:51,125 DEBUG : BROWSER_OUTPUT: __metrics
03:50:51 INFO - 2015-10-22 03:50:51,233 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT: console.error:
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection.
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT: Stack:
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
03:50:53 INFO - 2015-10-22 03:50:53,052 DEBUG : BROWSER_OUTPUT:
03:50:53 INFO - 2015-10-22 03:50:53,809 INFO : Browser initialized.
03:50:53 INFO - 2015-10-22 03:50:53,809 INFO : Running cycle 1/1 for dromaeo_css test...
03:50:53 INFO - 2015-10-22 03:50:53,809 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpx6rnto\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
03:51:05 INFO - 2015-10-22 03:51:05,453 DEBUG : BROWSER_OUTPUT: RSS: Main: 138788864
03:51:05 INFO - 2015-10-22 03:51:05,453 DEBUG : BROWSER_OUTPUT:
03:51:56 INFO - 2015-10-22 03:51:56,282 DEBUG : BROWSER_OUTPUT: 1445511116294 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]
03:54:11 INFO - 2015-10-22 03:54:11,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49779/tests/dromaeo/cssquery-ext.html)
03:54:12 INFO - 2015-10-22 03:54:12,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 192995328
03:54:12 INFO - 2015-10-22 03:54:12,657 DEBUG : BROWSER_OUTPUT:
03:57:15 INFO - 2015-10-22 03:57:15,612 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-ext.html (next: http://localhost:49779/tests/dromaeo/cssquery-jquery.html)
03:57:16 INFO - 2015-10-22 03:57:16,173 DEBUG : BROWSER_OUTPUT: RSS: Main: 196247552
03:57:16 INFO - 2015-10-22 03:57:16,173 DEBUG : BROWSER_OUTPUT:
04:00:18 INFO - 2015-10-22 04:00:18,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49779/tests/dromaeo/cssquery-mootools.html)
04:00:19 INFO - 2015-10-22 04:00:19,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 145969152
04:00:19 INFO - 2015-10-22 04:00:19,095 DEBUG : BROWSER_OUTPUT:
04:03:21 INFO - 2015-10-22 04:03:21,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49779/tests/dromaeo/cssquery-prototype.html)
04:03:21 INFO - 2015-10-22 04:03:21,757 DEBUG : BROWSER_OUTPUT: RSS: Main: 140570624
04:03:21 INFO - 2015-10-22 04:03:21,757 DEBUG : BROWSER_OUTPUT:
04:06:24 INFO - 2015-10-22 04:06:24,693 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49779/tests/dromaeo/cssquery-yui.html)
04:06:25 INFO - 2015-10-22 04:06:25,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 139038720
04:06:25 INFO - 2015-10-22 04:06:25,288 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/dromaeo/cssquery-yui.html (next: http://localhost:49779/tests/dromaeo/cssquery-dojo.html)
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 189521920
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: __start_tp_report
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2287.5996700577393;2368.881555922204;2406.518696260747;2435.0746716247822;2438.2685194441674;4114.888979975409;4172.49930008399;4205.137946720923;4274.829006839726;4295.248331541976;4008.5790991945864;4231.428757117796;4267.871963841089;4338.544452832454;4347.326164444508;3607.6392360763975;3697.574778900434;3743.66307032367;3806.1626442182815;3807.752496087752;3615.439606860941;3737.9907424995235;3745.0637340664834;3749.9437508437395;3754.2679177560385;3485.3203625293077;3556.9329201239652;3598.586162591296;3601.7118630509494;3690.298843219793;3689.18837855674;3746.4754934309217;3765.228128233733;3788.583255841855;3848.191879705237;3469.8438570264398;3501.824908754552;3593.2813437312643;3737.99074249951;3803;4055.533613634411;4111.958880411217;4158.8336466541305;4165.437665915093;4240.724352917081;4493.842715504941;4499.707519011286;4555.521670224612;4585.885352866205;4593.40285762852;4528.841490547815;4536.637069034469;4621.976890115561;4626.537346265381;4629.861104166846;4002.0995276063236;4104.938425923643;4125.257453658371;4198.769067701306;4205.474315710536;4364.886953826754;4403.405540252089;4599;4668.626509879202;4685.344051832746;4244.617984381359;4286.699931004862;4351.390805287262;4515.593596576258;4528.0943811237885;4394.494633117232;4445.1998640245065;4497.392851965008;4503.572160644733;4539.523350048264;4409.823607055746;4458.08609235106;4484.8430304939175;4538.931916021263;4554.453465584085;4457.64338852891;4469.620082292977;4538.909221815546;4548.226801443762;4550.2492088804975;2930.1356099950567;2936.721011503904;3021.350409661933;3167.255694911694;3171.302313491028;2755.0632784853246;2770.667519897625;2774.0706863201053;2808.845513496736;2959.363736796599;4021.6380525752825;4097.303458412077;4115.36211887152;4208.38978348144;4358.738475691469;4116.485439320085;4221.620054195076;4481.439820022497;4512.548745125527;4630.4443466784205;4030.2946984277633;4106.609872062149;4171.873594129568;4292.527821939584;4323.956760432418;3856.421536769451;3925.371940489508;3936.291467535814;3937.153511994935;3939.350007248829;4348.34774783785;4382.627476664519;4608.723476591415;4646.767661616973;4762.095201911626;4506.571875671806;4508.819647214074;4588.472325682589;4594.678372514026;4601.884952876071;4625.953740462687;4638.397008389023;4735.455422626304;4737.597304229179;4744.762761861824;4253.447051883235;4326.58897404746;4417.889552761207;4423.491298500714;4428.955710442983;4280;4344.218040752695;4507.323901414814;4608.585227329557;4610.170169369546;4383.430154080077;4471.1057788443095;4471.217536931089;4473.35136405226;4482.058767658697;4254.340577210537;4271.124419494058;4359.975405779703;4364.62900653448;4396.384506169203;4261.382099595603;4305.74165550068;4334.046509767846;4340.848070317524;4360.454943132108;4148.585141485948;4175.227582897173;4215.473065866767;4376.277914144131;4465.5534446556385;4078.938815917823;4154.210699966997;4302.978485107555;4335.609795118455;4381.671374646978
04:09:28 INFO - 2015-10-22 04:09:28,542 DEBUG : BROWSER_OUTPUT: |1;ext.html;481.1555719711905;493.93578834751474;493.97777100030515;497.05311381817637;522.69161194895;25869.74130258697;34286.485702714446;34453.13867153323;34745.826270868674;34814.65185348146;36373.45439818405;37049.62950370496;37127.4430883537;38221.23557528855;38502.229955400944;6367.968160159193;6438.066480360345;8074.313683336909;8264.462809917337;8271.503709777402;10249.692509224735;10509;10544.736381590445;10550.314229575091;10553.47232638365;10176.542055607479;10310.175185985103;10347.637832675859;10360.637377691744;10367.896321036806;6037.577369584153;6604.603723776589;10083.697489075339;10179.89820101797;10588.735281617945;9741.512924353825;9891.406515609087;9896.703098907044;9897.307188496736;9907.504624768804;34054.659453405395;34383.656163438296;34809.47785783215;35844.820775896216;36708;2599.895044606054;2825.6044153818484;3331.5502407175204;3380.6957373836226;3410.38613049651;25359.366015849566;26245.475090498276;26252.8687356562;26656.200313990612;26942.326441838915;2189.8905054747356;2211.800937915595;2263.7170353705787;2267.5464907018495;2327.2785436514564;218456;221865;222640;228610;232378;43569;44592.77703611526;52170.21744673833;53021.469785302426;53110;58858.70570647119;59918.700406497686;60633;60678.696606516685;60713.69643151756;12250.571230006906;12314.568990085483;12348.259104453582;12378.381080945917;12394.442250098587;7489.28851759082;7527.473076884566;7541.132769731551;7547.05661792276;8818.250448711802;14359.353829077507;17351.392701255394;17465;17556.38552650651;17934.730979035325;7824.33493153077;8058.0733215679775;8073.31376832964;8155;8156.429049966446;765.6745882999703;805.5408417202256;837.4221786966973;883.5273128876021;962.2302158273353;25236.87381563117;25808.354791129997;25980;26013.73986260113;26084.739152608232;902.2691619787993;919.5356345045709;925.1719710858744;958.8465845464692;994.5623925472769;12767.489300427878;12952.575216726153;13223.471061157446;13416.511706956262;13556.661083472993;322571;328140;335135;352971;354355;46643;47137.29294060592;47177;47187.76406117948;47276.76361618239;996.3722854601699;1057.4131357096885;1071.989280107189;1078.7950289444823;1123.5168877382803;25223.87388063048;25343.6198457027;25838.966441342134;26001.479970400866;26155.607665884647;36134.27731445409;36340.63659363478;37981.430278546395;38027.42958855564;38185.42721859119;213740.72518549854;218403;218647;219862;274673;36989.26021479609;37348;37465.2506949854;37500.43749343707;37514.249715005004;40720.592794072865;41048;41159;41370.79314603408;41580.376294355;31570.368592628478;33651;33710;33750.324993500486;34000.31999360048;35695.464568030984;36134.457983129745;36369.27261454703;37434.06414839651;37525.62474375221;27027.459450810482;28393.43213135767;32438.675613244508;36080.819595901856;36942.63057369392;30267;33449;33519;34134;34155.82922085374;24268;24336;24527.632085518373;24983.625245621693;25393.619095713948
04:09:28 INFO - 2015-10-22 04:09:28,546 DEBUG : BROWSER_OUTPUT: |2;jquery.html;294.6802719049831;297.21386931565996;306.69790256597247;309.7692219296624;314.95748074010015;11617.18679692422;13669.794953075687;14861.256937153139;15379.769303460456;15821.20893955302;14402.27988600571;14548.272586370666;14719.264036798144;14749.755053888155;15435.228238588054;4990.725510096943;5112.05426996006;5185.533302002819;5243.606729495284;5249.868753281179;3441.225724212045;3539.3275277697285;5198.090334191501;5770.249867517216;5970.582059255832;5499.752511137009;5545.473180047909;5576.721163941807;5628.971855140719;5893.499052580536;5677.318721753395;5746.827595172152;5747.59766816323;5843;5878.294604647448;4889.462159162489;4894.1679914414635;4896.975515122402;4902.8774280643265;4920.458749537548;18000.599820054063;18098.728519072225;18838.246470141177;19378;19515.414537563895;1473.6389584551746;1525.031604930877;1565.2565031610009;1685.3258696521366;1728.023666628351;8083.757487275332;8668.956655216747;8816.515091669955;8999.730008099703;9069.909300907038;1423.5373503611368;1426.1585664457916;1465.8167729033871;1480.6298425393652;1497.6405662640996;51941.7402912983;56455.71772141113;56816.999999999585;56896.71551642257;57550.71224643892;5298.311219541435;5465.480779325958;5467.4532546745;5483.75323110453;5515.200295957064;6396.392342727433;6482.319356467503;6485.416312531895;6514.218293804774;6542.934570654233;4450.109978004412;4458;4476.194285028661;4480.641548676098;4555.33947577606;4395.296752519582;4649.837255696035;4703.800530864608;4707.411573553306;4733.917076486335;2430.963535546968;2578.729233430463;2583.315421413327;2611.9477610447684;2615.269156026502;911.544227886057;912.6623149434619;922;962.3456049886005;976.7509285132246;375.53058676654183;381.8358106014385;383.428691250041;388.3553301519464;395.5391968356832;9837.852432213522;10198.592056317664;10448;10494.737631559272;10671.466426678635;354.57273984848194;377.7336977430905;386.6732610943748;396.68463571460757;413.7414116177389;3872.0513474199;5274.156135018378;5530.861728456821;5547.72261386929;5607.102863541895;281874;282986;286040;286296;288195;65566.67216663886;65940.67029664821;67895.66052169807;68363.3163668357;69941;417.25103439326483;417.7723140888223;418.10942692065703;426.1179358727405;437.8183054032541;9449.622015119317;9468.905310946802;9840.01599840039;9882.308238423242;9963.103320701171;34388.14029649279;34632.30735385228;34898.302033959684;35192;35728.28543429169;46078;46746.29880551862;46855.29717054315;47377.76311118422;53062.73468632632;12263.20289181236;12344.247464725966;12952.611421657366;13277.468901244229;13291.66770830737;9976.301658883809;10193.74515637115;10455.268131230741;10559.208059395422;10587.629432969808;7862.960685196537;8106.635201415833;8519.74440766778;8833.160849719266;9084.454932704059;8172.788680602904;8869.06874778139;8958.596863141043;9049.819003620023;9357.719268421959;8742.431741936754;8969.282457403522;9224.815503690023;9572.712818615453;9601;12764.256084761708;13048.478060877458;13534.93232533831;13535.59393218205;13613.251271180181;13867.930660346634;13943.651408714863;14358.282085895873;14362.784558231426;14498
04:09:28 INFO - 2015-10-22 04:09:28,548 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1102.812521871282;1283.7111649878777;1323.4507679313085;1453.4912780526818;1480.5484327280176;6726.79819605412;7616.466847320689;7710.961445192766;7725.111612164603;7740.264674855894;7701.191374905624;7713.73001944932;7739.535627862332;7764.340031097349;7772.339351155145;2087.6659734442455;2112.5246819465574;2112.8204102651293;2113.1124927530473;2113.8097571218586;2021.9898900505477;2044.9795502045013;2073.7718850926385;2078.989605051973;2079.189116244666;2014.1037238428905;2034.6032523657893;2044.3355909329453;2048.3445297504804;2050.4053824390908;2027.3411141378963;2094.0890712540017;2103.400530848701;2107.74707035156;2109.4515425989275;2038.6126635939272;2060.4951786812176;2068.5449201175716;2069.4722845674405;2076.107273872234;6600.636964966925;6861.725530978755;7657.4639775215755;7693.769186924345;7703.576303303316;4249.660027197817;4375.409319741826;4378.759168245745;4383.561643835623;4383.583559561836;2656.389030522971;2699.676038875328;2728.6589176352954;2732.357895894463;2735.261479400551;807.5598798654747;829.618375547249;832.4755404095441;832.8875601793801;860.8192279621287;10396.11633011195;10397.584096636201;10611.787764244671;11045.613403530919;11104.222704410695;4046;4074.898127546776;4148.108156746314;4188.937165942513;4198.076423186894;9357.298202634829;9390.295727820441;9396.71809845692;9412.576434060484;9412.905870941204;4255.234057869613;4506.301523263899;4557.453105627346;4564.863054108382;4568.977155114269;4053.351463765783;4062.7968601569805;4069.694772891986;4070.898227544335;4071.063655359284;3205.983970080135;3325.1022223999385;3358.7648864579733;3396.6093899201414;3408.6080100788727;1012.5848402155228;1023.6059117239917;1024.3341827811832;1029.6499190275342;1055.1875056206677;770.6108415250375;800.8198155415101;811.5982588618672;811.8295158016764;818.979525511867;7327.120745510573;7363.77908662741;7376.373008294355;7378.520396174232;7385.335319821242;414.20472692430604;415.2276765216695;425.0266888824619;427.634372611419;434.97390156590075;5324.307839980777;5335.706536140549;5386.730663466811;5485;5656.066748986454;8393.37049721261;8655.870161947576;8929.419587726907;9713.46575938316;9730.44312909931;9179.678711245208;9205.953970230106;9615.90384096164;9634.03659634045;9639.662611808553;6907.13660792401;6965.059716938148;6965.582065076112;6972.616506092112;6979.720811167598;6112.419320164578;6199.070139479114;6578.638174900427;6600.9339906602245;6608;7981.760547183594;8006.119326873923;8017.078036025699;8024.598770061591;8033.317168040782;8336.166383361615;8480.957595211985;8483.915160848559;8488;8502.36232282569;6381.680915954276;6418.711157997808;6420.486361091195;6429.164208652845;6435.292117867125;6952.582845029315;7103.898895671179;7131.037309963089;7259.9274007259255;7312.488125831141;6695.096162018065;6697.732090716317;6858.211305699709;6871.244163141951;6894.276101009322;6549.70526326326;6568;6703.564268322543;6718.529702920944;6808.557443766139;6840.760573379908;6869.587824730532;6870.484713646397;6878.55289406187;6885.139357580302;6145.477634401126;6170.5372097092;6193.783217587363;6194.938050619617;6199.473044791243;6157.907631385622;6212.875742485035;6302.936970630419;6456.096146539582;6466.676666166767
04:09:28 INFO - 2015-10-22 04:09:28,602 DEBUG : BROWSER_OUTPUT: |4;prototype.html;274.9340158361993;279.5317842613624;285.65435822654587;288.9869955851986;292.6195945271148;4544.204764166274;4552.499225085238;4566.58900698937;4567.132244873472;4571.474280457748;4376.584224498675;4425.225585522537;4458.219811532985;4504.842330518432;4506.391637128987;3476.600190978032;3483.164040630243;3483.512308276843;3483.6168021517615;3483.982580087096;3207.8235697036653;3266.297745984613;3272.836358182093;3387.2209391839892;3482.0772495288643;3373.1904342957637;3376.3922493951077;3409.539712138854;3434.965650343502;3455.101673564866;3428.9142771430666;3432.073340198145;3437.5874895012507;3440.501127336522;3453.0504111369323;3093.8607762650736;3093.9535906961414;3096.829674367909;3097.9690203097907;3098.178982569621;4401.5378385269405;4403.889902752425;4405.757683327416;4421.8452354167775;4424.314231294123;4065.3698676705158;4070.61329173728;4073.4500842386196;4074.1851629674184;4074.5517993020744;4506.684532082755;4724.196886529298;4727.338172655831;4728.101660684493;4730.763461826929;3361.6638336166434;3499.667531584521;3506.8421921013614;3512.666296701809;3513.5608048993877;4513.255312873372;4663.626909847204;4672.275797251432;4930;4957.454679985199;3602.3155600435834;3603.4955106285142;3604.98197509016;3606.13452771333;3608.188157664557;3422.845971931269;3433.4506478963744;3521.542199514047;3644.4168932970597;3650.2699460108083;4065.4511640928686;4097.97951010243;4107.322291821875;4114.341705327193;4114.876553703393;4754.786034628381;4770.833020844254;4776.23580227162;4777.355057067321;4788.473267940523;1214.726686495549;1224.995503686986;1231.1443546735113;1290.180735233115;1290.5031562848183;896.3411892259185;907.4691305586174;913.2511340700562;918.4122161816442;925.9907400926039;4315.827366905289;4340.110277393127;4447.510773814878;4528.750918699503;4598.103369842848;4916.06594746997;4917.041176970456;4917.729524876167;4921.581665558396;4922.089413458521;4584.312353147055;4596.839110631178;4601.3558101865765;4602.562756538124;4606.055758569485;2520.092766604021;2609.06073813427;2673.492036513095;2673.5454972654306;2675.745804148603;4813.879653008703;4916.803327866845;4986.426560945538;5039;5059.443461219263;4979.103761322996;5001.174806157016;5020.046191223656;5023.34696489454;5023.397192336943;4759.381280433521;4792.292354819516;4885.316055752198;5043.193089105652;5052.46949070357;4887.7556122194455;4900.607951363811;4905.828296009765;4905.950940490549;4913.164761990542;4788.928166077581;4800.231962886061;4800.783964721526;4804.543568361;4813.542713442217;4967.7516124192925;5054.772535235997;5058.620603454682;5063.594912407072;5072;3243.01088168111;3258.3483303338953;3437.1407148212948;3476.652334766503;3480.8781692640637;4809.927851082307;4812.614990800657;4879.048585525788;4880;4887.780049897834;4408.743508100176;4535.183666939851;4609.608183304456;4684.227102528181;4714.198586240416;4457.576530229623;4473.776311184362;4551.453825540956;4612.823729948842;4629.722216667011;4546.745956325458;4607.193741095228;4656.278276867091;4670.346151538855;4692.694974826762;4783.545563171493;4802.183628783046;4804.855854324376;4807.471178170468;4809.182438985311;4384.254676705032;4647.349371087983;4741.83403580873;4746.0745154694505;4750
04:09:28 INFO - 2015-10-22 04:09:28,604 DEBUG : BROWSER_OUTPUT: |5;yui.html;678.7488629207194;680.394448940443;682.6552590941574;687.2611942162177;687.7214728035144;11243.718907027318;11410.97301242888;11417.828732568998;11420.371879546621;11464.828027579582;10473;10695.411752353617;10737.731556711085;10787.56849726012;10793.892061079387;607.5989846701167;617.9443850053494;618.4588485075559;619.2321521313561;619.3744318238589;449.12196655538344;453.9841105561306;605.7819185093363;611.0589691874508;620.6710443464971;642.2678146912524;643.7682434323663;644.2076246217156;647.2459584583978;648.0700195219852;496.2209331349782;502.08870899317986;643.0514990389186;643.8905386084377;644.5262731892055;463.2981033733896;468.5174270501389;471.3824889394904;472.1642692434372;472.3693868685309;9832.852507212398;10210.540525676362;10352.275340726152;10449.373037617692;10473.109785668152;10046.464526121392;10272.28094033418;10322.122619577345;10456.215783816167;10476.058402836927;8514.74455766328;8555.572221388906;8875.511846848422;8911.108889111038;9345.532723363805;3009.5636132760847;3019.5470679398045;3064.1726733781975;3202.9199270018203;3241.497567876983;33084.503732444035;33939.32121357584;33958.83020584906;34449.6555034449;35079.64920350815;8129.79675508117;8229.25936665691;8260;8499.320054395634;8574.871376929352;14183.929080354532;14688.706225875636;14692.706145877022;14704.26478676062;14755.40978360875;5088.847334579968;5571.721413929287;5587.161925711176;5600.831975040674;5917.763289468373;3433.5879694436826;3474.8262586870555;3509.596396414445;3641.2353405785084;3653.5798383186275;2236.0496788864666;2241.688612161871;2278.737945136297;2281.6805647209403;2284.7144106986625;358.8367292881733;369.28174700207836;377.7072768604299;381.57073292545886;385.82252164004313;545.4437070301611;573.5698226330252;576.4725276372072;624.9125122482856;654.5647144648756;7086.219806703408;7327.157376901618;7405.925940740632;7468;7675;375.50058422298275;613.2916481463916;617.2685367839125;617.7229795932532;640.4748106552582;3368.6631336866117;3431.5882094148365;3443.8622455101518;3486.4247399178853;3636.581793093775;44593.777031115285;44624.55375446204;44756.55243447589;44757.55242447534;44808.55191448109;34631.480527792104;36404.81797590995;37235;37262.813685931396;37689.62310376916;31199.532007020367;31394.843025784725;31578.684213158034;31642;31668.366632667217;6865.965670171618;7011.123609548807;7130.001799748143;7228.31331023552;7256.875184346435;22534.098636054376;23057.19299824498;23125.190618328277;23148.884255579287;23168.884155579115;31999.36001280008;32891.8355408231;32913.67086329106;32996.34007319792;33384.83307583447;6444.7744328948265;6484.286728459771;6731.091302674076;6735.898961515679;6899.724011039502;10146.833114191963;10174;10550.366977981346;10667.146628269564;10760.19298552596;9809.901900980898;10290.588376465157;10352.482375881327;10355.378677279386;10573.62992295266;9655.662051828433;9663.855042174233;9815.901840981498;10010.249231307827;10074.89925100769;9509.904900950902;9974.202063834731;10115.898841011496;10119.59521619127;10510.421926794099;8340.665493521008;8382.916170838214;8483.406161568877;8963.372563920462;8985;8257.42198046131;8422.284105850826;8481.363897707573;8525.658973641233;8562.443441176303
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: __end_tp_report
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: __start_cc_report
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,892
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: __end_cc_report
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: __startTimestamp1445512168547__endTimestamp
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: Number of tests: 6
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4117.51 Median:4286.70 stddev:536.83 (12.5%) stddev-sans-first:518.93
04:09:28 INFO - 2015-10-22 04:09:28,605 DEBUG : BROWSER_OUTPUT: Values: 2287.6 2368.9 2406.5 2435.1 2438.3 4114.9 4172.5 4205.1 4274.8 4295.2 4008.6 4231.4 4267.9 4338.5 4347.3 3607.6 3697.6 3743.7 3806.2 3807.8 3615.4 3738.0 3745.1 3749.9 3754.3 3485.3 3556.9 3598.6 3601.7 3690.3 3689.2 3746.5 3765.2 3788.6 3848.2 3469.8 3501.8 3593.3 3738.0 3803.0 4055.5 4112.0 4158.8 4165.4 4240.7 4493.8 4499.7 4555.5 4585.9 4593.4 4528.8 4536.6 4622.0 4626.5 4629.9 4002.1 4104.9 4125.3 4198.8 4205.5 4364.9 4403.4 4599.0 4668.6 4685.3 4244.6 4286.7 4351.4 4515.6 4528.1 4394.5 4445.2 4497.4 4503.6 4539.5 4409.8 4458.1 4484.8 4538.9 4554.5 4457.6 4469.6 4538.9 4548.2 4550.2 2930.1 2936.7 3021.4 3167.3 3171.3 2755.1 2770.7 2774.1 2808.8 2959.4 4021.6 4097.3 4115.4 4208.4 4358.7 4116.5 4221.6 4481.4 4512.5 4630.4 4030.3 4106.6 4171.9 4292.5 4324.0 3856.4 3925.4 3936.3 3937.2 3939.4 4348.3 4382.6 4608.7 4646.8 4762.1 4506.6 4508.8 4588.5 4594.7 4601.9 4626.0 4638.4 4735.5 4737.6 4744.8 4253.4 4326.6 4417.9 4423.5 4429.0 4280.0 4344.2 4507.3 4608.6 4610.2 4383.4 4471.1 4471.2 4473.4 4482.1 4254.3 4271.1 4360.0 4364.6 4396.4 4261.4 4305.7 4334.0 4340.8 4360.5 4148.6 4175.2 4215.5 4376.3 4465.6 4078.9 4154.2 4303.0 4335.6 4381.7
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:42277.23 Median:34335.07 stddev:70708.58 (205.9%) stddev-sans-first:70837.29
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT: Values: 481.2 493.9 494.0 497.1 522.7 25869.7 34286.5 34453.1 34745.8 34814.7 36373.5 37049.6 37127.4 38221.2 38502.2 6368.0 6438.1 8074.3 8264.5 8271.5 10249.7 10509.0 10544.7 10550.3 10553.5 10176.5 10310.2 10347.6 10360.6 10367.9 6037.6 6604.6 10083.7 10179.9 10588.7 9741.5 9891.4 9896.7 9897.3 9907.5 34054.7 34383.7 34809.5 35844.8 36708.0 2599.9 2825.6 3331.6 3380.7 3410.4 25359.4 26245.5 26252.9 26656.2 26942.3 2189.9 2211.8 2263.7 2267.5 2327.3 218456.0 221865.0 222640.0 228610.0 232378.0 43569.0 44592.8 52170.2 53021.5 53110.0 58858.7 59918.7 60633.0 60678.7 60713.7 12250.6 12314.6 12348.3 12378.4 12394.4 7489.3 7527.5 7541.1 7547.1 8818.3 14359.4 17351.4 17465.0 17556.4 17934.7 7824.3 8058.1 8073.3 8155.0 8156.4 765.7 805.5 837.4 883.5 962.2 25236.9 25808.4 25980.0 26013.7 26084.7 902.3 919.5 925.2 958.8 994.6 12767.5 12952.6 13223.5 13416.5 13556.7 322571.0 328140.0 335135.0 352971.0 354355.0 46643.0 47137.3 47177.0 47187.8 47276.8 996.4 1057.4 1072.0 1078.8 1123.5 25223.9 25343.6 25839.0 26001.5 26155.6 36134.3 36340.6 37981.4 38027.4 38185.4 213740.7 218403.0 218647.0 219862.0 274673.0 36989.3 37348.0 37465.3 37500.4 37514.2 40720.6 41048.0 41159.0 41370.8 41580.4 31570.4 33651.0 33710.0 33750.3 34000.3 35695.5 36134.5 36369.3 37434.1 37525.6 27027.5 28393.4 32438.7 36080.8 36942.6 30267.0 33449.0 33519.0 34134.0 34155.8 24268.0 24336.0 24527.6 24983.6 25393.6
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:19608.94 Median:4517.99 stddev:47537.07 (1052.2%) stddev-sans-first:47648.30
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT: Values: 294.7 297.2 306.7 309.8 315.0 11617.2 13669.8 14861.3 15379.8 15821.2 14402.3 14548.3 14719.3 14749.8 15435.2 4990.7 5112.1 5185.5 5243.6 5249.9 3441.2 3539.3 5198.1 5770.2 5970.6 5499.8 5545.5 5576.7 5629.0 5893.5 5677.3 5746.8 5747.6 5843.0 5878.3 4889.5 4894.2 4897.0 4902.9 4920.5 18000.6 18098.7 18838.2 19378.0 19515.4 1473.6 1525.0 1565.3 1685.3 1728.0 8083.8 8669.0 8816.5 8999.7 9069.9 1423.5 1426.2 1465.8 1480.6 1497.6 51941.7 56455.7 56817.0 56896.7 57550.7 5298.3 5465.5 5467.5 5483.8 5515.2 6396.4 6482.3 6485.4 6514.2 6542.9 4450.1 4458.0 4476.2 4480.6 4555.3 4395.3 4649.8 4703.8 4707.4 4733.9 2431.0 2578.7 2583.3 2611.9 2615.3 911.5 912.7 922.0 962.3 976.8 375.5 381.8 383.4 388.4 395.5 9837.9 10198.6 10448.0 10494.7 10671.5 354.6 377.7 386.7 396.7 413.7 3872.1 5274.2 5530.9 5547.7 5607.1 281874.0 282986.0 286040.0 286296.0 288195.0 65566.7 65940.7 67895.7 68363.3 69941.0 417.3 417.8 418.1 426.1 437.8 9449.6 9468.9 9840.0 9882.3 9963.1 34388.1 34632.3 34898.3 35192.0 35728.3 46078.0 46746.3 46855.3 47377.8 53062.7 12263.2 12344.2 12952.6 13277.5 13291.7 9976.3 10193.7 10455.3 10559.2 10587.6 7863.0 8106.6 8519.7 8833.2 9084.5 8172.8 8869.1 8958.6 9049.8 9357.7 8742.4 8969.3 9224.8 9572.7 9601.0 12764.3 13048.5 13534.9 13535.6 13613.3 13867.9 13943.7 14358.3 14362.8 14498.0
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,608 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5225.52 Median:6400.20 stddev:2880.43 (45.0%) stddev-sans-first:2871.84
04:09:28 INFO - 2015-10-22 04:09:28,609 DEBUG : BROWSER_OUTPUT: Values: 1102.8 1283.7 1323.5 1453.5 1480.5 6726.8 7616.5 7711.0 7725.1 7740.3 7701.2 7713.7 7739.5 7764.3 7772.3 2087.7 2112.5 2112.8 2113.1 2113.8 2022.0 2045.0 2073.8 2079.0 2079.2 2014.1 2034.6 2044.3 2048.3 2050.4 2027.3 2094.1 2103.4 2107.7 2109.5 2038.6 2060.5 2068.5 2069.5 2076.1 6600.6 6861.7 7657.5 7693.8 7703.6 4249.7 4375.4 4378.8 4383.6 4383.6 2656.4 2699.7 2728.7 2732.4 2735.3 807.6 829.6 832.5 832.9 860.8 10396.1 10397.6 10611.8 11045.6 11104.2 4046.0 4074.9 4148.1 4188.9 4198.1 9357.3 9390.3 9396.7 9412.6 9412.9 4255.2 4506.3 4557.5 4564.9 4569.0 4053.4 4062.8 4069.7 4070.9 4071.1 3206.0 3325.1 3358.8 3396.6 3408.6 1012.6 1023.6 1024.3 1029.6 1055.2 770.6 800.8 811.6 811.8 819.0 7327.1 7363.8 7376.4 7378.5 7385.3 414.2 415.2 425.0 427.6 435.0 5324.3 5335.7 5386.7 5485.0 5656.1 8393.4 8655.9 8929.4 9713.5 9730.4 9179.7 9206.0 9615.9 9634.0 9639.7 6907.1 6965.1 6965.6 6972.6 6979.7 6112.4 6199.1 6578.6 6600.9 6608.0 7981.8 8006.1 8017.1 8024.6 8033.3 8336.2 8481.0 8483.9 8488.0 8502.4 6381.7 6418.7 6420.5 6429.2 6435.3 6952.6 7103.9 7131.0 7259.9 7312.5 6695.1 6697.7 6858.2 6871.2 6894.3 6549.7 6568.0 6703.6 6718.5 6808.6 6840.8 6869.6 6870.5 6878.6 6885.1 6145.5 6170.5 6193.8 6194.9 6199.5 6157.9 6212.9 6302.9 6456.1 6466.7
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:3980.44 Median:4539.69 stddev:1158.50 (25.5%) stddev-sans-first:1127.87
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT: Values: 274.9 279.5 285.7 289.0 292.6 4544.2 4552.5 4566.6 4567.1 4571.5 4376.6 4425.2 4458.2 4504.8 4506.4 3476.6 3483.2 3483.5 3483.6 3484.0 3207.8 3266.3 3272.8 3387.2 3482.1 3373.2 3376.4 3409.5 3435.0 3455.1 3428.9 3432.1 3437.6 3440.5 3453.1 3093.9 3094.0 3096.8 3098.0 3098.2 4401.5 4403.9 4405.8 4421.8 4424.3 4065.4 4070.6 4073.5 4074.2 4074.6 4506.7 4724.2 4727.3 4728.1 4730.8 3361.7 3499.7 3506.8 3512.7 3513.6 4513.3 4663.6 4672.3 4930.0 4957.5 3602.3 3603.5 3605.0 3606.1 3608.2 3422.8 3433.5 3521.5 3644.4 3650.3 4065.5 4098.0 4107.3 4114.3 4114.9 4754.8 4770.8 4776.2 4777.4 4788.5 1214.7 1225.0 1231.1 1290.2 1290.5 896.3 907.5 913.3 918.4 926.0 4315.8 4340.1 4447.5 4528.8 4598.1 4916.1 4917.0 4917.7 4921.6 4922.1 4584.3 4596.8 4601.4 4602.6 4606.1 2520.1 2609.1 2673.5 2673.5 2675.7 4813.9 4916.8 4986.4 5039.0 5059.4 4979.1 5001.2 5020.0 5023.3 5023.4 4759.4 4792.3 4885.3 5043.2 5052.5 4887.8 4900.6 4905.8 4906.0 4913.2 4788.9 4800.2 4800.8 4804.5 4813.5 4967.8 5054.8 5058.6 5063.6 5072.0 3243.0 3258.3 3437.1 3476.7 3480.9 4809.9 4812.6 4879.0 4880.0 4887.8 4408.7 4535.2 4609.6 4684.2 4714.2 4457.6 4473.8 4551.5 4612.8 4629.7 4546.7 4607.2 4656.3 4670.3 4692.7 4783.5 4802.2 4804.9 4807.5 4809.2 4384.3 4647.3 4741.8 4746.1 4750.0
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT:
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10531.59 Median:44690.55 stddev:11472.98 (25.7%) stddev-sans-first:11481.31
04:09:28 INFO - 2015-10-22 04:09:28,611 DEBUG : BROWSER_OUTPUT: Values: 678.7 680.4 682.7 687.3 687.7 11243.7 11411.0 11417.8 11420.4 11464.8 10473.0 10695.4 10737.7 10787.6 10793.9 607.6 617.9 618.5 619.2 619.4 449.1 454.0 605.8 611.1 620.7 642.3 643.8 644.2 647.2 648.1 496.2 502.1 643.1 643.9 644.5 463.3 468.5 471.4 472.2 472.4 9832.9 10210.5 10352.3 10449.4 10473.1 10046.5 10272.3 10322.1 10456.2 10476.1 8514.7 8555.6 8875.5 8911.1 9345.5 3009.6 3019.5 3064.2 3202.9 3241.5 33084.5 33939.3 33958.8 34449.7 35079.6 8129.8 8229.3 8260.0 8499.3 8574.9 14183.9 14688.7 14692.7 14704.3 14755.4 5088.8 5571.7 5587.2 5600.8 5917.8 3433.6 3474.8 3509.6 3641.2 3653.6 2236.0 2241.7 2278.7 2281.7 2284.7 358.8 369.3 377.7 381.6 385.8 545.4 573.6 576.5 624.9 654.6 7086.2 7327.2 7405.9 7468.0 7675.0 375.5 613.3 617.3 617.7 640.5 3368.7 3431.6 3443.9 3486.4 3636.6 44593.8 44624.6 44756.6 44757.6 44808.6 34631.5 36404.8 37235.0 37262.8 37689.6 31199.5 31394.8 31578.7 31642.0 31668.4 6866.0 7011.1 7130.0 7228.3 7256.9 22534.1 23057.2 23125.2 23148.9 23168.9 31999.4 32891.8 32913.7 32996.3 33384.8 6444.8 6484.3 6731.1 6735.9 6899.7 10146.8 10174.0 10550.4 10667.1 10760.2 9809.9 10290.6 10352.5 10355.4 10573.6 9655.7 9663.9 9815.9 10010.2 10074.9 9509.9 9974.2 10115.9 10119.6 10510.4 8340.7 8382.9 8483.4 8963.4 8985.0 8257.4 8422.3 8481.4 8525.7 8562.4
04:09:28 INFO - 2015-10-22 04:09:28,612 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
04:09:28 INFO - 2015-10-22 04:09:28,612 DEBUG : BROWSER_OUTPUT:
04:09:29 INFO - 2015-10-22 04:09:29,091 INFO : Browser exited with error code: 0
04:09:29 INFO - 2015-10-22 04:09:29,828 INFO : Completed test dromaeo_css (00:18:46)
04:09:29 INFO - 2015-10-22 04:09:29,828 INFO : Starting test kraken
04:09:29 INFO - 2015-10-22 04:09:29,828 DEBUG : operating with platform_type : w7_
04:09:29 INFO - 2015-10-22 04:09:29,828 INFO : Initialising browser for kraken test...
04:09:29 INFO - 2015-10-22 04:09:29,855 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppeylrn\profile http://localhost:49779/getInfo.html
04:09:34 INFO - 2015-10-22 04:09:34,970 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
04:09:34 INFO - 2015-10-22 04:09:34,970 DEBUG : BROWSER_OUTPUT: colorDepth:24
04:09:34 INFO - 2015-10-22 04:09:34,970 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
04:09:34 INFO - 2015-10-22 04:09:34,970 DEBUG : BROWSER_OUTPUT: __metrics
04:09:35 INFO - 2015-10-22 04:09:35,045 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
04:09:36 INFO - 2015-10-22 04:09:36,226 INFO : Browser initialized.
04:09:36 INFO - 2015-10-22 04:09:36,226 INFO : Running cycle 1/1 for kraken test...
04:09:36 INFO - 2015-10-22 04:09:36,226 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppeylrn\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
04:09:47 INFO - 2015-10-22 04:09:47,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 141586432
04:09:47 INFO - 2015-10-22 04:09:47,880 DEBUG : BROWSER_OUTPUT:
04:09:50 INFO - 2015-10-22 04:09:50,421 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49779/tests/kraken/driver.html?testName=audio-beat-detection)
04:09:51 INFO - 2015-10-22 04:09:51,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 175243264
04:09:51 INFO - 2015-10-22 04:09:51,003 DEBUG : BROWSER_OUTPUT:
04:09:53 INFO - 2015-10-22 04:09:53,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49779/tests/kraken/driver.html?testName=audio-dft)
04:09:54 INFO - 2015-10-22 04:09:54,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 177221632
04:09:54 INFO - 2015-10-22 04:09:54,365 DEBUG : BROWSER_OUTPUT:
04:09:57 INFO - 2015-10-22 04:09:57,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49779/tests/kraken/driver.html?testName=audio-fft)
04:09:57 INFO - 2015-10-22 04:09:57,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 171003904
04:09:57 INFO - 2015-10-22 04:09:57,700 DEBUG : BROWSER_OUTPUT:
04:09:59 INFO - 2015-10-22 04:09:59,864 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49779/tests/kraken/driver.html?testName=audio-oscillator)
04:10:00 INFO - 2015-10-22 04:10:00,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 167669760
04:10:00 INFO - 2015-10-22 04:10:00,438 DEBUG : BROWSER_OUTPUT:
04:10:02 INFO - 2015-10-22 04:10:02,740 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49779/tests/kraken/driver.html?testName=imaging-gaussian-blur)
04:10:03 INFO - 2015-10-22 04:10:03,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 167743488
04:10:03 INFO - 2015-10-22 04:10:03,299 DEBUG : BROWSER_OUTPUT:
04:10:06 INFO - 2015-10-22 04:10:06,474 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49779/tests/kraken/driver.html?testName=imaging-darkroom)
04:10:07 INFO - 2015-10-22 04:10:07,055 DEBUG : BROWSER_OUTPUT: RSS: Main: 164352000
04:10:07 INFO - 2015-10-22 04:10:07,055 DEBUG : BROWSER_OUTPUT:
04:10:10 INFO - 2015-10-22 04:10:10,437 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49779/tests/kraken/driver.html?testName=imaging-desaturate)
04:10:11 INFO - 2015-10-22 04:10:11,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 165052416
04:10:11 INFO - 2015-10-22 04:10:11,013 DEBUG : BROWSER_OUTPUT:
04:10:13 INFO - 2015-10-22 04:10:13,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49779/tests/kraken/driver.html?testName=json-parse-financial)
04:10:14 INFO - 2015-10-22 04:10:14,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 164859904
04:10:14 INFO - 2015-10-22 04:10:14,431 DEBUG : BROWSER_OUTPUT:
04:10:16 INFO - 2015-10-22 04:10:16,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49779/tests/kraken/driver.html?testName=json-stringify-tinderbox)
04:10:16 INFO - 2015-10-22 04:10:16,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 194502656
04:10:16 INFO - 2015-10-22 04:10:16,854 DEBUG : BROWSER_OUTPUT:
04:10:18 INFO - 2015-10-22 04:10:18,801 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-aes)
04:10:19 INFO - 2015-10-22 04:10:19,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 199704576
04:10:19 INFO - 2015-10-22 04:10:19,375 DEBUG : BROWSER_OUTPUT:
04:10:21 INFO - 2015-10-22 04:10:21,790 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-ccm)
04:10:22 INFO - 2015-10-22 04:10:22,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 205434880
04:10:22 INFO - 2015-10-22 04:10:22,365 DEBUG : BROWSER_OUTPUT:
04:10:24 INFO - 2015-10-22 04:10:24,986 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
04:10:25 INFO - 2015-10-22 04:10:25,552 DEBUG : BROWSER_OUTPUT: RSS: Main: 207204352
04:10:25 INFO - 2015-10-22 04:10:25,552 DEBUG : BROWSER_OUTPUT:
04:10:28 INFO - 2015-10-22 04:10:28,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
04:10:29 INFO - 2015-10-22 04:10:29,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 206761984
04:10:29 INFO - 2015-10-22 04:10:29,323 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49779/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49779/tests/kraken/driver.html?testName=ai-astar)
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 252280832
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: __start_tp_report
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |0;ai-astar;96;98;98;89;98;108;98;98;98;96
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;145;157;169;142;141;150;149;166;142
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |2;audio-dft;151;151;148;145;145;145;146;144;144;144
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |3;audio-fft;95;99;107;110;78;79;78;80;106;81
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;101;100;117;100;101;106;129;101;101;130
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117
04:10:31 INFO - 2015-10-22 04:10:31,585 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;137;138;137;137;137;136;138;136;137;137
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;89;90;89;90;89;89;89;89;90;89
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;79;79;80;78;79;79;79;79;90
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;74;74;80;74;74;74;74;75;74;74
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;99;100;101;99;99;100;99;99;103;101
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;129;127;131;130;135;130;134;139;132;131
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;196;199;198;201;202;193;198;199;199;191
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;94;92;92;91;93;90;92;91;92;93
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: __end_tp_report
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: __start_cc_report
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,956
04:10:31 INFO - 2015-10-22 04:10:31,586 DEBUG : BROWSER_OUTPUT: __end_cc_report
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT: __startTimestamp1445512231587__endTimestamp
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT: Number of tests: 14
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:97.70 Median:98.00 stddev:4.57 (4.7%) stddev-sans-first:4.81
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT: Values: 96.0 98.0 98.0 89.0 98.0 108.0 98.0 98.0 98.0 96.0
04:10:31 INFO - 2015-10-22 04:10:31,588 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:150.50 Median:149.50 stddev:10.19 (6.8%) stddev-sans-first:10.53
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT: Values: 144.0 145.0 157.0 169.0 142.0 141.0 150.0 149.0 166.0 142.0
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:146.30 Median:145.50 stddev:2.75 (1.9%) stddev-sans-first:2.33
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT: Values: 151.0 151.0 148.0 145.0 145.0 145.0 146.0 144.0 144.0 144.0
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,589 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:91.30 Median:79.50 stddev:13.43 (16.9%) stddev-sans-first:14.18
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT: Values: 95.0 99.0 107.0 110.0 78.0 79.0 78.0 80.0 106.0 81.0
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.60 Median:103.50 stddev:12.16 (11.7%) stddev-sans-first:12.58
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT: Values: 101.0 100.0 117.0 100.0 101.0 106.0 129.0 101.0 101.0 130.0
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,591 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:137.00 Median:137.00 stddev:0.67 (0.5%) stddev-sans-first:0.71
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 137.0 138.0 137.0 137.0 137.0 136.0 138.0 136.0 137.0 137.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:89.30 Median:89.00 stddev:0.48 (0.5%) stddev-sans-first:0.50
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 89.0 90.0 89.0 90.0 89.0 89.0 89.0 89.0 90.0 89.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.10 Median:79.00 stddev:3.51 (4.4%) stddev-sans-first:3.70
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 79.0 80.0 78.0 79.0 79.0 79.0 79.0 90.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:74.00 stddev:1.89 (2.6%) stddev-sans-first:1.99
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 74.0 74.0 80.0 74.0 74.0 74.0 74.0 75.0 74.0 74.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:100.00 Median:99.00 stddev:1.33 (1.3%) stddev-sans-first:1.36
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 99.0 100.0 101.0 99.0 99.0 100.0 99.0 99.0 103.0 101.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:131.80 Median:131.50 stddev:3.43 (2.6%) stddev-sans-first:3.48
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT: Values: 129.0 127.0 131.0 130.0 135.0 130.0 134.0 139.0 132.0 131.0
04:10:31 INFO - 2015-10-22 04:10:31,592 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,594 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.60 Median:199.00 stddev:3.41 (1.7%) stddev-sans-first:3.56
04:10:31 INFO - 2015-10-22 04:10:31,594 DEBUG : BROWSER_OUTPUT: Values: 196.0 199.0 198.0 201.0 202.0 193.0 198.0 199.0 199.0 191.0
04:10:31 INFO - 2015-10-22 04:10:31,594 DEBUG : BROWSER_OUTPUT:
04:10:31 INFO - 2015-10-22 04:10:31,594 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:92.00 Median:92.00 stddev:1.15 (1.3%) stddev-sans-first:0.97
04:10:31 INFO - 2015-10-22 04:10:31,595 DEBUG : BROWSER_OUTPUT: Values: 94.0 92.0 92.0 91.0 93.0 90.0 92.0 91.0 92.0 93.0
04:10:31 INFO - 2015-10-22 04:10:31,595 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
04:10:31 INFO - 2015-10-22 04:10:31,595 DEBUG : BROWSER_OUTPUT:
04:10:32 INFO - 2015-10-22 04:10:32,309 INFO : Browser exited with error code: 0
04:10:33 INFO - 2015-10-22 04:10:33,072 INFO : Completed test kraken (00:01:03)
04:10:33 INFO - 2015-10-22 04:10:33,072 INFO : Starting test v8_7
04:10:33 INFO - 2015-10-22 04:10:33,072 DEBUG : operating with platform_type : w7_
04:10:33 INFO - 2015-10-22 04:10:33,072 INFO : Initialising browser for v8_7 test...
04:10:33 INFO - 2015-10-22 04:10:33,098 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpzqql1w\profile http://localhost:49779/getInfo.html
04:10:38 INFO - 2015-10-22 04:10:38,154 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
04:10:38 INFO - 2015-10-22 04:10:38,154 DEBUG : BROWSER_OUTPUT: colorDepth:24
04:10:38 INFO - 2015-10-22 04:10:38,154 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
04:10:38 INFO - 2015-10-22 04:10:38,154 DEBUG : BROWSER_OUTPUT: __metrics
04:10:38 INFO - 2015-10-22 04:10:38,213 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
04:10:38 INFO - 2015-10-22 04:10:38,868 DEBUG : BROWSER_OUTPUT: console.error:
04:10:38 INFO - 2015-10-22 04:10:38,868 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]
04:10:38 INFO - 2015-10-22 04:10:38,868 DEBUG : BROWSER_OUTPUT: Stack:
04:10:38 INFO - 2015-10-22 04:10:38,868 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
04:10:38 INFO - 2015-10-22 04:10:38,868 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
04:10:38 INFO - 2015-10-22 04:10:38,868 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
04:10:38 INFO - 2015-10-22 04:10:38,869 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5657.45
04:11:15 INFO - RETURN: kraken: 1613.80
04:11:15 INFO - RETURN: v8_7: 19198.34
04:11:15 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,131,25]]", "result": "19198.34"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,131,25]]", "result": "1613.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,131,25]]", "result": "5657.45"}}}
04:11:15 INFO - Return code: 0
04:11:15 INFO - # TBPL SUCCESS #
04:11:15 INFO - Running post-action listener: _resource_record_post_action
04:11:15 INFO - Running post-run listener: _resource_record_post_run
04:11:16 INFO - Total resource usage - Wall time: 1242s; CPU: 13.0%; Read bytes: 139550208; Write bytes: 445912064; Read time: 1440930; Write time: 4570310
04:11:16 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 1820160; Read time: 0; Write time: 47010
04:11:16 INFO - run-tests - Wall time: 1241s; CPU: 13.0%; Read bytes: 139550208; Write bytes: 422931968; Read time: 1440930; Write time: 4504450
04:11:16 INFO - Running post-run listener: _upload_blobber_files
04:11:16 INFO - Blob upload gear active.
04:11:16 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
04:11:16 INFO - Copying logs to upload dir...
04:11:16 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=1463.941000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 10 secs) (at 2015-10-22 04:12:02.633832) =========
========= Started set props: build_url (results: 0, elapsed: 3 secs) (at 2015-10-22 04:12:02.635263) =========
'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-128
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-128
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-128
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/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/8-LVfCh1Qiq0ET0RglObTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 3 secs) (at 2015-10-22 04:12:05.769735) =========
========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-10-22 04:12:05.770097) =========
'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-128
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-128
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-128
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: 1 secs) (at 2015-10-22 04:12:07.039450) =========
========= Started reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-22 04:12:07.039808) =========
========= Finished reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-22 04:12:08.155486) =========