builder: b2g-inbound_win7-ix_test-dromaeojs
slave: t-w732-ix-155
starttime: 1445935037.63
results: success (0)
buildid: 20151027005022
builduid: 83f5ed855e884f29babb505302f4cbb0
revision: afa51178a780763be38c80564ed234fb05a42075
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 01:37:17.631330) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 01:37:17.635883) =========
========= Started set props: basedir (results: 0, elapsed: 11 secs) (at 2015-10-27 01:37:17.636422) =========
'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-155
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-155
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-155
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.570000
basedir: 'C:\\slave\\test'
========= Finished set props: basedir (results: 0, elapsed: 11 secs) (at 2015-10-27 01:37:28.765512) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 40 secs) (at 2015-10-27 01:37:28.765866) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 40 secs) (at 2015-10-27 01:38:08.948351) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-27 01:38:08.948653) =========
'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-155
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-155
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-155
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.601000
========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-10-27 01:38:11.258941) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 01:38:11.259241) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 01:38:11.259635) =========
========= Started 'bash -c ...' (results: 0, elapsed: 18 secs) (at 2015-10-27 01:38:11.259955) =========
'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-155
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-155
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-155
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
--01:38:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 11.46 MB/s
01:38:19 (11.46 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.665000
========= Finished 'bash -c ...' (results: 0, elapsed: 18 secs) (at 2015-10-27 01:38:30.241461) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 21 secs) (at 2015-10-27 01:38:30.245828) =========
'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-155
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-155
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-155
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=11.901000
========= Finished 'rm -rf ...' (results: 0, elapsed: 21 secs) (at 2015-10-27 01:38:52.018969) =========
========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-27 01:38:52.020702) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev afa51178a780763be38c80564ed234fb05a42075 --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev afa51178a780763be38c80564ed234fb05a42075 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-155
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-155
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-155
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-10-27 01:39:02,447 truncating revision to first 12 chars
2015-10-27 01:39:02,447 Setting DEBUG logging.
2015-10-27 01:39:02,447 attempt 1/10
2015-10-27 01:39:02,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/afa51178a780?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-10-27 01:39:03,947 unpacking tar archive at: b2g-inbound-afa51178a780/testing/mozharness/
program finished with exit code 0
elapsedTime=2.083000
========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2015-10-27 01:39:04.595435) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2015-10-27 01:39:04.595781) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 8 secs) (at 2015-10-27 01:39:13.406284) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 01:39:13.409579) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 01:39:13.410485) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 28 secs) (at 2015-10-27 01:39:13.410825) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'B2G-Inbound-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'B2G-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', 'B2G-Inbound-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound-Non-PGO']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-155
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-155
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-155
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
01:39:15 INFO - MultiFileLogger online at 20151027 01:39:15 in C:\slave\test
01:39:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound-Non-PGO
01:39:15 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
01:39:15 INFO - {'append_to_log': False,
01:39:15 INFO - 'base_work_dir': 'C:\\slave\\test',
01:39:15 INFO - 'blob_upload_branch': 'B2G-Inbound-Non-PGO',
01:39:15 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
01:39:15 INFO - 'branch': 'B2G-Inbound-Non-PGO',
01:39:15 INFO - 'buildbot_json_path': 'buildprops.json',
01:39:15 INFO - 'config_files': ('talos/windows_config.py',),
01:39:15 INFO - 'default_actions': ('clobber',
01:39:15 INFO - 'read-buildbot-config',
01:39:15 INFO - 'download-and-extract',
01:39:15 INFO - 'populate-webroot',
01:39:15 INFO - 'create-virtualenv',
01:39:15 INFO - 'install',
01:39:15 INFO - 'run-tests'),
01:39:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
01:39:15 INFO - 'download_minidump_stackwalk': True,
01:39:15 INFO - 'download_symbols': 'ondemand',
01:39:15 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
01:39:15 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
01:39:15 INFO - 'hg': 'c:/mozilla-build/hg/hg',
01:39:15 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
01:39:15 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
01:39:15 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
01:39:15 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
01:39:15 INFO - 'C:/mozilla-build/tooltool.py'),
01:39:15 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
01:39:15 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
01:39:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
01:39:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
01:39:15 INFO - 'installer_path': 'installer.exe',
01:39:15 INFO - 'log_level': 'info',
01:39:15 INFO - 'log_name': 'talos',
01:39:15 INFO - 'log_to_console': True,
01:39:15 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
01:39:15 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
01:39:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
01:39:15 INFO - 'opt_config_files': (),
01:39:15 INFO - 'pip_index': False,
01:39:15 INFO - 'sps_profile': False,
01:39:15 INFO - 'sps_profile_interval': 0,
01:39:15 INFO - 'suite': 'dromaeojs',
01:39:15 INFO - 'system_bits': '32',
01:39:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
01:39:15 INFO - 'title': 'T-W732-IX-155',
01:39:15 INFO - 'use_talos_json': True,
01:39:15 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
01:39:15 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
01:39:15 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
01:39:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
01:39:15 INFO - 'work_dir': 'build'}
01:39:15 INFO - #####
01:39:15 INFO - ##### Running clobber step.
01:39:15 INFO - #####
01:39:15 INFO - Running pre-action listener: _resource_record_pre_action
01:39:15 INFO - Running main action method: clobber
01:39:15 INFO - rmtree: C:\slave\test\build
01:39:15 INFO - Using _rmtree_windows ...
01:39:15 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
01:40:29 INFO - Running post-action listener: _resource_record_post_action
01:40:29 INFO - #####
01:40:29 INFO - ##### Running read-buildbot-config step.
01:40:29 INFO - #####
01:40:29 INFO - Running pre-action listener: _resource_record_pre_action
01:40:29 INFO - Running main action method: read_buildbot_config
01:40:29 INFO - Using buildbot properties:
01:40:29 INFO - {
01:40:29 INFO - "properties": {
01:40:29 INFO - "buildnumber": 276,
01:40:29 INFO - "product": "firefox",
01:40:29 INFO - "script_repo_revision": "production",
01:40:29 INFO - "builddir": "b2g-inbound_win7-ix_test-dromaeojs",
01:40:29 INFO - "repository": "",
01:40:29 INFO - "buildername": "Windows 7 32-bit b2g-inbound talos dromaeojs",
01:40:29 INFO - "buildid": "20151027005022",
01:40:29 INFO - "slavename": "t-w732-ix-155",
01:40:29 INFO - "pgo_build": "False",
01:40:29 INFO - "basedir": "C:\\slave\\test",
01:40:29 INFO - "project": "",
01:40:29 INFO - "platform": "win7-ix",
01:40:29 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
01:40:29 INFO - "slavebuilddir": "test",
01:40:29 INFO - "scheduler": "tests-b2g-inbound-win32-talos",
01:40:29 INFO - "branch": "b2g-inbound",
01:40:29 INFO - "repo_path": "integration/b2g-inbound",
01:40:29 INFO - "stage_platform": "win32",
01:40:29 INFO - "builduid": "83f5ed855e884f29babb505302f4cbb0",
01:40:29 INFO - "revision": "afa51178a780763be38c80564ed234fb05a42075"
01:40:29 INFO - },
01:40:29 INFO - "sourcestamp": {
01:40:29 INFO - "repository": "",
01:40:29 INFO - "hasPatch": false,
01:40:29 INFO - "project": "",
01:40:29 INFO - "branch": "b2g-inbound-win32-talos",
01:40:29 INFO - "changes": [
01:40:29 INFO - {
01:40:29 INFO - "category": null,
01:40:29 INFO - "files": [
01:40:29 INFO - {
01:40:29 INFO - "url": null,
01:40:29 INFO - "name": "https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip"
01:40:29 INFO - }
01:40:29 INFO - ],
01:40:29 INFO - "repository": "",
01:40:29 INFO - "rev": "afa51178a780763be38c80564ed234fb05a42075",
01:40:29 INFO - "who": "yhuang@mozilla.com",
01:40:29 INFO - "when": 1445934403,
01:40:29 INFO - "number": 6578660,
01:40:29 INFO - "comments": "Backout e54c3b878993",
01:40:29 INFO - "project": "",
01:40:29 INFO - "at": "Tue 27 Oct 2015 01:26:43",
01:40:29 INFO - "branch": "b2g-inbound-win32-talos",
01:40:29 INFO - "revlink": "",
01:40:29 INFO - "properties": [
01:40:29 INFO - [
01:40:29 INFO - "buildid",
01:40:29 INFO - "20151027005022",
01:40:29 INFO - "Change"
01:40:29 INFO - ],
01:40:29 INFO - [
01:40:29 INFO - "builduid",
01:40:29 INFO - "83f5ed855e884f29babb505302f4cbb0",
01:40:29 INFO - "Change"
01:40:29 INFO - ],
01:40:29 INFO - [
01:40:29 INFO - "pgo_build",
01:40:29 INFO - "False",
01:40:29 INFO - "Change"
01:40:29 INFO - ]
01:40:29 INFO - ],
01:40:29 INFO - "revision": "afa51178a780763be38c80564ed234fb05a42075"
01:40:29 INFO - }
01:40:29 INFO - ],
01:40:29 INFO - "revision": "afa51178a780763be38c80564ed234fb05a42075"
01:40:29 INFO - }
01:40:29 INFO - }
01:40:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip.
01:40:29 INFO - Running post-action listener: _resource_record_post_action
01:40:29 INFO - #####
01:40:29 INFO - ##### Running download-and-extract step.
01:40:29 INFO - #####
01:40:29 INFO - Running pre-action listener: _resource_record_pre_action
01:40:29 INFO - Running main action method: download_and_extract
01:40:29 INFO - mkdir: C:\slave\test\build\tests
01:40:29 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')]}
01:40:29 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
01:40:29 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/test_packages.json
01:40:29 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
01:40:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
01:40:30 INFO - Downloaded 1221 bytes.
01:40:30 INFO - Reading from file C:\slave\test\build\test_packages.json
01:40:30 INFO - Using the following test package requirements:
01:40:30 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
01:40:30 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'],
01:40:30 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'jsshell-win32.zip'],
01:40:30 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'],
01:40:30 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
01:40:30 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'],
01:40:30 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'],
01:40:30 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'],
01:40:30 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'],
01:40:30 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip',
01:40:30 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']}
01:40:30 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common
01:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
01:40:30 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
01:40:30 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
01:40:30 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:40:31 INFO - Downloaded 18013504 bytes.
01:40:31 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
01:40:31 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip
01:40:31 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
01:41:04 INFO - Return code: 0
01:41:04 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
01:41: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')]}
01:41:04 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
01:41:04 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip
01:41:04 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:05 INFO - Downloaded 18013504 bytes.
01:41:05 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
01:41:05 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip
01:41:05 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
01:41:28 INFO - Return code: 0
01:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:41:28 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
01:41:28 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip
01:41:28 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:30 INFO - Downloaded 11131307 bytes.
01:41:30 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
01:41:30 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip
01:41:30 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
01:41:36 INFO - Return code: 0
01:41: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')]}
01:41:36 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net
01:41:36 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
01:41:36 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip
01:41:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:38 INFO - Downloaded 51515177 bytes.
01:41:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
01:41:38 INFO - mkdir: C:\slave\test\properties
01:41:38 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
01:41:38 INFO - Writing to file C:\slave\test\properties\build_url
01:41:38 INFO - Contents:
01:41:38 INFO - build_url:https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
01:41:38 INFO - Running post-action listener: _resource_record_post_action
01:41:38 INFO - Running post-action listener: set_extra_try_arguments
01:41:38 INFO - #####
01:41:38 INFO - ##### Running populate-webroot step.
01:41:38 INFO - #####
01:41:38 INFO - Running pre-action listener: _resource_record_pre_action
01:41:38 INFO - Running main action method: populate_webroot
01:41:38 INFO - Guessing talos json url...
01:41:38 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')]}
01:41:38 INFO - https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net
01:41:38 INFO - trying https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt
01:41:38 INFO - Downloading https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt
01:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/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
01:41:39 INFO - Downloaded 107 bytes.
01:41:39 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt
01:41:39 INFO - Contents:
01:41:39 INFO - 20151027005022
01:41:39 INFO - https://hg.mozilla.org/integration/b2g-inbound/rev/afa51178a780763be38c80564ed234fb05a42075
01:41: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')]}
01:41:39 INFO - trying https://hg.mozilla.org/integration/b2g-inbound/raw-file/afa51178a7/testing/talos/talos.json
01:41:39 INFO - Downloading https://hg.mozilla.org/integration/b2g-inbound/raw-file/afa51178a7/testing/talos/talos.json to C:\slave\test\build\talos.json
01:41:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/b2g-inbound/raw-file/afa51178a7/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1
01:41:40 INFO - Downloaded 4264 bytes.
01:41:40 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
01:41:40 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
01:41:40 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
01:41:40 INFO - u'../fennec_ids.txt'],
01:41:40 INFO - u'tests': [u'tcheck2']},
01:41:40 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
01:41:40 INFO - u'--tppagecycles',
01:41:40 INFO - u'7'],
01:41:40 INFO - u'tests': [u'tsvgm']}},
01:41:40 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
01:41:40 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'tresize', u'tcanvasmark']},
01:41:40 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
01:41:40 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'dromaeo_css',
01:41:40 INFO - u'kraken',
01:41:40 INFO - u'v8_7']},
01:41:40 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
01:41:40 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
01:41:40 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'tests': [u'damp', u'tps']},
01:41:40 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'damp', u'tps']},
01:41:40 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
01:41:40 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
01:41:40 INFO - u'other': {u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'other_l64': {u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'other_nol64': {u'tests': [u'a11yr',
01:41:40 INFO - u'ts_paint',
01:41:40 INFO - u'tpaint',
01:41:40 INFO - u'sessionrestore',
01:41:40 INFO - u'sessionrestore_no_auto_restore']},
01:41:40 INFO - u'svgr': {u'tests': [u'tsvgx',
01:41:40 INFO - u'tsvgr_opacity',
01:41:40 INFO - u'tart',
01:41:40 INFO - u'tscrollx',
01:41:40 INFO - u'cart']},
01:41:40 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'tsvgx',
01:41:40 INFO - u'tsvgr_opacity',
01:41:40 INFO - u'tart',
01:41:40 INFO - u'tscrollx',
01:41:40 INFO - u'cart']},
01:41:40 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'tests': [u'tp5o']},
01:41:40 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'talos_options': [u'--e10s'],
01:41:40 INFO - u'tests': [u'tp5o']},
01:41:40 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'talos_options': [u'--xperf_path',
01:41:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
01:41:40 INFO - u'tests': [u'tp5n']},
01:41:40 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
01:41:40 INFO - u'talos_options': [u'--e10s',
01:41:40 INFO - u'--xperf_path',
01:41:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
01:41:40 INFO - u'C:/slave/talos-data/talos/xperf.config'],
01:41:40 INFO - u'tests': [u'tp5n']}},
01:41:40 INFO - u'talos.zip': {u'path': u'',
01:41:40 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
01:41:40 INFO - Running post-action listener: _resource_record_post_action
01:41:40 INFO - #####
01:41:40 INFO - ##### Running create-virtualenv step.
01:41:40 INFO - #####
01:41:40 INFO - Running pre-action listener: _resource_record_pre_action
01:41:40 INFO - Running main action method: create_virtualenv
01:41:40 INFO - Creating virtualenv C:\slave\test\build/venv
01:41:40 INFO - mkdir: C:\slave\test\build\venv\Scripts
01:41:40 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
01:41:40 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'!
01:41:40 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
01:41:40 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
01:41:46 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
01:41:49 INFO - Installing distribute......................................................................................................................................................................................done.
01:41:50 INFO - Return code: 0
01:41:50 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
01:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:41:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:41:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:41:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:41:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
01:41:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
01:41:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:41:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:41:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:41:50 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:41:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:41:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:41:50 INFO - 'DCLOCATION': 'SCL3',
01:41:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:41:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:41:50 INFO - 'HOMEDRIVE': 'C:',
01:41:50 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:41:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:41:50 INFO - 'KTS_VERSION': '1.19c',
01:41:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:41:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:41:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:41:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:41:50 INFO - 'MOZ_NO_REMOTE': '1',
01:41:50 INFO - 'NO_EM_RESTART': '1',
01:41:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:41:50 INFO - 'OS': 'Windows_NT',
01:41:50 INFO - 'OURDRIVE': 'C:',
01:41:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:41:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:41:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:41:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:41:50 INFO - 'PROCESSOR_LEVEL': '6',
01:41:50 INFO - 'PROCESSOR_REVISION': '1e05',
01:41:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:41:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:41:50 INFO - 'PROMPT': '$P$G',
01:41:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:41:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:41:50 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:41:50 INFO - 'PWD': 'C:\\slave\\test',
01:41:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:41:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:41:50 INFO - 'SYSTEMDRIVE': 'C:',
01:41:50 INFO - 'SYSTEMROOT': 'C:\\windows',
01:41:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:50 INFO - 'TEST1': 'testie',
01:41:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:50 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:41:50 INFO - 'USERNAME': 'cltbld',
01:41:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:41:50 INFO - 'WINDIR': 'C:\\windows',
01:41:50 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:41:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:41:52 INFO - Ignoring indexes: http://pypi.python.org/simple/
01:41:52 INFO - Downloading/unpacking pip>=1.5
01:41:52 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
01:41:56 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
01:41:56 INFO - Running setup.py egg_info for package pip
01:41:56 INFO - warning: no files found matching 'pip\cacert.pem'
01:41:56 INFO - warning: no files found matching '*.html' under directory 'docs'
01:41:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
01:41:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
01:41:56 INFO - Installing collected packages: pip
01:41:56 INFO - Found existing installation: pip 0.8.2
01:41:56 INFO - Uninstalling pip:
01:41:56 INFO - Successfully uninstalled pip
01:41:56 INFO - Running setup.py install for pip
01:41:56 INFO - warning: no files found matching 'pip\cacert.pem'
01:41:56 INFO - warning: no files found matching '*.html' under directory 'docs'
01:41:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
01:41:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
01:41:56 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
01:41:56 INFO - Successfully installed pip
01:41:56 INFO - Cleaning up...
01:41:56 INFO - Return code: 0
01:41:56 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
01:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:41:56 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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:41:56 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
01:41:56 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
01:41:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:41:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:41:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:41:56 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:41:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:41:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:41:56 INFO - 'DCLOCATION': 'SCL3',
01:41:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:41:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:41:56 INFO - 'HOMEDRIVE': 'C:',
01:41:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:41:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:41:56 INFO - 'KTS_VERSION': '1.19c',
01:41:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:41:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:41:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:41:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:41:56 INFO - 'MOZ_NO_REMOTE': '1',
01:41:56 INFO - 'NO_EM_RESTART': '1',
01:41:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:41:56 INFO - 'OS': 'Windows_NT',
01:41:56 INFO - 'OURDRIVE': 'C:',
01:41:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:41:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:41:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:41:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:41:56 INFO - 'PROCESSOR_LEVEL': '6',
01:41:56 INFO - 'PROCESSOR_REVISION': '1e05',
01:41:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:41:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:41:56 INFO - 'PROMPT': '$P$G',
01:41:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:41:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:41:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:41:56 INFO - 'PWD': 'C:\\slave\\test',
01:41:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:41:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:41:56 INFO - 'SYSTEMDRIVE': 'C:',
01:41:56 INFO - 'SYSTEMROOT': 'C:\\windows',
01:41:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:56 INFO - 'TEST1': 'testie',
01:41:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:56 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:41:56 INFO - 'USERNAME': 'cltbld',
01:41:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:41:56 INFO - 'WINDIR': 'C:\\windows',
01:41:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:41:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:41:58 INFO - Downloading/unpacking psutil>=0.7.1
01:41:58 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
01:41:58 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
01:41:59 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
01:41:59 INFO - Installing collected packages: psutil
01:41:59 INFO - Successfully installed psutil
01:41:59 INFO - Cleaning up...
01:41:59 INFO - Return code: 0
01:41:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
01:41:59 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')]}
01:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:41:59 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')]}
01:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:41:59 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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:41:59 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
01:41:59 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
01:41:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:41:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:41:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:41:59 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:41:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:41:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:41:59 INFO - 'DCLOCATION': 'SCL3',
01:41:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:41:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:41:59 INFO - 'HOMEDRIVE': 'C:',
01:41:59 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:41:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:41:59 INFO - 'KTS_VERSION': '1.19c',
01:41:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:41:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:41:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:41:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:41:59 INFO - 'MOZ_NO_REMOTE': '1',
01:41:59 INFO - 'NO_EM_RESTART': '1',
01:41:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:41:59 INFO - 'OS': 'Windows_NT',
01:41:59 INFO - 'OURDRIVE': 'C:',
01:41:59 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;',
01:41:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:41:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:41:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:41:59 INFO - 'PROCESSOR_LEVEL': '6',
01:41:59 INFO - 'PROCESSOR_REVISION': '1e05',
01:41:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:41:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:41:59 INFO - 'PROMPT': '$P$G',
01:41:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:41:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:41:59 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:41:59 INFO - 'PWD': 'C:\\slave\\test',
01:41:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:41:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:41:59 INFO - 'SYSTEMDRIVE': 'C:',
01:41:59 INFO - 'SYSTEMROOT': 'C:\\windows',
01:41:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:59 INFO - 'TEST1': 'testie',
01:41:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:41:59 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:41:59 INFO - 'USERNAME': 'cltbld',
01:41:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:41:59 INFO - 'WINDIR': 'C:\\windows',
01:41:59 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:41:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:41:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
01:41:59 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
01:41:59 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
01:42:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz
01:42:03 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
01:42:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
01:42:03 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)
01:42:03 INFO - Installing collected packages: mozsystemmonitor
01:42:03 INFO - Running setup.py install for mozsystemmonitor
01:42:03 INFO - Successfully installed mozsystemmonitor
01:42:03 INFO - Cleaning up...
01:42:04 INFO - Return code: 0
01:42:04 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
01:42: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')]}
01:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:42: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')]}
01:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:42: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', '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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:42: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', 'blobuploader==1.2.4'] in C:\slave\test\build
01:42: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 blobuploader==1.2.4
01:42:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:42:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:42:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:42:04 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:42:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:42:04 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:42:04 INFO - 'DCLOCATION': 'SCL3',
01:42:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:42:04 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:42:04 INFO - 'HOMEDRIVE': 'C:',
01:42:04 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:42:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:42:04 INFO - 'KTS_VERSION': '1.19c',
01:42:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:42:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:42:04 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:42:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:42:04 INFO - 'MOZ_NO_REMOTE': '1',
01:42:04 INFO - 'NO_EM_RESTART': '1',
01:42:04 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:42:04 INFO - 'OS': 'Windows_NT',
01:42:04 INFO - 'OURDRIVE': 'C:',
01:42: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;',
01:42:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:42:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:42:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:42:04 INFO - 'PROCESSOR_LEVEL': '6',
01:42:04 INFO - 'PROCESSOR_REVISION': '1e05',
01:42:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:42:04 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:42:04 INFO - 'PROMPT': '$P$G',
01:42:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:42:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:42:04 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:42:04 INFO - 'PWD': 'C:\\slave\\test',
01:42:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:42:04 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:42:04 INFO - 'SYSTEMDRIVE': 'C:',
01:42:04 INFO - 'SYSTEMROOT': 'C:\\windows',
01:42:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:04 INFO - 'TEST1': 'testie',
01:42:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:04 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:42:04 INFO - 'USERNAME': 'cltbld',
01:42:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:42:04 INFO - 'WINDIR': 'C:\\windows',
01:42:04 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:42:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:42:04 INFO - Downloading/unpacking blobuploader==1.2.4
01:42: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
01:42: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
01:42:05 INFO - Downloading blobuploader-1.2.4.tar.gz
01:42:05 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
01:42:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
01:42:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
01:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
01:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
01:42:05 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
01:42:05 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
01:42:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
01:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
01:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
01:42:06 INFO - Downloading docopt-0.6.1.tar.gz
01:42:06 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
01:42:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
01:42:06 INFO - Installing collected packages: blobuploader, requests, docopt
01:42:06 INFO - Running setup.py install for blobuploader
01:42:06 INFO - Running setup.py install for requests
01:42:07 INFO - Running setup.py install for docopt
01:42:07 INFO - Successfully installed blobuploader requests docopt
01:42:07 INFO - Cleaning up...
01:42:08 INFO - Return code: 0
01:42:08 INFO - Installing None into virtualenv C:\slave\test\build/venv
01:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
01:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:42:08 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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:42:08 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
01:42:08 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
01:42:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:42:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:42:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:42:08 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:42:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:42:08 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:42:08 INFO - 'DCLOCATION': 'SCL3',
01:42:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:42:08 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:42:08 INFO - 'HOMEDRIVE': 'C:',
01:42:08 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:42:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:42:08 INFO - 'KTS_VERSION': '1.19c',
01:42:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:42:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:42:08 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:42:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:42:08 INFO - 'MOZ_NO_REMOTE': '1',
01:42:08 INFO - 'NO_EM_RESTART': '1',
01:42:08 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:42:08 INFO - 'OS': 'Windows_NT',
01:42:08 INFO - 'OURDRIVE': 'C:',
01:42:08 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
01:42:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:42:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:42:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:42:08 INFO - 'PROCESSOR_LEVEL': '6',
01:42:08 INFO - 'PROCESSOR_REVISION': '1e05',
01:42:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:42:08 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:42:08 INFO - 'PROMPT': '$P$G',
01:42:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:42:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:42:08 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:42:08 INFO - 'PWD': 'C:\\slave\\test',
01:42:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:42:08 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:42:08 INFO - 'SYSTEMDRIVE': 'C:',
01:42:08 INFO - 'SYSTEMROOT': 'C:\\windows',
01:42:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:08 INFO - 'TEST1': 'testie',
01:42:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:08 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:42:08 INFO - 'USERNAME': 'cltbld',
01:42:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:42:08 INFO - 'WINDIR': 'C:\\windows',
01:42:08 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:42:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:42:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
01:42:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l9fcno-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
01:42:08 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
01:42:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ezg_ai-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f6xhgm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4yplvi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nd0sdg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-makigg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-itw_3l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i7jj6m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
01:42:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nwd5rn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
01:42:09 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-agakef-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
01:42:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3nvtmh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
01:42:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvmobt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
01:42:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nltk73-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
01:42:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sim7oq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
01:42:10 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
01:42:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mtaodt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
01:42:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
01:42:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7mlh5u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
01:42:11 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
01:42:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cgfky3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
01:42:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
01:42:11 INFO - Running setup.py install for manifestparser
01:42:11 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
01:42:11 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
01:42:12 INFO - Running setup.py install for mozcrash
01:42:12 INFO - Running setup.py install for mozdebug
01:42:12 INFO - Running setup.py install for mozdevice
01:42:13 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
01:42:13 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
01:42:13 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
01:42:13 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
01:42:13 INFO - Running setup.py install for mozfile
01:42:13 INFO - Running setup.py install for mozhttpd
01:42:13 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
01:42:13 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Running setup.py install for mozinfo
01:42:14 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Running setup.py install for mozInstall
01:42:14 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
01:42:14 INFO - Running setup.py install for mozleak
01:42:15 INFO - Running setup.py install for mozlog
01:42:15 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
01:42:15 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
01:42:16 INFO - Running setup.py install for moznetwork
01:42:16 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
01:42:16 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
01:42:16 INFO - Running setup.py install for mozprocess
01:42:16 INFO - Running setup.py install for mozprofile
01:42:17 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Running setup.py install for mozrunner
01:42:17 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
01:42:17 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
01:42:18 INFO - Running setup.py install for mozscreenshot
01:42:18 INFO - Running setup.py install for moztest
01:42:18 INFO - Running setup.py install for mozversion
01:42:19 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
01:42:19 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
01:42:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
01:42:19 INFO - Cleaning up...
01:42:19 INFO - Return code: 0
01:42:19 INFO - Installing None into virtualenv C:\slave\test\build/venv
01:42:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
01:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:42:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]}
01:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:42:19 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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:42:19 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
01:42:19 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
01:42:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:42:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:42:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:42:19 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:42:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:42:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:42:19 INFO - 'DCLOCATION': 'SCL3',
01:42:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:42:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:42:19 INFO - 'HOMEDRIVE': 'C:',
01:42:19 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:42:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:42:19 INFO - 'KTS_VERSION': '1.19c',
01:42:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:42:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:42:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:42:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:42:19 INFO - 'MOZ_NO_REMOTE': '1',
01:42:19 INFO - 'NO_EM_RESTART': '1',
01:42:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:42:19 INFO - 'OS': 'Windows_NT',
01:42:19 INFO - 'OURDRIVE': 'C:',
01:42:19 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;',
01:42:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:42:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:42:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:42:19 INFO - 'PROCESSOR_LEVEL': '6',
01:42:19 INFO - 'PROCESSOR_REVISION': '1e05',
01:42:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:42:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:42:19 INFO - 'PROMPT': '$P$G',
01:42:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:42:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:42:19 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:42:19 INFO - 'PWD': 'C:\\slave\\test',
01:42:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:42:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:42:19 INFO - 'SYSTEMDRIVE': 'C:',
01:42:19 INFO - 'SYSTEMROOT': 'C:\\windows',
01:42:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:19 INFO - 'TEST1': 'testie',
01:42:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:19 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:42:19 INFO - 'USERNAME': 'cltbld',
01:42:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:42:19 INFO - 'WINDIR': 'C:\\windows',
01:42:19 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:42:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:42:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
01:42:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n4vshq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
01:42:19 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))
01:42:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
01:42:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zqeqha-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dpxe3r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q9fyak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9pa6pp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aprmnd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5rikji-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
01:42:20 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))
01:42:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
01:42:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qghqse-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ogoab1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x43jm9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1dmfxn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xtejap-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frbrbd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
01:42:21 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))
01:42:21 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
01:42:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-efydig-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
01:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
01:42:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
01:42:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a8fxkn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
01:42:22 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))
01:42:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
01:42:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ge9jis-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
01:42:22 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))
01:42:22 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
01:42:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cproao-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
01:42:22 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))
01:42:22 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))
01:42:22 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))
01:42:22 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))
01:42:22 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))
01:42:22 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))
01:42:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
01:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
01:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
01:42:23 INFO - Downloading blessings-1.5.1.tar.gz
01:42:23 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
01:42:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
01:42:23 INFO - Installing collected packages: blessings
01:42:23 INFO - Running setup.py install for blessings
01:42:23 INFO - Successfully installed blessings
01:42:23 INFO - Cleaning up...
01:42:26 INFO - Return code: 0
01:42:26 INFO - Done creating virtualenv C:\slave\test\build/venv.
01:42:26 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
01:42:26 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
01:42:26 INFO - Reading from file tmpfile_stdout
01:42:26 INFO - Using _rmtree_windows ...
01:42:26 INFO - Using _rmtree_windows ...
01:42:26 INFO - Current package versions:
01:42:26 INFO - blessings == 1.5.1
01:42:26 INFO - blobuploader == 1.2.4
01:42:26 INFO - docopt == 0.6.1
01:42:26 INFO - manifestparser == 1.1
01:42:26 INFO - mozInstall == 1.12
01:42:26 INFO - mozcrash == 0.16
01:42:26 INFO - mozdebug == 0.1
01:42:26 INFO - mozdevice == 0.46
01:42:26 INFO - mozfile == 1.2
01:42:26 INFO - mozhttpd == 0.7
01:42:26 INFO - mozinfo == 0.8
01:42:26 INFO - mozleak == 0.1
01:42:26 INFO - mozlog == 3.0
01:42:26 INFO - moznetwork == 0.27
01:42:26 INFO - mozprocess == 0.22
01:42:26 INFO - mozprofile == 0.27
01:42:26 INFO - mozrunner == 6.11
01:42:26 INFO - mozscreenshot == 0.1
01:42:26 INFO - mozsystemmonitor == 0.0
01:42:26 INFO - moztest == 0.7
01:42:26 INFO - mozversion == 1.4
01:42:26 INFO - psutil == 3.1.1
01:42:26 INFO - requests == 1.2.3
01:42:26 INFO - Installing None into virtualenv C:\slave\test\build/venv
01:42:26 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')]}
01:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
01:42:26 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')]}
01:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
01:42:26 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 0x018A2B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018FBEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017BB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-155', 'USERDOMAIN': 'T-W732-IX-155', '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-155', '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
01:42:27 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
01:42:27 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
01:42:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:42:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:42:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:42:27 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:42:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:42:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:42:27 INFO - 'DCLOCATION': 'SCL3',
01:42:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:42:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:42:27 INFO - 'HOMEDRIVE': 'C:',
01:42:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:42:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:42:27 INFO - 'KTS_VERSION': '1.19c',
01:42:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:42:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:42:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:42:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:42:27 INFO - 'MOZ_NO_REMOTE': '1',
01:42:27 INFO - 'NO_EM_RESTART': '1',
01:42:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:42:27 INFO - 'OS': 'Windows_NT',
01:42:27 INFO - 'OURDRIVE': 'C:',
01:42:27 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;',
01:42:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:42:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:42:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:42:27 INFO - 'PROCESSOR_LEVEL': '6',
01:42:27 INFO - 'PROCESSOR_REVISION': '1e05',
01:42:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:42:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:42:27 INFO - 'PROMPT': '$P$G',
01:42:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:42:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:42:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:42:27 INFO - 'PWD': 'C:\\slave\\test',
01:42:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:42:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:42:27 INFO - 'SYSTEMDRIVE': 'C:',
01:42:27 INFO - 'SYSTEMROOT': 'C:\\windows',
01:42:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:27 INFO - 'TEST1': 'testie',
01:42:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:27 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:42:27 INFO - 'USERNAME': 'cltbld',
01:42:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:42:27 INFO - 'WINDIR': 'C:\\windows',
01:42:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:42:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 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))
01:42:27 INFO - Cleaning up...
01:42:27 INFO - Return code: 0
01:42:27 INFO - Running post-action listener: _resource_record_post_action
01:42:27 INFO - Running post-action listener: _start_resource_monitoring
01:42:27 INFO - Starting resource monitoring.
01:42:27 INFO - #####
01:42:27 INFO - ##### Running install step.
01:42:27 INFO - #####
01:42:27 INFO - Running pre-action listener: _resource_record_pre_action
01:42:27 INFO - Running main action method: install
01:42:27 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
01:42:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
01:42:27 INFO - Reading from file tmpfile_stdout
01:42:27 INFO - Using _rmtree_windows ...
01:42:27 INFO - Using _rmtree_windows ...
01:42:27 INFO - Detecting whether we're running mozinstall >=1.0...
01:42:27 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
01:42:27 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
01:42:28 INFO - Reading from file tmpfile_stdout
01:42:28 INFO - Output received:
01:42:28 INFO - Usage: mozinstall-script.py [options] installer
01:42:28 INFO - Options:
01:42:28 INFO - -h, --help show this help message and exit
01:42:28 INFO - -d DEST, --destination=DEST
01:42:28 INFO - Directory to install application into. [default:
01:42:28 INFO - "C:\slave\test"]
01:42:28 INFO - --app=APP Application being installed. [default: firefox]
01:42:28 INFO - Using _rmtree_windows ...
01:42:28 INFO - Using _rmtree_windows ...
01:42:28 INFO - mkdir: C:\slave\test\build\application
01:42:28 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']
01:42:28 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
01:42:29 INFO - Reading from file tmpfile_stdout
01:42:29 INFO - Output received:
01:42:29 INFO - C:\slave\test\build\application\firefox\firefox.exe
01:42:29 INFO - Using _rmtree_windows ...
01:42:29 INFO - Using _rmtree_windows ...
01:42:29 INFO - Running post-action listener: _resource_record_post_action
01:42:29 INFO - #####
01:42:29 INFO - ##### Running run-tests step.
01:42:29 INFO - #####
01:42:29 INFO - Running pre-action listener: _resource_record_pre_action
01:42:29 INFO - Running main action method: run_tests
01:42:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
01:42:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
01:42:29 INFO - Python 2.7.3
01:42:29 INFO - Return code: 0
01:42:29 INFO - grabbing minidump binary from tooltool
01:42:29 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')]}
01:42:29 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 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0197A660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
01:42:29 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
01:42:29 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
01:42:29 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
01:42:29 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpmzvejv
01:42:29 INFO - INFO - File integrity verified, renaming tmpmzvejv to win32-minidump_stackwalk.exe
01:42:29 INFO - Return code: 0
01:42:29 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
01:42:29 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
01:42:29 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
01:42:29 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
01:42:29 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
01:42:29 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-155', '--webServer', 'localhost'] in C:\slave\test\build
01:42:29 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName B2G-Inbound-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-155 --webServer localhost
01:42:29 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
01:42:29 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
01:42:29 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
01:42:29 INFO - 'COMPUTERNAME': 'T-W732-IX-155',
01:42:29 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
01:42:29 INFO - 'CYGWINBASE': 'C:\\cygwin',
01:42:29 INFO - 'DCLOCATION': 'SCL3',
01:42:29 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
01:42:29 INFO - 'FP_NO_HOST_CHECK': 'NO',
01:42:29 INFO - 'HOMEDRIVE': 'C:',
01:42:29 INFO - 'HOMEPATH': '\\Users\\cltbld',
01:42:29 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
01:42:29 INFO - 'KTS_VERSION': '1.19c',
01:42:29 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
01:42:29 INFO - 'LOGONSERVER': '\\\\T-W732-IX-155',
01:42:29 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
01:42:29 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
01:42:29 INFO - 'MONDIR': 'C:\\Monitor_config\\',
01:42:29 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
01:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
01:42:29 INFO - 'MOZ_NO_REMOTE': '1',
01:42:29 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
01:42:29 INFO - 'NO_EM_RESTART': '1',
01:42:29 INFO - 'NUMBER_OF_PROCESSORS': '8',
01:42:29 INFO - 'OS': 'Windows_NT',
01:42:29 INFO - 'OURDRIVE': 'C:',
01:42:29 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;',
01:42:29 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
01:42:29 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
01:42:29 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
01:42:29 INFO - 'PROCESSOR_LEVEL': '6',
01:42:29 INFO - 'PROCESSOR_REVISION': '1e05',
01:42:29 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
01:42:29 INFO - 'PROGRAMFILES': 'C:\\Program Files',
01:42:29 INFO - 'PROMPT': '$P$G',
01:42:29 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
01:42:29 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
01:42:29 INFO - 'PUBLIC': 'C:\\Users\\Public',
01:42:29 INFO - 'PWD': 'C:\\slave\\test',
01:42:29 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
01:42:29 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
01:42:29 INFO - 'SLAVEDIR': 'C:\\slave\\',
01:42:29 INFO - 'SYSTEMDRIVE': 'C:',
01:42:29 INFO - 'SYSTEMROOT': 'C:\\windows',
01:42:29 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:29 INFO - 'TEST1': 'testie',
01:42:29 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
01:42:29 INFO - 'USERDOMAIN': 'T-W732-IX-155',
01:42:29 INFO - 'USERNAME': 'cltbld',
01:42:29 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
01:42:29 INFO - 'WINDIR': 'C:\\windows',
01:42:29 INFO - 'WINDOWS_TRACING_FLAGS': '3',
01:42:29 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
01:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
01:42:29 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-155', '--webServer', 'localhost'] with output_timeout 3600
01:42:31 INFO - mozversion INFO | application_buildid: 20151027005022
01:42:31 INFO - mozversion INFO | application_changeset: afa51178a780763be38c80564ed234fb05a42075
01:42:31 INFO - mozversion INFO | application_display_name: Nightly
01:42:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
01:42:31 INFO - mozversion INFO | application_name: Firefox
01:42:31 INFO - mozversion INFO | application_remotingname: firefox
01:42:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound
01:42:31 INFO - mozversion INFO | application_vendor: Mozilla
01:42:31 INFO - mozversion INFO | application_version: 44.0a1
01:42:31 INFO - mozversion INFO | platform_buildid: 20151027005022
01:42:31 INFO - mozversion INFO | platform_changeset: afa51178a780763be38c80564ed234fb05a42075
01:42:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound
01:42:31 INFO - mozversion INFO | platform_version: 44.0a1
01:42:31 INFO - 2015-10-27 01:42:31,164 DEBUG : using testdate: 1445935351
01:42:31 INFO - 2015-10-27 01:42:31,164 DEBUG : actual date: 1445935351
01:42:31 INFO - 2015-10-27 01:42:31,174 INFO : starting webserver on 'localhost:49847'
01:42:31 INFO - 2015-10-27 01:42:31,176 INFO : Starting test suite T-W732-IX-155
01:42:31 INFO - 2015-10-27 01:42:31,176 INFO : Starting test dromaeo_css
01:42:31 INFO - 2015-10-27 01:42:31,176 DEBUG : operating with platform_type : w7_
01:42:31 INFO - 2015-10-27 01:42:31,177 INFO : Initialising browser for dromaeo_css test...
01:42:31 INFO - 2015-10-27 01:42:31,279 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpfznrps\profile http://localhost:49847/getInfo.html
01:42:40 INFO - 2015-10-27 01:42:40,066 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
01:42:40 INFO - 2015-10-27 01:42:40,066 DEBUG : BROWSER_OUTPUT: colorDepth:24
01:42:40 INFO - 2015-10-27 01:42:40,066 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
01:42:40 INFO - 2015-10-27 01:42:40,066 DEBUG : BROWSER_OUTPUT: __metrics
01:42:40 INFO - 2015-10-27 01:42:40,141 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
01:42:41 INFO - 2015-10-27 01:42:41,684 INFO : Browser initialized.
01:42:41 INFO - 2015-10-27 01:42:41,684 INFO : Running cycle 1/1 for dromaeo_css test...
01:42:41 INFO - 2015-10-27 01:42:41,684 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpfznrps\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
01:42:53 INFO - 2015-10-27 01:42:53,391 DEBUG : BROWSER_OUTPUT: RSS: Main: 141651968
01:42:53 INFO - 2015-10-27 01:42:53,391 DEBUG : BROWSER_OUTPUT:
01:43:46 INFO - 2015-10-27 01:43:46,592 DEBUG : BROWSER_OUTPUT: 1445935426602 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]
01:45:58 INFO - 2015-10-27 01:45:58,450 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49847/tests/dromaeo/cssquery-ext.html)
01:45:59 INFO - 2015-10-27 01:45:59,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 176541696
01:45:59 INFO - 2015-10-27 01:45:59,437 DEBUG : BROWSER_OUTPUT:
01:49:02 INFO - 2015-10-27 01:49:02,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-ext.html (next: http://localhost:49847/tests/dromaeo/cssquery-jquery.html)
01:49:02 INFO - 2015-10-27 01:49:02,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 192274432
01:49:02 INFO - 2015-10-27 01:49:02,700 DEBUG : BROWSER_OUTPUT:
01:52:04 INFO - 2015-10-27 01:52:04,956 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49847/tests/dromaeo/cssquery-mootools.html)
01:52:05 INFO - 2015-10-27 01:52:05,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 145195008
01:52:05 INFO - 2015-10-27 01:52:05,530 DEBUG : BROWSER_OUTPUT:
01:55:07 INFO - 2015-10-27 01:55:07,671 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49847/tests/dromaeo/cssquery-prototype.html)
01:55:08 INFO - 2015-10-27 01:55:08,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 138952704
01:55:08 INFO - 2015-10-27 01:55:08,246 DEBUG : BROWSER_OUTPUT:
01:58:11 INFO - 2015-10-27 01:58:11,246 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49847/tests/dromaeo/cssquery-yui.html)
01:58:11 INFO - 2015-10-27 01:58:11,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 138244096
01:58:11 INFO - 2015-10-27 01:58:11,841 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,575 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/dromaeo/cssquery-yui.html (next: http://localhost:49847/tests/dromaeo/cssquery-dojo.html)
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 190074880
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: __start_tp_report
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2200.251914349121;2381.2975172324172;2392.079049565917;2396.3170496408507;2403.9879800600993;4095.7133000689964;4125.236831186229;4153.54311025787;4167.45823043004;4183.351580505019;3882.7282090253693;3993.7204395692315;4020.7989600520004;4137.1725654869;4204.747715137094;3429.7084747796466;3466.8266586670693;3484.703800176988;3639.981800090996;3699.685526730238;3636.8727094551696;3647.617000214979;3653.8538458461762;3669.7981611011382;3670.155864151247;3445.1214940190216;3646.6535679110443;3673.3938900081453;3685.4840322354894;3687.2441149564274;3393.779404338735;3486.703630191437;3519.630438803927;3564;3608.548931383577;3474.2183008823067;3533.628968958248;3548.3790336690977;3614.168741189515;3733.085394078468;3805.5243094613174;4025.0943537704075;4092.1815636872743;4124.4019617155645;4179.038821071171;4239.0885959518855;4302.290122129845;4324.545922678105;4345.109252603241;4351.216780979423;4356.455443069616;4370.431843860278;4441.600255976945;4518.1415531049;4526.502084770673;4085.3667681509414;4129.926219182974;4137.46212992309;4217.7891105444605;4219.261629214906;4271.8718438446895;4389;4439.200943830077;4506.7070640408265;4513.751743654065;4291.828326866953;4322.805473753689;4327.32926396409;4377.584129507692;4415.072834704748;4394.494633117232;4433.7561434121435;4463.085067561142;4472.575105364985;4489.842855500042;4241.660667146621;4312.460942382202;4331.610155085994;4347.239233134252;4415.712978656441;4125.546189919107;4131.9173616527505;4304.203722311382;4345.435093437833;4430.13612345596;2961.6742158362563;3038.5897903782707;3084.7532197424152;3088.0581422665855;3097.98451007748;2689.22012616343;2734.398432344881;2785.582162675574;2799.622051023086;2803.9299017524727;3906.7265291429894;3941.842326306973;4062.0657248832345;4128.0092777733125;4187.958120418818;4267.4025636410925;4385.693001489929;4455.532169122195;4458.77706114693;4476.216662084123;3791.4881490998914;3938.6849052075763;4040.1919616076902;4134.979325103355;4136.338185890243;3742.607026262258;3795.2599243147874;3802.9429558556644;3809.961900381016;3811.4282857571584;4492.752898590609;4503.617192538671;4510.954890451185;4598.333241680005;4605.125026245003;4221.261279276176;4287.206866729665;4291.291936830388;4322.76224807626;4402.757848318373;4255.936160957526;4261.957380426156;4305.375720520569;4329.891752706081;4344.587264209895;4098.7950602470355;4173.603507666767;4182.142660754599;4224.302990006615;4410.977945110254;3976.701747368901;4087.938680919729;4114.19773144234;4183.686223533186;4339.37079123519;4005.719599627998;4044.1102957349335;4077.8776636700945;4093.2427500912427;4099.385092236188;4216.91566168681;4262.7016108872085;4350.0647360817575;4350.956490435182;4351.021020270414;3990.3216453202444;4123.690723195712;4155.065110350147;4156.272652285777;4195.643370313557;4102.651274641689;4198.580141985777;4238.131183107517;4340.218760623118;4349.064951035542;3911.8239679213934;4003;4090.734102283342;4121.1345617420675;4250.234957707642
02:01:14 INFO - 2015-10-27 02:01:14,831 DEBUG : BROWSER_OUTPUT: |1;ext.html;474.22227546823194;475.1447394689558;492.65514140101936;518.6473198225208;527.1854984049643;24929;35131.64868351319;35291;36280.274394512126;36460.45309320362;37308.253834923285;38382.424263636065;39133;39249.41125883114;39355.40966885492;6296.464800491964;6440.6779661017;7515.887261691066;8148.918510814875;8171.5505647189675;9723.805523889518;9946.30375873689;9973.451460169688;9975.451350175737;10000.799984000316;9387.43675379476;9705.369151005198;9825.557849896772;9859.556319965583;9938.15525680318;6347.492200623962;6564.621429499811;9426.481543515103;10139.64511242103;10429;9969.850452243221;10068.496575171212;10123.898761012442;10127.189824813997;10128.94935525325;36348.27303453916;36540.26919461596;36736.81631591851;36753.448698269545;36775.816120919226;2724.5504491758834;2750.7661848742882;2816.2959260184953;3046.037033553922;3407.3015031918644;27529.44941101167;27740.30649233785;27964.580531292046;28062;28394.574081388797;2016.5160361513279;2023.6458619741486;2036.6435873722187;2043.1214577731569;2132.2110818996916;223393;225545;225707;229705;239601;41057.38413923793;41759.7912010438;50229.497705022484;50604.74697626488;50655.24017139746;53076.73461632667;54848.45151548433;55235.44764552303;56124.43875561192;58005.1089526646;12533.686657833445;12626.116171868065;12803.871961280269;12804.103712740205;12805.743885122245;9386.202172815387;9511.714648560554;9543.665971690958;9551.997040310667;9552.474613896164;15253.313600887986;15599.766003509956;15883.920580397024;16401.753973690404;16413.753793693104;7295.343419092307;7303.6348182590655;7432.182459929403;7486.887696684554;7498.4001279897475;756.8788993760971;841.2050612171562;850.5067061104545;924.5931790012507;963.8939716631165;24981.375465613142;25339.87330063338;25438.109666161596;25502.23493295204;25784.48431031369;878.7232021913077;888.2494292322976;929.8884133903974;936.1901954809041;986.3687240166299;13254.204747715168;13291.734165316639;13315.600531984055;14103.294835258195;14594.124352538882;271430;277774;278022;278157;278833;43143.784281079024;43162.568374316485;43809.56190438118;45054.774726126816;45924.311135332995;1091.170710260192;1103.255302670678;1132.932024078558;1165.5396118533301;1169.0121847039238;18832.34086806955;24885.50228995446;25036.248912532654;25073;26115.347116322246;40307.79846100868;40423.7978810104;41868;41883.58116418797;41902.37146442745;234133;234589;234786;234850;235351;21282.36152915415;26581.335466612716;26887.462250754485;27027;27068;32336.514952276204;32594;33402.162881041775;34550.82724586361;34996;29178.270543236587;30517;31103;31240.375192496478;32038;30371.392572148874;30720.692793072678;31034;31585.5262171063;31759.206019848763;29331.85334073316;30277.545836812023;30309;30667.2333191672;31679;25324.366890827878;27052.019654201664;27890.721092789623;27977.580336295377;29209.85395073011;21936.45158871041;22953;23028.424289392227;23528.411789705395;23616.763832361456
02:01:14 INFO - 2015-10-27 02:01:14,842 DEBUG : BROWSER_OUTPUT: |2;jquery.html;282.81758265918495;282.8981566636011;289.99420011599756;293.64468992519045;294.5552216153607;11482.827757583622;11536.942315288432;15232.69534609307;15639.765403518943;15649.687006259883;10053.547590358434;14394.712105757879;14605.853941460582;14642.853571464282;14654.48709295175;4045.919081618366;4190.874273771777;4197.16056788642;4199.979000104995;4207.558206388331;3036.1347016100417;3084.5835812165406;4084.97957510212;4620.607248383892;4716.268978308368;4570.908581828361;4658.324542941272;4668.6731928765;4676;4677.812887484513;4514.480834703985;4650.627949764028;4658.091672123937;4664.440267167927;4675.579197872208;3835.5780864104927;3837.2517359114986;3837.4627552142724;3839.7504162229643;3840.5007349044727;11845.940770296093;11923.582674606347;11951.402429878559;12088.637340879788;12472.006718387613;1669.7829282193359;1792.9910350448295;1808.6292310076403;1810.97283540747;1837.724341348795;5356.303680521546;9198.252332056887;9233.49215793131;9243.26053915685;9477.526123693788;1498.5279636914338;1508.7661412481084;1513.3038802151025;1554.1529866222936;1568.1767072287141;56185.438145618835;56758;57912.71043644797;58550.41449585492;58675.41324586785;5681.289838770153;5807.448292412214;5817.912731309034;5835.183074369592;5895.67573783446;6874.621895795679;6881.759138430231;6889.414399775975;6896.344847239504;6901.275366086589;4643.489216186217;4728.1252968201;4774.40319960005;4813.326134341125;4846.854594362175;5035.1188542005;5063;5064.240363945438;5074.467180945948;5091.719955402415;2701.91894243173;2735.3571910600995;2753.7796976241852;2757;2761.1854502921688;970.9271804614682;972.2659392158874;973.8441849304078;981.1267971505366;1019.4698756646502;357.02888144247606;379.4725331788816;394.15650507912517;399.0641944639815;400.6754528831656;10567.682969510926;10939;10950.3429794211;10998.12015038795;11079.889201108046;345.9588428273216;402.11935860465485;410.208297984892;416.5979829464581;419.7586387826951;5258.6581872178185;5720.856978575569;5734.39788822176;5860.501857342174;5867.765289388377;304394;308908.68374222866;316426;317062;318973;61955.690221548604;62111.37888621146;62778;62793.68603156955;64168;445.7704282294621;449.8088312467254;450.6890245730501;451.8170141092863;452.2853890852445;10066.49667516636;10113.544890480092;10119.7470063249;10291.794164116525;10318.535665894902;33114.834425827714;33686.326273474886;34277.48583771295;34729.305413891074;34743.47884781781;46058.30912536247;46452.767736161106;46844.29733553931;47089.52910470851;47153.528464716284;5719.084946408555;5864.44287792659;5869.706514674334;5887.735051922588;5911.970440147772;11063.72340691489;11291.548338066385;11329.206955513035;11503.48234329474;11506.30962142274;9606.183474404474;9763.902360976299;9851.261155413229;9872.457014863965;9903.158231550111;9440.395132827298;9606.087421694929;9729.90222231083;9809.019098090133;9903.45530995802;9652.372595781251;9715;9985.950070249603;10100.141487973311;10145.84781228297;4979.178435558092;5357.330333708286;5518.144687573433;5699.430056994267;5701.287339082614;9217.815643687223;9221.308401869752;9286.62853485853;9489.145976861839;9552.331336806636
02:01:14 INFO - 2015-10-27 02:01:14,842 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1198.5146015863572;1228.963131106067;1266.689661033047;1377.7520046391649;1551.1468692219275;5729.885402291951;6493.545451818374;6655.767048153303;6660.234073081591;6661.73353065878;6483.481321494281;6561.245456772473;6561.475081993441;6567.737290508374;6572.408483236508;1918.5875036867105;1929.0547631660456;1936.2255097960788;1941.262320318277;1942.660034493965;1905.4759941016262;1924.393815947972;1925.6341295153945;1957.559549101455;1969.9113539890739;1917.9136938837785;1919.5393105654682;1929.1222493765304;1931.7875033746348;1933.82595566399;1836.1553685304775;1892.630936967292;1893.7916829148783;1894.2233684189484;1909.7039958806408;1835.7246413038017;1845.5847434327304;1880.1163453176985;1887.877287976277;1898.0604600722731;5747.770089196427;5907.438793314628;6371.108044873721;6515.967420162916;6538.771143009972;3874.5931677174053;3925.7448265862913;3954.8418063277436;3962.286788378091;3968.0278331808604;2430.2830664953703;2435.281591930385;2452.3869032741813;2462.4582591829767;2469.333280014386;740.311510295425;744.0922075068407;750.7334896111856;774.9535027898344;781.6052893288853;8195.42632015759;8570.22867941882;8586.527740974245;8621;8626.137386261387;3639.5632524097277;3711.851525938986;3723.646253605903;3752.45589389537;3764.3412402829395;7530.736424225126;7534.208908064575;7562.130355009244;7566.659500322498;7574.469787114849;3955.3275943089743;3977.900552486211;3983.2630963271886;3985.202959408072;4001.299772539794;3635.781853088823;3638.599754027055;3655.1044993976648;3658.122050707811;3662.1393972416463;2906.520424130037;2919.5912572239904;2936.060460652571;2954.542045982876;2954.8818047278296;1004.5730564510054;1006.5118417567473;1012.9341592796445;1016.6289304403987;1022.2844009193594;827.7723626002897;837.9287760540301;839.047680882203;839.177605946164;840.2941529115573;6093;6093.329733729286;6102.69486525672;6108.419700128481;6217.316095229521;931.9394239374419;944.3200895355333;954.5704433005176;971.2763990826738;986.8223719730374;4798.66409351349;4899.534544218293;4912.361393018885;4940.604751619861;4941.777620007108;6890.655467226618;7161.462890283249;7526.360259378014;7622.695092196361;7631.3513351365755;7299.854002919912;7506.587137707369;7508.962455187689;7538.886916696254;7542.924570754332;5776.133579963039;5929.37741537141;5934.821955341346;5958.8380265847745;5964.642121472725;5689.687067211343;5718.79984200551;5793.565482588907;5886.764529418775;5904.143899134688;6445.6454894981225;6651.235107962646;6734.528582999339;6735;6780.288069752803;6944.548604340702;7130.5365151265005;7155.604657092025;7236.891446628199;7279.053723015974;6226.906596401148;6245.781397651061;6326.810195694136;6329.936700632935;6353.650549219838;6407.871842563216;6457.5479716419395;6463.870722585428;6464.806055818333;6468.15913931186;5931.970340148272;5935.762569497345;6011.849703757441;6107.725152368065;6162.352952940056;5740.827775166752;5892.793752218823;5981.222441082631;5984.042553191469;6072.14989901423;6066.575339726177;6106.1451396803595;6111.694415279129;6115.816525504242;6120.1125836752535;5236.450172731808;5432.728363581726;5436.836894893159;5474;5474.425185355481;5381.73091345439;5564.221009058816;5576.996140694714;5583.35791383996;5583.441655834384
02:01:14 INFO - 2015-10-27 02:01:14,861 DEBUG : BROWSER_OUTPUT: |4;prototype.html;261.63501914828817;274.41275670066074;277.32471432062925;278.3694930981327;281.15512883784226;4618.792154353054;4637.5130611285795;4639.652026098048;4643.953560464394;4648.558386953238;4456.398386217859;4464.732116073038;4471.798769055392;4529.637628989681;4576.405067341249;3351.028201821469;3476.6349533299017;3478.808665523395;3480.199554102558;3483.6168021517615;3371.5448414463976;3469.843857026427;3478.4956181353687;3479.530263414432;3480.756347055707;3403.0641573567345;3403.8468268927836;3438.243586410998;3439.982800085996;3443.173638326796;3393.6097348804956;3452.654734526552;3453;3454.8618055277757;3456.879009234677;2985.1641540368732;3031.787774855761;3032.2116249774995;3032.378362435695;3046.299351149226;4462.12988467249;4463.665225108098;4464.6874718769695;4468.664850136252;4471.709338892994;4083.183363327317;4085.7957102144774;4090.6727461802984;4098.754074755524;4104.015036391274;4793.904121917577;4794.856154315376;4798.088363210979;4799.256115302133;4870.439899411542;3253.056613582082;3329.1177837872983;3337.8164200968936;3341.298327351259;3344.96655033449;4941.530554597308;5011.974940125276;5015.799368025275;5021.071101846133;5028.296038554642;3396.7622266441113;3402.659734026627;3403.2342722887647;3404.557407537004;3405.761596688208;3385.4583266677214;3436.3642726095754;3444.6555344465855;3445.9655403445645;3446.8965931022108;3737.981310093432;3788.299164654548;3951.1900060487533;3968.6428221460205;3988.3419235825763;4829.372181616438;4895.143349913751;4895.461499235082;4896.36347274852;4902.166631672624;1225.0383448992677;1238.1642391385778;1257.346179986402;1258.9433475493624;1258.9748205035849;798.4490701416005;820.958952052395;833.2251006563954;835.3734698975768;839.1231163434104;4559.270516717309;4608.769561521911;4609.861704148881;4611.308303754464;4617.607503362185;4993.775280112403;4997.750112494361;5005.89988200234;5010;5018.1218286800395;4620.1221767864;4631.3516107744945;4635.860924172281;4646.070785842845;4647.837325693652;2648.7880969522394;2714.077213747296;2728.181545536331;2729.426820367705;2737.3704048068657;5033.6224783141415;5037.748112594356;5094.54149126573;5111.948880511147;5145.176771716508;5103.897922041539;5122.129238029544;5146.0479811235045;5153.943441594464;5158.148905430562;5119.411267704261;5156.664816786897;5156.716380599103;5162.767675454614;5162.819301324435;4939.901201976012;4963.70217786934;5031.245313202902;5034.546890779848;5035.723035233097;4791.9281210782565;4842.491538388559;4901.950980490149;4999.625028122832;5004.949950500594;4687;4843.782029808737;5006.574441172541;5092.923606145837;5151.201563757623;3330.1341651170387;3445.586529616462;3460.861565537451;3468.1676397664883;3468.2890007548895;4965.577925876197;4966.35437393151;4968.22992436173;4971.65198436106;4972.105021096238;4791.448983366818;4794.041191761592;4795.568398843981;4798.088363210979;4800.951990480051;4782.976085119692;4798.5441383068555;4803.495632958628;4808.08646357205;4808.2547205183255;4573.611243044378;4691.9765401174145;4693.647976401715;4706.176419126571;4712.717236965793;4805.5915247204375;4869.70781753096;4871.952530206023;4888.242322440027;4892.217245240744;4705.411823522059;4785.473597904297;4794.2808578713475;4801.855944321676;4803.567678908915
02:01:14 INFO - 2015-10-27 02:01:14,862 DEBUG : BROWSER_OUTPUT: |5;yui.html;569.4191924237275;600.930892947311;601.161379875074;627.8860510416974;699.569764594774;9956.452395118265;10066.848997265048;12390.31853248071;12620.242785432869;12637.747245055094;12140;12417.192882462634;12854;12859;12884.549040783575;479.1088575250032;610.7465401858224;621.7854840080188;621.8414304352407;623.2396476298908;476.5258567725118;609.9146119543245;617.5059952038374;619.5260625621403;620.0203678188474;632.7342516143208;647.546717297891;647.9805605831832;648.5524987758455;648.5881465269541;637.923449186096;643.5913195121085;643.703896207745;643.7231990244193;644.0339490763854;464.0996466854292;466.941632295963;468.2741750287041;468.40746455733523;468.4285172090045;12298.569550065795;12486;12486.625401237978;12510.124291299613;12551.058670599741;11574.900384463463;11638.243514171636;11912.761744765055;11989.700257493632;12034.157608967376;9836.31145819793;9979.501024948797;9993.950030249802;10128.54421551032;10200.897991020069;3276.459384201628;3286.293446908926;3342.1143396999814;3370.6460821613623;3461.8961431157104;44302.77848610769;44313.556864431266;45354.77322613399;45572;45603.315950260774;9102.772430689285;9140.398149208795;9159.816803663889;9177.770555736026;9410.764730881645;16440.83559164417;16550.337986480405;16572.254248558846;16573.41993030262;16599.087050212114;6587.868242635121;6621.668916554153;6847.623380714109;6867.244603093655;6873.725050997904;3142.1830324115895;3598.9280214395567;3629.4059613142354;3646.197836475971;3657.4148136298063;2268.534950335177;2289.587874182663;2303.5047464794816;2420.2255278311122;2438.378213555532;380.50914320526766;397.2848872030334;402.01105281009046;403.62059663915824;407.3299422450053;536.1983834167944;556.1518684006921;583.6760597868224;591.470633782762;599.6731781179268;7513.2862378074;8098.75703728889;8227.218414250636;8349.916500835036;8815.823683526422;481.43912342121035;508.6769901112746;600.5075837812951;640.6156306216326;641.4515589171191;3573.249617580337;3665.2303016366327;3739.882503294071;3831.980840095782;3904.882853514399;60095;60336.396636033955;60745.39254607397;60800;60881.08678369828;49172.262416063786;51004.48995510072;51009;51010.48989510132;51122.233166502534;46225;46735.53264467448;46940.765296173304;47029;47293.817654558225;7303;7460.850782984418;7655.540667559964;7897.921020789949;8011.479253848481;31069.53395699021;31958.52062219022;32001.359972800878;32019.839900800347;32724.345513089127;44104.77947610241;44634.3304850421;45665;46681.29978050264;46695;6795.830104247236;6866.4506839453725;6870.793876183722;7000.684969176501;7198.820029499305;11700;11746.177767556532;11790.351530665896;11849.348285844017;11912.404379780803;10703.357798532114;10908;11033.282836615594;11040.061594764535;11290.15323850731;10509.31689440184;10806;10964.122870170208;11060.059894909024;11072.667819965414;10203.132733717717;10241.385516869012;10527.526261318411;10679.94660026726;10894.346339219672;9078.228350590274;9255.722328330161;9359.682214505317;9521.761955951157;9692.176165026052;9042.773930651787;9214.907850921405;9266.151159890247;9403.247740180632;9603.37578057424
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: __end_tp_report
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: __start_cc_report
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,773
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: __end_cc_report
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: __startTimestamp1445936474825__endTimestamp
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT: Number of tests: 6
02:01:14 INFO - 2015-10-27 02:01:14,864 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:3989.09 Median:4136.34 stddev:499.35 (12.1%) stddev-sans-first:480.76
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: Values: 2200.3 2381.3 2392.1 2396.3 2404.0 4095.7 4125.2 4153.5 4167.5 4183.4 3882.7 3993.7 4020.8 4137.2 4204.7 3429.7 3466.8 3484.7 3640.0 3699.7 3636.9 3647.6 3653.9 3669.8 3670.2 3445.1 3646.7 3673.4 3685.5 3687.2 3393.8 3486.7 3519.6 3564.0 3608.5 3474.2 3533.6 3548.4 3614.2 3733.1 3805.5 4025.1 4092.2 4124.4 4179.0 4239.1 4302.3 4324.5 4345.1 4351.2 4356.5 4370.4 4441.6 4518.1 4526.5 4085.4 4129.9 4137.5 4217.8 4219.3 4271.9 4389.0 4439.2 4506.7 4513.8 4291.8 4322.8 4327.3 4377.6 4415.1 4394.5 4433.8 4463.1 4472.6 4489.8 4241.7 4312.5 4331.6 4347.2 4415.7 4125.5 4131.9 4304.2 4345.4 4430.1 2961.7 3038.6 3084.8 3088.1 3098.0 2689.2 2734.4 2785.6 2799.6 2803.9 3906.7 3941.8 4062.1 4128.0 4188.0 4267.4 4385.7 4455.5 4458.8 4476.2 3791.5 3938.7 4040.2 4135.0 4136.3 3742.6 3795.3 3802.9 3810.0 3811.4 4492.8 4503.6 4511.0 4598.3 4605.1 4221.3 4287.2 4291.3 4322.8 4402.8 4255.9 4262.0 4305.4 4329.9 4344.6 4098.8 4173.6 4182.1 4224.3 4411.0 3976.7 4087.9 4114.2 4183.7 4339.4 4005.7 4044.1 4077.9 4093.2 4099.4 4216.9 4262.7 4350.1 4351.0 4351.0 3990.3 4123.7 4155.1 4156.3 4195.6 4102.7 4198.6 4238.1 4340.2 4349.1 3911.8 4003.0 4090.7 4121.1 4250.2
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39721.94 Median:31068.50 stddev:64600.84 (207.9%) stddev-sans-first:64714.84
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: Values: 474.2 475.1 492.7 518.6 527.2 24929.0 35131.6 35291.0 36280.3 36460.5 37308.3 38382.4 39133.0 39249.4 39355.4 6296.5 6440.7 7515.9 8148.9 8171.6 9723.8 9946.3 9973.5 9975.5 10000.8 9387.4 9705.4 9825.6 9859.6 9938.2 6347.5 6564.6 9426.5 10139.6 10429.0 9969.9 10068.5 10123.9 10127.2 10128.9 36348.3 36540.3 36736.8 36753.4 36775.8 2724.6 2750.8 2816.3 3046.0 3407.3 27529.4 27740.3 27964.6 28062.0 28394.6 2016.5 2023.6 2036.6 2043.1 2132.2 223393.0 225545.0 225707.0 229705.0 239601.0 41057.4 41759.8 50229.5 50604.7 50655.2 53076.7 54848.5 55235.4 56124.4 58005.1 12533.7 12626.1 12803.9 12804.1 12805.7 9386.2 9511.7 9543.7 9552.0 9552.5 15253.3 15599.8 15883.9 16401.8 16413.8 7295.3 7303.6 7432.2 7486.9 7498.4 756.9 841.2 850.5 924.6 963.9 24981.4 25339.9 25438.1 25502.2 25784.5 878.7 888.2 929.9 936.2 986.4 13254.2 13291.7 13315.6 14103.3 14594.1 271430.0 277774.0 278022.0 278157.0 278833.0 43143.8 43162.6 43809.6 45054.8 45924.3 1091.2 1103.3 1132.9 1165.5 1169.0 18832.3 24885.5 25036.2 25073.0 26115.3 40307.8 40423.8 41868.0 41883.6 41902.4 234133.0 234589.0 234786.0 234850.0 235351.0 21282.4 26581.3 26887.5 27027.0 27068.0 32336.5 32594.0 33402.2 34550.8 34996.0 29178.3 30517.0 31103.0 31240.4 32038.0 30371.4 30720.7 31034.0 31585.5 31759.2 29331.9 30277.5 30309.0 30667.2 31679.0 25324.4 27052.0 27890.7 27977.6 29209.9 21936.5 22953.0 23028.4 23528.4 23616.8
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:19526.90 Median:2483.38 stddev:51956.77 (2092.2%) stddev-sans-first:52082.43
02:01:14 INFO - 2015-10-27 02:01:14,865 DEBUG : BROWSER_OUTPUT: Values: 282.8 282.9 290.0 293.6 294.6 11482.8 11536.9 15232.7 15639.8 15649.7 10053.5 14394.7 14605.9 14642.9 14654.5 4045.9 4190.9 4197.2 4200.0 4207.6 3036.1 3084.6 4085.0 4620.6 4716.3 4570.9 4658.3 4668.7 4676.0 4677.8 4514.5 4650.6 4658.1 4664.4 4675.6 3835.6 3837.3 3837.5 3839.8 3840.5 11845.9 11923.6 11951.4 12088.6 12472.0 1669.8 1793.0 1808.6 1811.0 1837.7 5356.3 9198.3 9233.5 9243.3 9477.5 1498.5 1508.8 1513.3 1554.2 1568.2 56185.4 56758.0 57912.7 58550.4 58675.4 5681.3 5807.4 5817.9 5835.2 5895.7 6874.6 6881.8 6889.4 6896.3 6901.3 4643.5 4728.1 4774.4 4813.3 4846.9 5035.1 5063.0 5064.2 5074.5 5091.7 2701.9 2735.4 2753.8 2757.0 2761.2 970.9 972.3 973.8 981.1 1019.5 357.0 379.5 394.2 399.1 400.7 10567.7 10939.0 10950.3 10998.1 11079.9 346.0 402.1 410.2 416.6 419.8 5258.7 5720.9 5734.4 5860.5 5867.8 304394.0 308908.7 316426.0 317062.0 318973.0 61955.7 62111.4 62778.0 62793.7 64168.0 445.8 449.8 450.7 451.8 452.3 10066.5 10113.5 10119.7 10291.8 10318.5 33114.8 33686.3 34277.5 34729.3 34743.5 46058.3 46452.8 46844.3 47089.5 47153.5 5719.1 5864.4 5869.7 5887.7 5912.0 11063.7 11291.5 11329.2 11503.5 11506.3 9606.2 9763.9 9851.3 9872.5 9903.2 9440.4 9606.1 9729.9 9809.0 9903.5 9652.4 9715.0 9986.0 10100.1 10145.8 4979.2 5357.3 5518.1 5699.4 5701.3 9217.8 9221.3 9286.6 9489.1 9552.3
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4543.19 Median:5918.41 stddev:2317.81 (39.2%) stddev-sans-first:2310.68
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT: Values: 1198.5 1229.0 1266.7 1377.8 1551.1 5729.9 6493.5 6655.8 6660.2 6661.7 6483.5 6561.2 6561.5 6567.7 6572.4 1918.6 1929.1 1936.2 1941.3 1942.7 1905.5 1924.4 1925.6 1957.6 1969.9 1917.9 1919.5 1929.1 1931.8 1933.8 1836.2 1892.6 1893.8 1894.2 1909.7 1835.7 1845.6 1880.1 1887.9 1898.1 5747.8 5907.4 6371.1 6516.0 6538.8 3874.6 3925.7 3954.8 3962.3 3968.0 2430.3 2435.3 2452.4 2462.5 2469.3 740.3 744.1 750.7 775.0 781.6 8195.4 8570.2 8586.5 8621.0 8626.1 3639.6 3711.9 3723.6 3752.5 3764.3 7530.7 7534.2 7562.1 7566.7 7574.5 3955.3 3977.9 3983.3 3985.2 4001.3 3635.8 3638.6 3655.1 3658.1 3662.1 2906.5 2919.6 2936.1 2954.5 2954.9 1004.6 1006.5 1012.9 1016.6 1022.3 827.8 837.9 839.0 839.2 840.3 6093.0 6093.3 6102.7 6108.4 6217.3 931.9 944.3 954.6 971.3 986.8 4798.7 4899.5 4912.4 4940.6 4941.8 6890.7 7161.5 7526.4 7622.7 7631.4 7299.9 7506.6 7509.0 7538.9 7542.9 5776.1 5929.4 5934.8 5958.8 5964.6 5689.7 5718.8 5793.6 5886.8 5904.1 6445.6 6651.2 6734.5 6735.0 6780.3 6944.5 7130.5 7155.6 7236.9 7279.1 6226.9 6245.8 6326.8 6329.9 6353.7 6407.9 6457.5 6463.9 6464.8 6468.2 5932.0 5935.8 6011.8 6107.7 6162.4 5740.8 5892.8 5981.2 5984.0 6072.1 6066.6 6106.1 6111.7 6115.8 6120.1 5236.5 5432.7 5436.8 5474.0 5474.4 5381.7 5564.2 5577.0 5583.4 5583.4
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4030.57 Median:4638.58 stddev:1211.61 (26.1%) stddev-sans-first:1181.53
02:01:14 INFO - 2015-10-27 02:01:14,868 DEBUG : BROWSER_OUTPUT: Values: 261.6 274.4 277.3 278.4 281.2 4618.8 4637.5 4639.7 4644.0 4648.6 4456.4 4464.7 4471.8 4529.6 4576.4 3351.0 3476.6 3478.8 3480.2 3483.6 3371.5 3469.8 3478.5 3479.5 3480.8 3403.1 3403.8 3438.2 3440.0 3443.2 3393.6 3452.7 3453.0 3454.9 3456.9 2985.2 3031.8 3032.2 3032.4 3046.3 4462.1 4463.7 4464.7 4468.7 4471.7 4083.2 4085.8 4090.7 4098.8 4104.0 4793.9 4794.9 4798.1 4799.3 4870.4 3253.1 3329.1 3337.8 3341.3 3345.0 4941.5 5012.0 5015.8 5021.1 5028.3 3396.8 3402.7 3403.2 3404.6 3405.8 3385.5 3436.4 3444.7 3446.0 3446.9 3738.0 3788.3 3951.2 3968.6 3988.3 4829.4 4895.1 4895.5 4896.4 4902.2 1225.0 1238.2 1257.3 1258.9 1259.0 798.4 821.0 833.2 835.4 839.1 4559.3 4608.8 4609.9 4611.3 4617.6 4993.8 4997.8 5005.9 5010.0 5018.1 4620.1 4631.4 4635.9 4646.1 4647.8 2648.8 2714.1 2728.2 2729.4 2737.4 5033.6 5037.7 5094.5 5111.9 5145.2 5103.9 5122.1 5146.0 5153.9 5158.1 5119.4 5156.7 5156.7 5162.8 5162.8 4939.9 4963.7 5031.2 5034.5 5035.7 4791.9 4842.5 4902.0 4999.6 5004.9 4687.0 4843.8 5006.6 5092.9 5151.2 3330.1 3445.6 3460.9 3468.2 3468.3 4965.6 4966.4 4968.2 4971.7 4972.1 4791.4 4794.0 4795.6 4798.1 4801.0 4783.0 4798.5 4803.5 4808.1 4808.3 4573.6 4692.0 4693.6 4706.2 4712.7 4805.6 4869.7 4872.0 4888.2 4892.2 4705.4 4785.5 4794.3 4801.9 4803.6
02:01:14 INFO - 2015-10-27 02:01:14,869 DEBUG : BROWSER_OUTPUT:
02:01:14 INFO - 2015-10-27 02:01:14,871 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13204.79 Median:468.34 stddev:16081.62 (3433.7%) stddev-sans-first:16098.74
02:01:14 INFO - 2015-10-27 02:01:14,871 DEBUG : BROWSER_OUTPUT: Values: 569.4 600.9 601.2 627.9 699.6 9956.5 10066.8 12390.3 12620.2 12637.7 12140.0 12417.2 12854.0 12859.0 12884.5 479.1 610.7 621.8 621.8 623.2 476.5 609.9 617.5 619.5 620.0 632.7 647.5 648.0 648.6 648.6 637.9 643.6 643.7 643.7 644.0 464.1 466.9 468.3 468.4 468.4 12298.6 12486.0 12486.6 12510.1 12551.1 11574.9 11638.2 11912.8 11989.7 12034.2 9836.3 9979.5 9994.0 10128.5 10200.9 3276.5 3286.3 3342.1 3370.6 3461.9 44302.8 44313.6 45354.8 45572.0 45603.3 9102.8 9140.4 9159.8 9177.8 9410.8 16440.8 16550.3 16572.3 16573.4 16599.1 6587.9 6621.7 6847.6 6867.2 6873.7 3142.2 3598.9 3629.4 3646.2 3657.4 2268.5 2289.6 2303.5 2420.2 2438.4 380.5 397.3 402.0 403.6 407.3 536.2 556.2 583.7 591.5 599.7 7513.3 8098.8 8227.2 8349.9 8815.8 481.4 508.7 600.5 640.6 641.5 3573.2 3665.2 3739.9 3832.0 3904.9 60095.0 60336.4 60745.4 60800.0 60881.1 49172.3 51004.5 51009.0 51010.5 51122.2 46225.0 46735.5 46940.8 47029.0 47293.8 7303.0 7460.9 7655.5 7897.9 8011.5 31069.5 31958.5 32001.4 32019.8 32724.3 44104.8 44634.3 45665.0 46681.3 46695.0 6795.8 6866.5 6870.8 7000.7 7198.8 11700.0 11746.2 11790.4 11849.3 11912.4 10703.4 10908.0 11033.3 11040.1 11290.2 10509.3 10806.0 10964.1 11060.1 11072.7 10203.1 10241.4 10527.5 10679.9 10894.3 9078.2 9255.7 9359.7 9521.8 9692.2 9042.8 9214.9 9266.2 9403.2 9603.4
02:01:14 INFO - 2015-10-27 02:01:14,871 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
02:01:14 INFO - 2015-10-27 02:01:14,871 DEBUG : BROWSER_OUTPUT:
02:01:15 INFO - 2015-10-27 02:01:15,414 INFO : Browser exited with error code: 0
02:01:16 INFO - 2015-10-27 02:01:16,151 INFO : Completed test dromaeo_css (00:18:44)
02:01:16 INFO - 2015-10-27 02:01:16,151 INFO : Starting test kraken
02:01:16 INFO - 2015-10-27 02:01:16,151 DEBUG : operating with platform_type : w7_
02:01:16 INFO - 2015-10-27 02:01:16,151 INFO : Initialising browser for kraken test...
02:01:16 INFO - 2015-10-27 02:01:16,176 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppkbezu\profile http://localhost:49847/getInfo.html
02:01:20 INFO - 2015-10-27 02:01:20,779 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
02:01:20 INFO - 2015-10-27 02:01:20,779 DEBUG : BROWSER_OUTPUT: colorDepth:24
02:01:20 INFO - 2015-10-27 02:01:20,779 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
02:01:20 INFO - 2015-10-27 02:01:20,779 DEBUG : BROWSER_OUTPUT: __metrics
02:01:20 INFO - 2015-10-27 02:01:20,875 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
02:01:21 INFO - 2015-10-27 02:01:21,634 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
02:01:22 INFO - 2015-10-27 02:01:22,401 INFO : Browser initialized.
02:01:22 INFO - 2015-10-27 02:01:22,401 INFO : Running cycle 1/1 for kraken test...
02:01:22 INFO - 2015-10-27 02:01:22,401 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppkbezu\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
02:01:34 INFO - 2015-10-27 02:01:34,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 141877248
02:01:34 INFO - 2015-10-27 02:01:34,043 DEBUG : BROWSER_OUTPUT:
02:01:36 INFO - 2015-10-27 02:01:36,608 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49847/tests/kraken/driver.html?testName=audio-beat-detection)
02:01:37 INFO - 2015-10-27 02:01:37,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 175611904
02:01:37 INFO - 2015-10-27 02:01:37,190 DEBUG : BROWSER_OUTPUT:
02:01:39 INFO - 2015-10-27 02:01:39,971 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49847/tests/kraken/driver.html?testName=audio-dft)
02:01:40 INFO - 2015-10-27 02:01:40,546 DEBUG : BROWSER_OUTPUT: RSS: Main: 178827264
02:01:40 INFO - 2015-10-27 02:01:40,546 DEBUG : BROWSER_OUTPUT:
02:01:43 INFO - 2015-10-27 02:01:43,302 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49847/tests/kraken/driver.html?testName=audio-fft)
02:01:43 INFO - 2015-10-27 02:01:43,881 DEBUG : BROWSER_OUTPUT: RSS: Main: 170700800
02:01:43 INFO - 2015-10-27 02:01:43,881 DEBUG : BROWSER_OUTPUT:
02:01:46 INFO - 2015-10-27 02:01:46,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49847/tests/kraken/driver.html?testName=audio-oscillator)
02:01:46 INFO - 2015-10-27 02:01:46,615 DEBUG : BROWSER_OUTPUT: RSS: Main: 169996288
02:01:46 INFO - 2015-10-27 02:01:46,615 DEBUG : BROWSER_OUTPUT:
02:01:48 INFO - 2015-10-27 02:01:48,890 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49847/tests/kraken/driver.html?testName=imaging-gaussian-blur)
02:01:49 INFO - 2015-10-27 02:01:49,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 168280064
02:01:49 INFO - 2015-10-27 02:01:49,457 DEBUG : BROWSER_OUTPUT:
02:01:52 INFO - 2015-10-27 02:01:52,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49847/tests/kraken/driver.html?testName=imaging-darkroom)
02:01:53 INFO - 2015-10-27 02:01:53,197 DEBUG : BROWSER_OUTPUT: RSS: Main: 162537472
02:01:53 INFO - 2015-10-27 02:01:53,197 DEBUG : BROWSER_OUTPUT:
02:01:56 INFO - 2015-10-27 02:01:56,594 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49847/tests/kraken/driver.html?testName=imaging-desaturate)
02:01:57 INFO - 2015-10-27 02:01:57,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 165126144
02:01:57 INFO - 2015-10-27 02:01:57,168 DEBUG : BROWSER_OUTPUT:
02:02:00 INFO - 2015-10-27 02:02:00,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49847/tests/kraken/driver.html?testName=json-parse-financial)
02:02:00 INFO - 2015-10-27 02:02:00,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 165031936
02:02:00 INFO - 2015-10-27 02:02:00,631 DEBUG : BROWSER_OUTPUT:
02:02:02 INFO - 2015-10-27 02:02:02,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49847/tests/kraken/driver.html?testName=json-stringify-tinderbox)
02:02:03 INFO - 2015-10-27 02:02:03,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 194547712
02:02:03 INFO - 2015-10-27 02:02:03,039 DEBUG : BROWSER_OUTPUT:
02:02:05 INFO - 2015-10-27 02:02:05,019 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-aes)
02:02:05 INFO - 2015-10-27 02:02:05,595 DEBUG : BROWSER_OUTPUT: RSS: Main: 199933952
02:02:05 INFO - 2015-10-27 02:02:05,595 DEBUG : BROWSER_OUTPUT:
02:02:08 INFO - 2015-10-27 02:02:08,009 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-ccm)
02:02:08 INFO - 2015-10-27 02:02:08,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 205545472
02:02:08 INFO - 2015-10-27 02:02:08,585 DEBUG : BROWSER_OUTPUT:
02:02:11 INFO - 2015-10-27 02:02:11,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
02:02:11 INFO - 2015-10-27 02:02:11,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 208068608
02:02:11 INFO - 2015-10-27 02:02:11,776 DEBUG : BROWSER_OUTPUT:
02:02:14 INFO - 2015-10-27 02:02:14,976 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
02:02:15 INFO - 2015-10-27 02:02:15,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 206839808
02:02:15 INFO - 2015-10-27 02:02:15,551 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,571 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49847/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49847/tests/kraken/driver.html?testName=ai-astar)
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 252583936
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: __start_tp_report
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |0;ai-astar;93;99;98;89;98;97;98;99;99;96
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;144;158;168;140;141;152;144;158;138
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |2;audio-dft;144;145;145;148;146;150;153;152;158;150
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |3;audio-fft;82;82;80;121;102;95;81;83;105;84
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;99;99;117;99;100;99;125;101;101;125
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;117;117;117;116;116;117;116;117;117
02:02:17 INFO - 2015-10-27 02:02:17,825 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;137;137;136;137;137;137;137;139;137;138
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;91;92;89;90;93;91;93;90;91
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;77;79;78;79;80;79;78;82;79;90
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;76;76;88;75;75;76;80;76;76;76
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;98;99;99;101;101;100;101;100;101;103
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;133;132;131;130;130;137;131;130;138;145
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;189;199;199;199;202;188;199;200;203;200
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;92;93;91;93;93;94;92;93;93;96
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: __end_tp_report
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: __start_cc_report
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,942
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: __end_cc_report
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: __startTimestamp1445936537824__endTimestamp
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
02:02:17 INFO - 2015-10-27 02:02:17,826 DEBUG : BROWSER_OUTPUT: Number of tests: 14
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:96.60 Median:98.00 stddev:3.24 (3.3%) stddev-sans-first:3.16
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: Values: 93.0 99.0 98.0 89.0 98.0 97.0 98.0 99.0 99.0 96.0
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.90 Median:154.00 stddev:9.94 (6.5%) stddev-sans-first:10.29
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: Values: 156.0 144.0 158.0 168.0 140.0 141.0 152.0 144.0 158.0 138.0
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:149.10 Median:150.00 stddev:4.41 (2.9%) stddev-sans-first:4.27
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: Values: 144.0 145.0 145.0 148.0 146.0 150.0 153.0 152.0 158.0 150.0
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:91.50 Median:82.00 stddev:13.85 (16.9%) stddev-sans-first:14.26
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: Values: 82.0 82.0 80.0 121.0 102.0 95.0 81.0 83.0 105.0 84.0
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:112.00 stddev:11.17 (10.0%) stddev-sans-first:11.51
02:02:17 INFO - 2015-10-27 02:02:17,828 DEBUG : BROWSER_OUTPUT: Values: 99.0 99.0 117.0 99.0 100.0 99.0 125.0 101.0 101.0 125.0
02:02:17 INFO - 2015-10-27 02:02:17,831 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,831 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50
02:02:17 INFO - 2015-10-27 02:02:17,831 DEBUG : BROWSER_OUTPUT: Values: 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 117.0
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:137.20 Median:137.00 stddev:0.79 (0.6%) stddev-sans-first:0.83
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT: Values: 137.0 137.0 136.0 137.0 137.0 137.0 137.0 139.0 137.0 138.0
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:91.00 Median:91.00 stddev:1.33 (1.5%) stddev-sans-first:1.36
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT: Values: 90.0 91.0 92.0 89.0 90.0 93.0 91.0 93.0 90.0 91.0
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,832 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.10 Median:79.00 stddev:3.73 (4.7%) stddev-sans-first:3.78
02:02:17 INFO - 2015-10-27 02:02:17,834 DEBUG : BROWSER_OUTPUT: Values: 77.0 79.0 78.0 79.0 80.0 79.0 78.0 82.0 79.0 90.0
02:02:17 INFO - 2015-10-27 02:02:17,834 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,834 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:77.40 Median:76.00 stddev:3.98 (5.2%) stddev-sans-first:4.19
02:02:17 INFO - 2015-10-27 02:02:17,834 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 88.0 75.0 75.0 76.0 80.0 76.0 76.0 76.0
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:100.30 Median:102.00 stddev:1.42 (1.4%) stddev-sans-first:1.24
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT: Values: 98.0 99.0 99.0 101.0 101.0 100.0 101.0 100.0 101.0 103.0
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:132.50 stddev:4.90 (3.7%) stddev-sans-first:5.19
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT: Values: 133.0 132.0 131.0 130.0 130.0 137.0 131.0 130.0 138.0 145.0
02:02:17 INFO - 2015-10-27 02:02:17,835 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,836 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.80 Median:199.50 stddev:5.09 (2.6%) stddev-sans-first:4.29
02:02:17 INFO - 2015-10-27 02:02:17,836 DEBUG : BROWSER_OUTPUT: Values: 189.0 199.0 199.0 199.0 202.0 188.0 199.0 200.0 203.0 200.0
02:02:17 INFO - 2015-10-27 02:02:17,836 DEBUG : BROWSER_OUTPUT:
02:02:17 INFO - 2015-10-27 02:02:17,836 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:93.00 Median:93.00 stddev:1.33 (1.4%) stddev-sans-first:1.36
02:02:17 INFO - 2015-10-27 02:02:17,838 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 91.0 93.0 93.0 94.0 92.0 93.0 93.0 96.0
02:02:17 INFO - 2015-10-27 02:02:17,838 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
02:02:17 INFO - 2015-10-27 02:02:17,838 DEBUG : BROWSER_OUTPUT:
02:02:18 INFO - 2015-10-27 02:02:18,546 INFO : Browser exited with error code: 0
02:02:19 INFO - 2015-10-27 02:02:19,404 INFO : Completed test kraken (00:01:03)
02:02:19 INFO - 2015-10-27 02:02:19,404 INFO : Starting test v8_7
02:02:19 INFO - 2015-10-27 02:02:19,404 DEBUG : operating with platform_type : w7_
02:02:19 INFO - 2015-10-27 02:02:19,404 INFO : Initialising browser for v8_7 test...
02:02:19 INFO - 2015-10-27 02:02:19,431 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpvmyldj\profile http://localhost:49847/getInfo.html
02:02:23 INFO - 2015-10-27 02:02:23,957 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
02:02:23 INFO - 2015-10-27 02:02:23,957 DEBUG : BROWSER_OUTPUT: colorDepth:24
02:02:23 INFO - 2015-10-27 02:02:23,957 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
02:02:23 INFO - 2015-10-27 02:02:23,957 DEBUG : BROWSER_OUTPUT: __metrics
02:02:24 INFO - 2015-10-27 02:02:24,092 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
02:02:24 INFO - 2015-10-27 02:02:24,848 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code.
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: console.error:
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no]
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: Stack:
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3
02:02:25 INFO - 2015-10-27 02:02:25,095 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14
02:02:25 INFO - 2015-10-27 02:02:25,096 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12
02:02:25 INFO - 2015-10-27 02:02:25,096 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5548.55
02:03:01 INFO - RETURN: kraken: 1620.80
02:03:01 INFO - RETURN: v8_7: 20061.57
02:03:01 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,203,25]]", "result": "20061.57"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,203,25]]", "result": "1620.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,203,25]]", "result": "5548.55"}}}
02:03:01 INFO - Return code: 0
02:03:01 INFO - # TBPL SUCCESS #
02:03:01 INFO - Running post-action listener: _resource_record_post_action
02:03:01 INFO - Running post-run listener: _resource_record_post_run
02:03:02 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 98271744; Write bytes: 451380224; Read time: 1608360; Write time: 5003680
02:03:02 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 2029568; Read time: 0; Write time: 89230
02:03:02 INFO - run-tests - Wall time: 1232s; CPU: 13.0%; Read bytes: 98271744; Write bytes: 427672064; Read time: 1608360; Write time: 4793910
02:03:02 INFO - Running post-run listener: _upload_blobber_files
02:03:02 INFO - Blob upload gear active.
02:03:02 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
02:03:02 INFO - Copying logs to upload dir...
02:03:02 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=1427.463000
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 28 secs) (at 2015-10-27 02:04:42.264876) =========
========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 02:04:42.265574) =========
'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-155
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-155
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-155
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/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/9rQ3RxJOTf272gaIP7buvg/artifacts/public/build/firefox-44.0a1.en-US.win32.zip'
========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-27 02:04:43.952664) =========
========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2015-10-27 02:04:43.952967) =========
'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-155
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-155
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-155
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: 9 secs) (at 2015-10-27 02:04:53.508393) =========
========= Started reboot slave lost (results: 0, elapsed: 35 secs) (at 2015-10-27 02:04:53.508848) =========
========= Finished reboot slave lost (results: 0, elapsed: 35 secs) (at 2015-10-27 02:05:28.761577) =========