builder: mozilla-inbound_xp-ix_test-other
slave: t-xp32-ix-165
starttime: 1446505848.82
results: success (0)
buildid: 20151102133708
builduid: 591c0ddc850f476fa48bfe7e0aee1ab5
revision: 4a48d855141720d3cf8e5c579db79fba4155b4fa
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:48.824945) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:48.825408) =========
========= Started set props: basedir (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:48.825729) =========
'cd'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['cd']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test
program finished with exit code 0
elapsedTime=0.168000
basedir: 'C:\\slave\\test'
========= master_lag: 1.33 =========
========= Finished set props: basedir (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:50.319871) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.320352) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.774147) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.774508) =========
'rm' '-rf' 'properties'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'properties']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.107000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.888563) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.888859) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.889331) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:50.889615) =========
'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:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
--15:10:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 6.80 MB/s
15:10:58 (6.80 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.613000
========= master_lag: 0.13 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:51.631392) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:51.631709) =========
'rm' '-rf' 'scripts'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=1.823000
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:53.502481) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:53.502836) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4a48d855141720d3cf8e5c579db79fba4155b4fa --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 4a48d855141720d3cf8e5c579db79fba4155b4fa --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-11-02 15:11:00,736 truncating revision to first 12 chars
2015-11-02 15:11:00,736 Setting DEBUG logging.
2015-11-02 15:11:00,736 attempt 1/10
2015-11-02 15:11:00,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/4a48d8551417?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-11-02 15:11:01,104 unpacking tar archive at: mozilla-inbound-4a48d8551417/testing/mozharness/
program finished with exit code 0
elapsedTime=1.088000
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-02 15:10:54.640948) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:54.641265) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:54.666893) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:54.667338) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 15:10:54.667787) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 6 mins, 30 secs) (at 2015-11-02 15:10:54.668167) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'other' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'other', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound-Non-PGO']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test/buildprops.json
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
15:11:01 INFO - MultiFileLogger online at 20151102 15:11:01 in C:\slave\test
15:11:01 INFO - Run as scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Mozilla-Inbound-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound-Non-PGO
15:11:01 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
15:11:01 INFO - {'append_to_log': False,
15:11:01 INFO - 'base_work_dir': 'C:\\slave\\test',
15:11:01 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO',
15:11:01 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
15:11:01 INFO - 'branch': 'Mozilla-Inbound-Non-PGO',
15:11:01 INFO - 'buildbot_json_path': 'buildprops.json',
15:11:01 INFO - 'config_files': ('talos/windows_config.py',),
15:11:01 INFO - 'default_actions': ('clobber',
15:11:01 INFO - 'read-buildbot-config',
15:11:01 INFO - 'download-and-extract',
15:11:01 INFO - 'populate-webroot',
15:11:01 INFO - 'create-virtualenv',
15:11:01 INFO - 'install',
15:11:01 INFO - 'run-tests'),
15:11:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:11:01 INFO - 'download_minidump_stackwalk': True,
15:11:01 INFO - 'download_symbols': 'ondemand',
15:11:01 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
15:11:01 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
15:11:01 INFO - 'hg': 'c:/mozilla-build/hg/hg',
15:11:01 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
15:11:01 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
15:11:01 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
15:11:01 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
15:11:01 INFO - 'C:/mozilla-build/tooltool.py'),
15:11:01 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
15:11:01 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
15:11:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:11:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:11:01 INFO - 'installer_path': 'installer.exe',
15:11:01 INFO - 'log_level': 'info',
15:11:01 INFO - 'log_name': 'talos',
15:11:01 INFO - 'log_to_console': True,
15:11:01 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
15:11:01 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
15:11:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
15:11:01 INFO - 'opt_config_files': (),
15:11:01 INFO - 'pip_index': False,
15:11:01 INFO - 'sps_profile': False,
15:11:01 INFO - 'sps_profile_interval': 0,
15:11:01 INFO - 'suite': 'other',
15:11:01 INFO - 'system_bits': '32',
15:11:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:11:01 INFO - 'title': 'T-XP32-IX-165',
15:11:01 INFO - 'use_talos_json': True,
15:11:01 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
15:11:01 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
15:11:01 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
15:11:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:11:01 INFO - 'work_dir': 'build'}
15:11:01 INFO - #####
15:11:01 INFO - ##### Running clobber step.
15:11:01 INFO - #####
15:11:01 INFO - Running pre-action listener: _resource_record_pre_action
15:11:01 INFO - Running main action method: clobber
15:11:02 INFO - rmtree: C:\slave\test\build
15:11:02 INFO - Using _rmtree_windows ...
15:11:02 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
15:11:26 INFO - Running post-action listener: _resource_record_post_action
15:11:26 INFO - #####
15:11:26 INFO - ##### Running read-buildbot-config step.
15:11:26 INFO - #####
15:11:26 INFO - Running pre-action listener: _resource_record_pre_action
15:11:26 INFO - Running main action method: read_buildbot_config
15:11:26 INFO - Using buildbot properties:
15:11:26 INFO - {
15:11:26 INFO - "properties": {
15:11:26 INFO - "buildnumber": 402,
15:11:26 INFO - "product": "firefox",
15:11:26 INFO - "script_repo_revision": "production",
15:11:26 INFO - "builddir": "mozilla-inbound_xp-ix_test-other",
15:11:26 INFO - "repository": "",
15:11:26 INFO - "buildername": "Windows XP 32-bit mozilla-inbound talos other",
15:11:26 INFO - "buildid": "20151102133708",
15:11:26 INFO - "slavename": "t-xp32-ix-165",
15:11:26 INFO - "pgo_build": "False",
15:11:26 INFO - "basedir": "C:\\slave\\test",
15:11:26 INFO - "project": "",
15:11:26 INFO - "platform": "xp-ix",
15:11:26 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
15:11:26 INFO - "slavebuilddir": "test",
15:11:26 INFO - "scheduler": "tests-mozilla-inbound-win32-talos",
15:11:26 INFO - "branch": "mozilla-inbound",
15:11:26 INFO - "repo_path": "integration/mozilla-inbound",
15:11:26 INFO - "stage_platform": "win32",
15:11:26 INFO - "builduid": "591c0ddc850f476fa48bfe7e0aee1ab5",
15:11:26 INFO - "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"
15:11:26 INFO - },
15:11:26 INFO - "sourcestamp": {
15:11:26 INFO - "repository": "",
15:11:26 INFO - "hasPatch": false,
15:11:26 INFO - "project": "",
15:11:26 INFO - "branch": "mozilla-inbound-win32-talos",
15:11:26 INFO - "changes": [
15:11:26 INFO - {
15:11:26 INFO - "category": null,
15:11:26 INFO - "files": [
15:11:26 INFO - {
15:11:26 INFO - "url": null,
15:11:26 INFO - "name": "https://queue.taskcluster.net/v1/task/hF0dAZATR5qw1vxSVble5Q/artifacts/public/build/firefox-45.0a1.en-US.win32.zip"
15:11:26 INFO - }
15:11:26 INFO - ],
15:11:26 INFO - "repository": "",
15:11:26 INFO - "rev": "d2b705bd76e1ac36b725746924b4ac11d1fe5ccc",
15:11:26 INFO - "who": "mchang@mozilla.com",
15:11:26 INFO - "when": 1446505537,
15:11:26 INFO - "number": 6611640,
15:11:26 INFO - "comments": "Bug 1220699 - Add telemetry probe to measure vsync latency in the parent refresh driver. r=avih",
15:11:26 INFO - "project": "",
15:11:26 INFO - "at": "Mon 02 Nov 2015 15:05:37",
15:11:26 INFO - "branch": "mozilla-inbound-win32-talos",
15:11:26 INFO - "revlink": "",
15:11:26 INFO - "properties": [
15:11:26 INFO - [
15:11:26 INFO - "buildid",
15:11:26 INFO - "20151102132207",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "builduid",
15:11:26 INFO - "8f4de7f253b342a984c8e036fe8fe6d3",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "pgo_build",
15:11:26 INFO - "False",
15:11:26 INFO - "Change"
15:11:26 INFO - ]
15:11:26 INFO - ],
15:11:26 INFO - "revision": "d2b705bd76e1ac36b725746924b4ac11d1fe5ccc"
15:11:26 INFO - },
15:11:26 INFO - {
15:11:26 INFO - "category": null,
15:11:26 INFO - "files": [
15:11:26 INFO - {
15:11:26 INFO - "url": null,
15:11:26 INFO - "name": "https://queue.taskcluster.net/v1/task/gzxxyj6UQ0OGrDQDdTxVPw/artifacts/public/build/firefox-45.0a1.en-US.win32.zip"
15:11:26 INFO - }
15:11:26 INFO - ],
15:11:26 INFO - "repository": "",
15:11:26 INFO - "rev": "3fd50056e877dbf41fdc91b870923280c2128d58",
15:11:26 INFO - "who": "mchang@mozilla.com",
15:11:26 INFO - "when": 1446505657,
15:11:26 INFO - "number": 6611651,
15:11:26 INFO - "comments": "Bug 1216506. Properly initialize min inset box shadow color to black. r=mstange",
15:11:26 INFO - "project": "",
15:11:26 INFO - "at": "Mon 02 Nov 2015 15:07:37",
15:11:26 INFO - "branch": "mozilla-inbound-win32-talos",
15:11:26 INFO - "revlink": "",
15:11:26 INFO - "properties": [
15:11:26 INFO - [
15:11:26 INFO - "buildid",
15:11:26 INFO - "20151102133106",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "builduid",
15:11:26 INFO - "c176f2a80a214cb19413d1cd3e960344",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "pgo_build",
15:11:26 INFO - "False",
15:11:26 INFO - "Change"
15:11:26 INFO - ]
15:11:26 INFO - ],
15:11:26 INFO - "revision": "3fd50056e877dbf41fdc91b870923280c2128d58"
15:11:26 INFO - },
15:11:26 INFO - {
15:11:26 INFO - "category": null,
15:11:26 INFO - "files": [
15:11:26 INFO - {
15:11:26 INFO - "url": null,
15:11:26 INFO - "name": "https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip"
15:11:26 INFO - }
15:11:26 INFO - ],
15:11:26 INFO - "repository": "",
15:11:26 INFO - "rev": "4a48d855141720d3cf8e5c579db79fba4155b4fa",
15:11:26 INFO - "who": "rgiles@mozilla.com",
15:11:26 INFO - "when": 1446505414,
15:11:26 INFO - "number": 6611616,
15:11:26 INFO - "comments": "Bug 1219047 - Call rust mp4parser with logging. r=kinetik\n\nAdd a helper for passing the initialization segments\nof mp4 streams to the rust parser and log the result.\n\nThis runs real data through the new parser for testing\nbut doesn't use the results.\n\nCode is conditional on MOZ_RUST_MP4PARSE to be defined\nin confvars.sh. See bug 1219530.",
15:11:26 INFO - "project": "",
15:11:26 INFO - "at": "Mon 02 Nov 2015 15:03:34",
15:11:26 INFO - "branch": "mozilla-inbound-win32-talos",
15:11:26 INFO - "revlink": "",
15:11:26 INFO - "properties": [
15:11:26 INFO - [
15:11:26 INFO - "buildid",
15:11:26 INFO - "20151102133708",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "builduid",
15:11:26 INFO - "591c0ddc850f476fa48bfe7e0aee1ab5",
15:11:26 INFO - "Change"
15:11:26 INFO - ],
15:11:26 INFO - [
15:11:26 INFO - "pgo_build",
15:11:26 INFO - "False",
15:11:26 INFO - "Change"
15:11:26 INFO - ]
15:11:26 INFO - ],
15:11:26 INFO - "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"
15:11:26 INFO - }
15:11:26 INFO - ],
15:11:26 INFO - "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"
15:11:26 INFO - }
15:11:26 INFO - }
15:11:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip.
15:11:26 INFO - Running post-action listener: _resource_record_post_action
15:11:26 INFO - #####
15:11:26 INFO - ##### Running download-and-extract step.
15:11:26 INFO - #####
15:11:26 INFO - Running pre-action listener: _resource_record_pre_action
15:11:26 INFO - Running main action method: download_and_extract
15:11:26 INFO - mkdir: C:\slave\test\build\tests
15:11: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')]}
15:11:26 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
15:11:26 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/test_packages.json
15:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
15:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
15:11:27 INFO - Downloaded 1221 bytes.
15:11:27 INFO - Reading from file C:\slave\test\build\test_packages.json
15:11:27 INFO - Using the following test package requirements:
15:11:27 INFO - {u'common': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
15:11:27 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.cppunittest.tests.zip'],
15:11:27 INFO - u'jittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'jsshell-win32.zip'],
15:11:27 INFO - u'mochitest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.mochitest.tests.zip'],
15:11:27 INFO - u'mozbase': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
15:11:27 INFO - u'reftest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.reftest.tests.zip'],
15:11:27 INFO - u'talos': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.talos.tests.zip'],
15:11:27 INFO - u'web-platform': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.web-platform.tests.zip'],
15:11:27 INFO - u'webapprt': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
15:11:27 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
15:11:27 INFO - u'firefox-45.0a1.en-US.win32.xpcshell.tests.zip']}
15:11:27 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip'] for test suite category: common
15:11:27 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')]}
15:11:27 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
15:11:27 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
15:11:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
15:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:11:29 INFO - Downloaded 18010123 bytes.
15:11:29 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests
15:11:29 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
15:11:29 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
15:11:46 INFO - Return code: 0
15:11:46 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip', u'firefox-45.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
15:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:11:46 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
15:11:46 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
15:11:46 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
15:11:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'}, attempt #1
15:11:48 INFO - Downloaded 18010123 bytes.
15:11:48 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests
15:11:48 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
15:11:48 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
15:12:02 INFO - Return code: 0
15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:02 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
15:12:02 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip
15:12:02 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip
15:12:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'}, attempt #1
15:12:04 INFO - Downloaded 11131307 bytes.
15:12:04 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests
15:12:04 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip
15:12:04 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
15:12:04 INFO - Return code: 0
15:12: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')]}
15:12:04 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip matches https://queue.taskcluster.net
15:12:04 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
15:12:04 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.zip
15:12:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.zip'}, attempt #1
15:12:09 INFO - Downloaded 51564483 bytes.
15:12:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
15:12:09 INFO - mkdir: C:\slave\test\properties
15:12:09 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
15:12:09 INFO - Writing to file C:\slave\test\properties\build_url
15:12:09 INFO - Contents:
15:12:09 INFO - build_url:https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
15:12:09 INFO - Running post-action listener: _resource_record_post_action
15:12:09 INFO - Running post-action listener: set_extra_try_arguments
15:12:09 INFO - #####
15:12:09 INFO - ##### Running populate-webroot step.
15:12:09 INFO - #####
15:12:09 INFO - Running pre-action listener: _resource_record_pre_action
15:12:09 INFO - Running main action method: populate_webroot
15:12:09 INFO - Guessing talos json url...
15:12:09 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')]}
15:12:09 INFO - https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.txt matches https://queue.taskcluster.net
15:12:09 INFO - trying https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.txt
15:12:09 INFO - Downloading https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.txt to C:\slave\test\build\firefox-45.0a1.en-US.win32.txt
15:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.txt'}, attempt #1
15:12:10 INFO - Downloaded 111 bytes.
15:12:10 INFO - Reading from file C:\slave\test\build\firefox-45.0a1.en-US.win32.txt
15:12:10 INFO - Contents:
15:12:10 INFO - 20151102133708
15:12:10 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/4a48d855141720d3cf8e5c579db79fba4155b4fa
15:12:10 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')]}
15:12:10 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/4a48d85514/testing/talos/talos.json
15:12:10 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/4a48d85514/testing/talos/talos.json to C:\slave\test\build\talos.json
15:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/4a48d85514/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1
15:12:13 INFO - Downloaded 4264 bytes.
15:12:13 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
15:12:13 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
15:12:13 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
15:12:13 INFO - u'../fennec_ids.txt'],
15:12:13 INFO - u'tests': [u'tcheck2']},
15:12:13 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
15:12:13 INFO - u'--tppagecycles',
15:12:13 INFO - u'7'],
15:12:13 INFO - u'tests': [u'tsvgm']}},
15:12:13 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
15:12:13 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'tresize', u'tcanvasmark']},
15:12:13 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
15:12:13 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'dromaeo_css',
15:12:13 INFO - u'kraken',
15:12:13 INFO - u'v8_7']},
15:12:13 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:12:13 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
15:12:13 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'tests': [u'damp', u'tps']},
15:12:13 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'damp', u'tps']},
15:12:13 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
15:12:13 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
15:12:13 INFO - u'other': {u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'other_l64': {u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'other_nol64': {u'tests': [u'a11yr',
15:12:13 INFO - u'ts_paint',
15:12:13 INFO - u'tpaint',
15:12:13 INFO - u'sessionrestore',
15:12:13 INFO - u'sessionrestore_no_auto_restore']},
15:12:13 INFO - u'svgr': {u'tests': [u'tsvgx',
15:12:13 INFO - u'tsvgr_opacity',
15:12:13 INFO - u'tart',
15:12:13 INFO - u'tscrollx',
15:12:13 INFO - u'cart']},
15:12:13 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'tsvgx',
15:12:13 INFO - u'tsvgr_opacity',
15:12:13 INFO - u'tart',
15:12:13 INFO - u'tscrollx',
15:12:13 INFO - u'cart']},
15:12:13 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'tests': [u'tp5o']},
15:12:13 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'talos_options': [u'--e10s'],
15:12:13 INFO - u'tests': [u'tp5o']},
15:12:13 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'talos_options': [u'--xperf_path',
15:12:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:12:13 INFO - u'tests': [u'tp5n']},
15:12:13 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
15:12:13 INFO - u'talos_options': [u'--e10s',
15:12:13 INFO - u'--xperf_path',
15:12:13 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"',
15:12:13 INFO - u'C:/slave/talos-data/talos/xperf.config'],
15:12:13 INFO - u'tests': [u'tp5n']}},
15:12:13 INFO - u'talos.zip': {u'path': u'',
15:12:13 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
15:12:13 INFO - Running post-action listener: _resource_record_post_action
15:12:13 INFO - #####
15:12:13 INFO - ##### Running create-virtualenv step.
15:12:13 INFO - #####
15:12:13 INFO - Running pre-action listener: _resource_record_pre_action
15:12:13 INFO - Running main action method: create_virtualenv
15:12:13 INFO - Creating virtualenv C:\slave\test\build/venv
15:12:13 INFO - mkdir: C:\slave\test\build\venv\Scripts
15:12:13 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
15:12:13 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'!
15:12:13 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
15:12:13 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
15:12:14 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
15:12:15 INFO - Installing distribute......................................................................................................................................................................................done.
15:12:16 INFO - Return code: 0
15:12:16 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
15:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:16 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
15:12:16 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
15:12:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:16 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:16 INFO - 'CLIENTNAME': 'Console',
15:12:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:16 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:16 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:16 INFO - 'DCLOCATION': 'SCL3',
15:12:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:16 INFO - 'HOMEDRIVE': 'C:',
15:12:16 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:16 INFO - 'KTS_VERSION': '1.19c',
15:12:16 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:16 INFO - 'MOZ_NO_REMOTE': '1',
15:12:16 INFO - 'NO_EM_RESTART': '1',
15:12:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:16 INFO - 'OS': 'Windows_NT',
15:12:16 INFO - 'OURDRIVE': 'C:',
15:12:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:12:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:16 INFO - 'PROCESSOR_LEVEL': '6',
15:12:16 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:16 INFO - 'PROMPT': '$P$G',
15:12:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:16 INFO - 'PWD': 'C:\\slave\\test',
15:12:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:16 INFO - 'SESSIONNAME': 'Console',
15:12:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:16 INFO - 'SYSTEMDRIVE': 'C:',
15:12:16 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:16 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:16 INFO - 'TEST1': 'testie',
15:12:16 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:16 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:16 INFO - 'USERNAME': 'cltbld',
15:12:16 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:16 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:17 INFO - Ignoring indexes: http://pypi.python.org/simple/
15:12:17 INFO - Downloading/unpacking pip>=1.5
15:12:17 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
15:12:20 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
15:12:20 INFO - Running setup.py egg_info for package pip
15:12:20 INFO - warning: no files found matching 'pip\cacert.pem'
15:12:20 INFO - warning: no files found matching '*.html' under directory 'docs'
15:12:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:12:20 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:12:20 INFO - Installing collected packages: pip
15:12:20 INFO - Found existing installation: pip 0.8.2
15:12:20 INFO - Uninstalling pip:
15:12:20 INFO - Successfully uninstalled pip
15:12:20 INFO - Running setup.py install for pip
15:12:20 INFO - warning: no files found matching 'pip\cacert.pem'
15:12:20 INFO - warning: no files found matching '*.html' under directory 'docs'
15:12:20 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
15:12:20 INFO - no previously-included directories found matching 'docs\_build\_sources'
15:12:20 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
15:12:20 INFO - Successfully installed pip
15:12:20 INFO - Cleaning up...
15:12:20 INFO - Return code: 0
15:12:20 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
15:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build
15:12:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
15:12:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:20 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:20 INFO - 'CLIENTNAME': 'Console',
15:12:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:20 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:20 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:20 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:20 INFO - 'DCLOCATION': 'SCL3',
15:12:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:20 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:20 INFO - 'HOMEDRIVE': 'C:',
15:12:20 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:20 INFO - 'KTS_VERSION': '1.19c',
15:12:20 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:20 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:20 INFO - 'MOZ_NO_REMOTE': '1',
15:12:20 INFO - 'NO_EM_RESTART': '1',
15:12:20 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:20 INFO - 'OS': 'Windows_NT',
15:12:20 INFO - 'OURDRIVE': 'C:',
15:12:20 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:12:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:20 INFO - 'PROCESSOR_LEVEL': '6',
15:12:20 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:20 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:20 INFO - 'PROMPT': '$P$G',
15:12:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:20 INFO - 'PWD': 'C:\\slave\\test',
15:12:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:20 INFO - 'SESSIONNAME': 'Console',
15:12:20 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:20 INFO - 'SYSTEMDRIVE': 'C:',
15:12:20 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:20 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:20 INFO - 'TEST1': 'testie',
15:12:20 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:20 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:20 INFO - 'USERNAME': 'cltbld',
15:12:20 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:20 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:20 INFO - Downloading/unpacking psutil>=0.7.1
15:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:12:21 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
15:12:21 INFO - Installing collected packages: psutil
15:12:21 INFO - Successfully installed psutil
15:12:21 INFO - Cleaning up...
15:12:21 INFO - Return code: 0
15:12:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
15:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:21 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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:21 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
15:12:21 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
15:12:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:21 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:21 INFO - 'CLIENTNAME': 'Console',
15:12:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:21 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:21 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:21 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:21 INFO - 'DCLOCATION': 'SCL3',
15:12:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:21 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:21 INFO - 'HOMEDRIVE': 'C:',
15:12:21 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:21 INFO - 'KTS_VERSION': '1.19c',
15:12:21 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:21 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:21 INFO - 'MOZ_NO_REMOTE': '1',
15:12:21 INFO - 'NO_EM_RESTART': '1',
15:12:21 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:21 INFO - 'OS': 'Windows_NT',
15:12:21 INFO - 'OURDRIVE': 'C:',
15:12:21 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;',
15:12:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:21 INFO - 'PROCESSOR_LEVEL': '6',
15:12:21 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:21 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:21 INFO - 'PROMPT': '$P$G',
15:12:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:21 INFO - 'PWD': 'C:\\slave\\test',
15:12:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:21 INFO - 'SESSIONNAME': 'Console',
15:12:21 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:21 INFO - 'SYSTEMDRIVE': 'C:',
15:12:21 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:21 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:21 INFO - 'TEST1': 'testie',
15:12:21 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:21 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:21 INFO - 'USERNAME': 'cltbld',
15:12:21 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:21 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
15:12: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
15:12: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
15:12:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz
15:12:23 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
15:12:23 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
15:12:23 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)
15:12:23 INFO - Installing collected packages: mozsystemmonitor
15:12:23 INFO - Running setup.py install for mozsystemmonitor
15:12:23 INFO - Successfully installed mozsystemmonitor
15:12:23 INFO - Cleaning up...
15:12:23 INFO - Return code: 0
15:12:23 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
15:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:23 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:23 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
15:12:23 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
15:12:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:23 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:23 INFO - 'CLIENTNAME': 'Console',
15:12:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:23 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:23 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:23 INFO - 'DCLOCATION': 'SCL3',
15:12:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:23 INFO - 'HOMEDRIVE': 'C:',
15:12:23 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:23 INFO - 'KTS_VERSION': '1.19c',
15:12:23 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:23 INFO - 'MOZ_NO_REMOTE': '1',
15:12:23 INFO - 'NO_EM_RESTART': '1',
15:12:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:23 INFO - 'OS': 'Windows_NT',
15:12:23 INFO - 'OURDRIVE': 'C:',
15:12:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
15:12:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:23 INFO - 'PROCESSOR_LEVEL': '6',
15:12:23 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:23 INFO - 'PROMPT': '$P$G',
15:12:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:23 INFO - 'PWD': 'C:\\slave\\test',
15:12:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:23 INFO - 'SESSIONNAME': 'Console',
15:12:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:23 INFO - 'SYSTEMDRIVE': 'C:',
15:12:23 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:23 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:23 INFO - 'TEST1': 'testie',
15:12:23 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:23 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:23 INFO - 'USERNAME': 'cltbld',
15:12:23 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:23 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:24 INFO - Downloading/unpacking blobuploader==1.2.4
15:12:24 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
15:12:24 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
15:12:25 INFO - Downloading blobuploader-1.2.4.tar.gz
15:12:25 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
15:12:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
15:12:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
15:12:25 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
15:12:25 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
15:12:25 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
15:12:25 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
15:12:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
15:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
15:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
15:12:26 INFO - Downloading docopt-0.6.1.tar.gz
15:12:26 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
15:12:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
15:12:26 INFO - Installing collected packages: blobuploader, requests, docopt
15:12:26 INFO - Running setup.py install for blobuploader
15:12:26 INFO - Running setup.py install for requests
15:12:27 INFO - Running setup.py install for docopt
15:12:27 INFO - Successfully installed blobuploader requests docopt
15:12:27 INFO - Cleaning up...
15:12:27 INFO - Return code: 0
15:12:27 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:12:27 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')]}
15:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:27 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')]}
15:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:27 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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:27 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
15:12:27 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
15:12:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:27 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:27 INFO - 'CLIENTNAME': 'Console',
15:12:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:27 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:27 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:27 INFO - 'DCLOCATION': 'SCL3',
15:12:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:27 INFO - 'HOMEDRIVE': 'C:',
15:12:27 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:27 INFO - 'KTS_VERSION': '1.19c',
15:12:27 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:27 INFO - 'MOZ_NO_REMOTE': '1',
15:12:27 INFO - 'NO_EM_RESTART': '1',
15:12:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:27 INFO - 'OS': 'Windows_NT',
15:12:27 INFO - 'OURDRIVE': 'C:',
15:12: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;',
15:12:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:27 INFO - 'PROCESSOR_LEVEL': '6',
15:12:27 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:27 INFO - 'PROMPT': '$P$G',
15:12:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:27 INFO - 'PWD': 'C:\\slave\\test',
15:12:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:27 INFO - 'SESSIONNAME': 'Console',
15:12:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:27 INFO - 'SYSTEMDRIVE': 'C:',
15:12:27 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:27 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:27 INFO - 'TEST1': 'testie',
15:12:27 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:27 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:27 INFO - 'USERNAME': 'cltbld',
15:12:27 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:27 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:12:28 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rinrbm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:12:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:12:28 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-a7lphw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:12:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:12:28 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-uxjl2v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:12:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:12:28 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-krujpd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ns3ed8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-mv8jit-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-l9zeyc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ms6exf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xjh8am-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:12:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:12:29 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-fdshff-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:12:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:12:30 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ckm3zr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:12:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:12:30 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-tott2b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:12:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:12:30 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-oqbpn_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:12:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:12:30 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-khes0f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:12:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:12:30 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-kzpqul-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:12:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:12:31 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-b43m4s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:12:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:12:31 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-dej2r1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:12:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:12:31 INFO - Running setup.py install for manifestparser
15:12:31 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
15:12:31 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
15:12:31 INFO - Running setup.py install for mozcrash
15:12:32 INFO - Running setup.py install for mozdebug
15:12:32 INFO - Running setup.py install for mozdevice
15:12:32 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
15:12:32 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
15:12:32 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
15:12:32 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
15:12:32 INFO - Running setup.py install for mozfile
15:12:33 INFO - Running setup.py install for mozhttpd
15:12:33 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
15:12:33 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
15:12:33 INFO - Running setup.py install for mozinfo
15:12:33 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
15:12:33 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
15:12:33 INFO - Running setup.py install for mozInstall
15:12:34 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Running setup.py install for mozleak
15:12:34 INFO - Running setup.py install for mozlog
15:12:34 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
15:12:34 INFO - Running setup.py install for moznetwork
15:12:35 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Running setup.py install for mozprocess
15:12:35 INFO - Running setup.py install for mozprofile
15:12:35 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
15:12:35 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
15:12:36 INFO - Running setup.py install for mozrunner
15:12:36 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
15:12:36 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
15:12:36 INFO - Running setup.py install for mozscreenshot
15:12:36 INFO - Running setup.py install for moztest
15:12:37 INFO - Running setup.py install for mozversion
15:12:37 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
15:12:37 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
15:12:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
15:12:37 INFO - Cleaning up...
15:12:37 INFO - Return code: 0
15:12:37 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:37 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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:37 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
15:12:37 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
15:12:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:37 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:37 INFO - 'CLIENTNAME': 'Console',
15:12:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:37 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:37 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:37 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:37 INFO - 'DCLOCATION': 'SCL3',
15:12:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:37 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:37 INFO - 'HOMEDRIVE': 'C:',
15:12:37 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:37 INFO - 'KTS_VERSION': '1.19c',
15:12:37 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:37 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:37 INFO - 'MOZ_NO_REMOTE': '1',
15:12:37 INFO - 'NO_EM_RESTART': '1',
15:12:37 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:37 INFO - 'OS': 'Windows_NT',
15:12:37 INFO - 'OURDRIVE': 'C:',
15:12:37 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;',
15:12:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:37 INFO - 'PROCESSOR_LEVEL': '6',
15:12:37 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:37 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:37 INFO - 'PROMPT': '$P$G',
15:12:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:37 INFO - 'PWD': 'C:\\slave\\test',
15:12:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:37 INFO - 'SESSIONNAME': 'Console',
15:12:37 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:37 INFO - 'SYSTEMDRIVE': 'C:',
15:12:37 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:37 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:37 INFO - 'TEST1': 'testie',
15:12:37 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:37 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:37 INFO - 'USERNAME': 'cltbld',
15:12:37 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:37 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lqrli0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
15:12:38 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))
15:12:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rl1hgu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
15:12:38 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))
15:12:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-le0x6r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
15:12:38 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))
15:12:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zzerxx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
15:12:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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))
15:12:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-53l5u1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
15:12:38 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))
15:12:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
15:12:38 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zahqpj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
15:12:39 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))
15:12:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
15:12:39 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xbgray-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
15:12:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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))
15:12:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
15:12:39 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-u3mlsw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
15:12:39 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))
15:12:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
15:12:39 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rvqt8m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
15:12:39 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))
15:12:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
15:12:39 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vkdpn4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
15:12:39 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))
15:12:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
15:12:39 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qsexxm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
15:12:40 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))
15:12:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
15:12:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-__whdu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
15:12:40 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))
15:12:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
15:12:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zwjdea-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
15:12:40 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))
15:12:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
15:12:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wnhniw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
15:12:40 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))
15:12:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
15:12:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qwlbqm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
15:12:40 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))
15:12:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
15:12:40 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qjq0rp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
15:12:41 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))
15:12:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
15:12:41 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-7iyku3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
15:12:41 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))
15:12:41 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))
15:12:41 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))
15:12:41 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))
15:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
15:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
15:12:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
15:12:41 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
15:12:41 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
15:12:42 INFO - Downloading blessings-1.5.1.tar.gz
15:12:42 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
15:12:42 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
15:12:42 INFO - Installing collected packages: blessings
15:12:42 INFO - Running setup.py install for blessings
15:12:42 INFO - Successfully installed blessings
15:12:42 INFO - Cleaning up...
15:12:42 INFO - Return code: 0
15:12:42 INFO - Done creating virtualenv C:\slave\test\build/venv.
15:12:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:12:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:12:43 INFO - Reading from file tmpfile_stdout
15:12:43 INFO - Using _rmtree_windows ...
15:12:43 INFO - Using _rmtree_windows ...
15:12:43 INFO - Current package versions:
15:12:43 INFO - blessings == 1.5.1
15:12:43 INFO - blobuploader == 1.2.4
15:12:43 INFO - docopt == 0.6.1
15:12:43 INFO - manifestparser == 1.1
15:12:43 INFO - mozInstall == 1.12
15:12:43 INFO - mozcrash == 0.16
15:12:43 INFO - mozdebug == 0.1
15:12:43 INFO - mozdevice == 0.47
15:12:43 INFO - mozfile == 1.2
15:12:43 INFO - mozhttpd == 0.7
15:12:43 INFO - mozinfo == 0.9
15:12:43 INFO - mozleak == 0.1
15:12:43 INFO - mozlog == 3.0
15:12:43 INFO - moznetwork == 0.27
15:12:43 INFO - mozprocess == 0.22
15:12:43 INFO - mozprofile == 0.27
15:12:43 INFO - mozrunner == 6.11
15:12:43 INFO - mozscreenshot == 0.1
15:12:43 INFO - mozsystemmonitor == 0.0
15:12:43 INFO - moztest == 0.7
15:12:43 INFO - mozversion == 1.4
15:12:43 INFO - psutil == 3.1.1
15:12:43 INFO - requests == 1.2.3
15:12:43 INFO - Installing None into virtualenv C:\slave\test\build/venv
15:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:12:43 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 0x00C58B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C93EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C13928>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-165', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-165', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-165', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'CLIENTNAME': 'Console', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', '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', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165'}}, attempt #1
15:12:43 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
15:12:43 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
15:12:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:43 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:43 INFO - 'CLIENTNAME': 'Console',
15:12:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:43 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:43 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:43 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:43 INFO - 'DCLOCATION': 'SCL3',
15:12:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:43 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:43 INFO - 'HOMEDRIVE': 'C:',
15:12:43 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:43 INFO - 'KTS_VERSION': '1.19c',
15:12:43 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:43 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:43 INFO - 'MOZ_NO_REMOTE': '1',
15:12:43 INFO - 'NO_EM_RESTART': '1',
15:12:43 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:43 INFO - 'OS': 'Windows_NT',
15:12:43 INFO - 'OURDRIVE': 'C:',
15:12:43 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;',
15:12:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:43 INFO - 'PROCESSOR_LEVEL': '6',
15:12:43 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:43 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:43 INFO - 'PROMPT': '$P$G',
15:12:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:43 INFO - 'PWD': 'C:\\slave\\test',
15:12:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:43 INFO - 'SESSIONNAME': 'Console',
15:12:43 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:43 INFO - 'SYSTEMDRIVE': 'C:',
15:12:43 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:43 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:43 INFO - 'TEST1': 'testie',
15:12:43 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:43 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:43 INFO - 'USERNAME': 'cltbld',
15:12:43 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:43 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 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))
15:12:43 INFO - Cleaning up...
15:12:44 INFO - Return code: 0
15:12:44 INFO - Running post-action listener: _resource_record_post_action
15:12:44 INFO - Running post-action listener: _start_resource_monitoring
15:12:44 INFO - Starting resource monitoring.
15:12:44 INFO - #####
15:12:44 INFO - ##### Running install step.
15:12:44 INFO - #####
15:12:44 INFO - Running pre-action listener: _resource_record_pre_action
15:12:44 INFO - Running main action method: install
15:12:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
15:12:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
15:12:44 INFO - Reading from file tmpfile_stdout
15:12:44 INFO - Using _rmtree_windows ...
15:12:44 INFO - Using _rmtree_windows ...
15:12:44 INFO - Detecting whether we're running mozinstall >=1.0...
15:12:44 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
15:12:44 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
15:12:44 INFO - Reading from file tmpfile_stdout
15:12:44 INFO - Output received:
15:12:44 INFO - Usage: mozinstall-script.py [options] installer
15:12:44 INFO - Options:
15:12:44 INFO - -h, --help show this help message and exit
15:12:44 INFO - -d DEST, --destination=DEST
15:12:44 INFO - Directory to install application into. [default:
15:12:44 INFO - "C:\slave\test"]
15:12:44 INFO - --app=APP Application being installed. [default: firefox]
15:12:44 INFO - Using _rmtree_windows ...
15:12:44 INFO - Using _rmtree_windows ...
15:12:44 INFO - mkdir: C:\slave\test\build\application
15:12:44 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-45.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
15:12:44 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-45.0a1.en-US.win32.zip --destination C:\slave\test\build\application
15:12:46 INFO - Reading from file tmpfile_stdout
15:12:46 INFO - Output received:
15:12:46 INFO - C:\slave\test\build\application\firefox\firefox.exe
15:12:46 INFO - Using _rmtree_windows ...
15:12:46 INFO - Using _rmtree_windows ...
15:12:46 INFO - Running post-action listener: _resource_record_post_action
15:12:46 INFO - #####
15:12:46 INFO - ##### Running run-tests step.
15:12:46 INFO - #####
15:12:46 INFO - Running pre-action listener: _resource_record_pre_action
15:12:46 INFO - Running main action method: run_tests
15:12:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
15:12:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
15:12:46 INFO - Python 2.7.3
15:12:46 INFO - Return code: 0
15:12:46 INFO - grabbing minidump binary from tooltool
15:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
15:12:46 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 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00C3A660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
15:12:46 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
15:12:46 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
15:12:46 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
15:12:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp5ajpdn
15:12:49 INFO - INFO - File integrity verified, renaming tmp5ajpdn to win32-minidump_stackwalk.exe
15:12:49 INFO - Return code: 0
15:12:49 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
15:12:49 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
15:12:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
15:12:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
15:12:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
15:12:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'other', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-165', '--webServer', 'localhost'] in C:\slave\test\build
15:12:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound-Non-PGO --suite other --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip --title T-XP32-IX-165 --webServer localhost
15:12:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
15:12:49 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165\\Application Data',
15:12:49 INFO - 'CLIENTNAME': 'Console',
15:12:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
15:12:49 INFO - 'COMPUTERNAME': 'T-XP32-IX-165',
15:12:49 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
15:12:49 INFO - 'CYGWINBASE': 'C:\\cygwin',
15:12:49 INFO - 'DCLOCATION': 'SCL3',
15:12:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
15:12:49 INFO - 'FP_NO_HOST_CHECK': 'NO',
15:12:49 INFO - 'HOMEDRIVE': 'C:',
15:12:49 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
15:12:49 INFO - 'KTS_VERSION': '1.19c',
15:12:49 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-165',
15:12:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:12:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
15:12:49 INFO - 'MONDIR': 'C:\\Monitor_config\\',
15:12:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
15:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:12:49 INFO - 'MOZ_NO_REMOTE': '1',
15:12:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
15:12:49 INFO - 'NO_EM_RESTART': '1',
15:12:49 INFO - 'NUMBER_OF_PROCESSORS': '8',
15:12:49 INFO - 'OS': 'Windows_NT',
15:12:49 INFO - 'OURDRIVE': 'C:',
15:12:49 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;',
15:12:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
15:12:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
15:12:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
15:12:49 INFO - 'PROCESSOR_LEVEL': '6',
15:12:49 INFO - 'PROCESSOR_REVISION': '1e05',
15:12:49 INFO - 'PROGRAMFILES': 'C:\\Program Files',
15:12:49 INFO - 'PROMPT': '$P$G',
15:12:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
15:12:49 INFO - 'PWD': 'C:\\slave\\test',
15:12:49 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
15:12:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
15:12:49 INFO - 'SESSIONNAME': 'Console',
15:12:49 INFO - 'SLAVEDIR': 'C:\\slave\\',
15:12:49 INFO - 'SYSTEMDRIVE': 'C:',
15:12:49 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
15:12:49 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:49 INFO - 'TEST1': 'testie',
15:12:49 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
15:12:49 INFO - 'USERDOMAIN': 'T-XP32-IX-165',
15:12:49 INFO - 'USERNAME': 'cltbld',
15:12:49 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-165',
15:12:49 INFO - 'WINDIR': 'C:\\WINDOWS',
15:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
15:12:49 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'other', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-165', '--webServer', 'localhost'] with output_timeout 3600
15:12:50 INFO - mozversion INFO | application_buildid: 20151102133708
15:12:50 INFO - mozversion INFO | application_changeset: 4a48d855141720d3cf8e5c579db79fba4155b4fa
15:12:50 INFO - mozversion INFO | application_display_name: Nightly
15:12:50 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:12:50 INFO - mozversion INFO | application_name: Firefox
15:12:50 INFO - mozversion INFO | application_remotingname: firefox
15:12:50 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound
15:12:50 INFO - mozversion INFO | application_vendor: Mozilla
15:12:50 INFO - mozversion INFO | application_version: 45.0a1
15:12:50 INFO - mozversion INFO | platform_buildid: 20151102133708
15:12:50 INFO - mozversion INFO | platform_changeset: 4a48d855141720d3cf8e5c579db79fba4155b4fa
15:12:50 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound
15:12:50 INFO - mozversion INFO | platform_version: 45.0a1
15:12:50 INFO - 2015-11-02 15:12:50,178 DEBUG : using testdate: 1446505970
15:12:50 INFO - 2015-11-02 15:12:50,178 DEBUG : actual date: 1446505970
15:12:50 INFO - 2015-11-02 15:12:50,178 INFO : starting webserver on 'localhost:1417'
15:12:50 INFO - 2015-11-02 15:12:50,178 INFO : Starting test suite T-XP32-IX-165
15:12:50 INFO - 2015-11-02 15:12:50,178 INFO : Starting test a11yr
15:12:50 INFO - 2015-11-02 15:12:50,178 DEBUG : operating with platform_type : win_
15:12:50 INFO - 2015-11-02 15:12:50,178 INFO : Initialising browser for a11yr test...
15:12:50 INFO - 2015-11-02 15:12:50,240 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp7trcwk\profile http://localhost:1417/getInfo.html
15:12:53 INFO - 2015-11-02 15:12:53,993 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:12:53 INFO - 2015-11-02 15:12:53,993 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:12:53 INFO - 2015-11-02 15:12:53,993 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
15:12:53 INFO - 2015-11-02 15:12:53,993 DEBUG : BROWSER_OUTPUT: __metrics
15:12:54 INFO - 2015-11-02 15:12:54,023 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: console.error:
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: Stack:
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:12:54 INFO - 2015-11-02 15:12:54,405 DEBUG : BROWSER_OUTPUT:
15:12:54 INFO - 2015-11-02 15:12:54,957 INFO : Browser initialized.
15:12:54 INFO - 2015-11-02 15:12:54,957 INFO : Running cycle 1/1 for a11yr test...
15:12:54 INFO - 2015-11-02 15:12:54,957 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp7trcwk\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\a11y\a11y.manifest.develop -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25
15:13:06 INFO - 2015-11-02 15:13:06,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 142733312
15:13:06 INFO - 2015-11-02 15:13:06,474 DEBUG : BROWSER_OUTPUT:
15:13:07 INFO - 2015-11-02 15:13:07,868 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:08 INFO - 2015-11-02 15:13:08,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 172556288
15:13:08 INFO - 2015-11-02 15:13:08,466 DEBUG : BROWSER_OUTPUT:
15:13:09 INFO - 2015-11-02 15:13:09,967 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:10 INFO - 2015-11-02 15:13:10,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 176259072
15:13:10 INFO - 2015-11-02 15:13:10,609 DEBUG : BROWSER_OUTPUT:
15:13:12 INFO - 2015-11-02 15:13:12,157 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:12 INFO - 2015-11-02 15:13:12,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 174505984
15:13:12 INFO - 2015-11-02 15:13:12,799 DEBUG : BROWSER_OUTPUT:
15:13:14 INFO - 2015-11-02 15:13:14,346 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:14 INFO - 2015-11-02 15:13:14,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 176971776
15:13:14 INFO - 2015-11-02 15:13:14,990 DEBUG : BROWSER_OUTPUT:
15:13:16 INFO - 2015-11-02 15:13:16,568 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:17 INFO - 2015-11-02 15:13:17,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 174796800
15:13:17 INFO - 2015-11-02 15:13:17,210 DEBUG : BROWSER_OUTPUT:
15:13:18 INFO - 2015-11-02 15:13:18,773 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:19 INFO - 2015-11-02 15:13:19,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 176943104
15:13:19 INFO - 2015-11-02 15:13:19,431 DEBUG : BROWSER_OUTPUT:
15:13:20 INFO - 2015-11-02 15:13:20,963 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:21 INFO - 2015-11-02 15:13:21,605 DEBUG : BROWSER_OUTPUT: RSS: Main: 175480832
15:13:21 INFO - 2015-11-02 15:13:21,605 DEBUG : BROWSER_OUTPUT:
15:13:23 INFO - 2015-11-02 15:13:23,138 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:23 INFO - 2015-11-02 15:13:23,780 DEBUG : BROWSER_OUTPUT: RSS: Main: 176640000
15:13:23 INFO - 2015-11-02 15:13:23,780 DEBUG : BROWSER_OUTPUT:
15:13:25 INFO - 2015-11-02 15:13:25,312 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:25 INFO - 2015-11-02 15:13:25,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 176222208
15:13:25 INFO - 2015-11-02 15:13:25,956 DEBUG : BROWSER_OUTPUT:
15:13:27 INFO - 2015-11-02 15:13:27,517 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:28 INFO - 2015-11-02 15:13:28,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 175661056
15:13:28 INFO - 2015-11-02 15:13:28,177 DEBUG : BROWSER_OUTPUT:
15:13:29 INFO - 2015-11-02 15:13:29,707 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:30 INFO - 2015-11-02 15:13:30,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 174600192
15:13:30 INFO - 2015-11-02 15:13:30,351 DEBUG : BROWSER_OUTPUT:
15:13:31 INFO - 2015-11-02 15:13:31,851 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:32 INFO - 2015-11-02 15:13:32,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 176500736
15:13:32 INFO - 2015-11-02 15:13:32,510 DEBUG : BROWSER_OUTPUT:
15:13:34 INFO - 2015-11-02 15:13:34,042 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:34 INFO - 2015-11-02 15:13:34,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 175542272
15:13:34 INFO - 2015-11-02 15:13:34,700 DEBUG : BROWSER_OUTPUT:
15:13:36 INFO - 2015-11-02 15:13:36,109 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:36 INFO - 2015-11-02 15:13:36,783 DEBUG : BROWSER_OUTPUT: RSS: Main: 176611328
15:13:36 INFO - 2015-11-02 15:13:36,783 DEBUG : BROWSER_OUTPUT:
15:13:38 INFO - 2015-11-02 15:13:38,285 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:38 INFO - 2015-11-02 15:13:38,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 176209920
15:13:38 INFO - 2015-11-02 15:13:38,943 DEBUG : BROWSER_OUTPUT:
15:13:40 INFO - 2015-11-02 15:13:40,459 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:41 INFO - 2015-11-02 15:13:41,118 DEBUG : BROWSER_OUTPUT: RSS: Main: 175972352
15:13:41 INFO - 2015-11-02 15:13:41,118 DEBUG : BROWSER_OUTPUT:
15:13:42 INFO - 2015-11-02 15:13:42,680 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:43 INFO - 2015-11-02 15:13:43,338 DEBUG : BROWSER_OUTPUT: RSS: Main: 176558080
15:13:43 INFO - 2015-11-02 15:13:43,338 DEBUG : BROWSER_OUTPUT:
15:13:44 INFO - 2015-11-02 15:13:44,915 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:45 INFO - 2015-11-02 15:13:45,559 DEBUG : BROWSER_OUTPUT: RSS: Main: 176902144
15:13:45 INFO - 2015-11-02 15:13:45,559 DEBUG : BROWSER_OUTPUT:
15:13:47 INFO - 2015-11-02 15:13:47,105 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:47 INFO - 2015-11-02 15:13:47,750 DEBUG : BROWSER_OUTPUT: RSS: Main: 175173632
15:13:47 INFO - 2015-11-02 15:13:47,750 DEBUG : BROWSER_OUTPUT:
15:13:49 INFO - 2015-11-02 15:13:49,326 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:49 INFO - 2015-11-02 15:13:49,986 DEBUG : BROWSER_OUTPUT: RSS: Main: 176939008
15:13:49 INFO - 2015-11-02 15:13:49,986 DEBUG : BROWSER_OUTPUT:
15:13:51 INFO - 2015-11-02 15:13:51,562 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:52 INFO - 2015-11-02 15:13:52,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 175931392
15:13:52 INFO - 2015-11-02 15:13:52,221 DEBUG : BROWSER_OUTPUT:
15:13:53 INFO - 2015-11-02 15:13:53,737 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:54 INFO - 2015-11-02 15:13:54,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 181735424
15:13:54 INFO - 2015-11-02 15:13:54,395 DEBUG : BROWSER_OUTPUT:
15:13:55 INFO - 2015-11-02 15:13:55,944 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:55 INFO - 2015-11-02 15:13:55,959 DEBUG : BROWSER_OUTPUT: 1446506035969 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]
15:13:56 INFO - 2015-11-02 15:13:56,641 DEBUG : BROWSER_OUTPUT: RSS: Main: 182153216
15:13:56 INFO - 2015-11-02 15:13:56,641 DEBUG : BROWSER_OUTPUT:
15:13:58 INFO - 2015-11-02 15:13:58,177 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:13:58 INFO - 2015-11-02 15:13:58,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 181276672
15:13:58 INFO - 2015-11-02 15:13:58,859 DEBUG : BROWSER_OUTPUT:
15:14:00 INFO - 2015-11-02 15:14:00,457 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html)
15:14:01 INFO - 2015-11-02 15:14:01,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 178188288
15:14:01 INFO - 2015-11-02 15:14:01,125 DEBUG : BROWSER_OUTPUT:
15:14:02 INFO - 2015-11-02 15:14:02,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:02 INFO - 2015-11-02 15:14:02,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 178823168
15:14:02 INFO - 2015-11-02 15:14:02,614 DEBUG : BROWSER_OUTPUT:
15:14:03 INFO - 2015-11-02 15:14:03,483 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:04 INFO - 2015-11-02 15:14:04,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 180252672
15:14:04 INFO - 2015-11-02 15:14:04,072 DEBUG : BROWSER_OUTPUT:
15:14:04 INFO - 2015-11-02 15:14:04,940 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:05 INFO - 2015-11-02 15:14:05,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 179027968
15:14:05 INFO - 2015-11-02 15:14:05,515 DEBUG : BROWSER_OUTPUT:
15:14:06 INFO - 2015-11-02 15:14:06,368 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:06 INFO - 2015-11-02 15:14:06,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 174960640
15:14:06 INFO - 2015-11-02 15:14:06,943 DEBUG : BROWSER_OUTPUT:
15:14:07 INFO - 2015-11-02 15:14:07,826 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:08 INFO - 2015-11-02 15:14:08,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 173121536
15:14:08 INFO - 2015-11-02 15:14:08,401 DEBUG : BROWSER_OUTPUT:
15:14:09 INFO - 2015-11-02 15:14:09,269 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:09 INFO - 2015-11-02 15:14:09,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 174473216
15:14:09 INFO - 2015-11-02 15:14:09,858 DEBUG : BROWSER_OUTPUT:
15:14:10 INFO - 2015-11-02 15:14:10,727 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:11 INFO - 2015-11-02 15:14:11,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 174755840
15:14:11 INFO - 2015-11-02 15:14:11,302 DEBUG : BROWSER_OUTPUT:
15:14:12 INFO - 2015-11-02 15:14:12,140 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:12 INFO - 2015-11-02 15:14:12,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 173903872
15:14:12 INFO - 2015-11-02 15:14:12,713 DEBUG : BROWSER_OUTPUT:
15:14:13 INFO - 2015-11-02 15:14:13,598 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:14 INFO - 2015-11-02 15:14:14,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 174575616
15:14:14 INFO - 2015-11-02 15:14:14,171 DEBUG : BROWSER_OUTPUT:
15:14:15 INFO - 2015-11-02 15:14:15,055 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:15 INFO - 2015-11-02 15:14:15,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 175005696
15:14:15 INFO - 2015-11-02 15:14:15,630 DEBUG : BROWSER_OUTPUT:
15:14:16 INFO - 2015-11-02 15:14:16,515 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:17 INFO - 2015-11-02 15:14:17,088 DEBUG : BROWSER_OUTPUT: RSS: Main: 174473216
15:14:17 INFO - 2015-11-02 15:14:17,088 DEBUG : BROWSER_OUTPUT:
15:14:17 INFO - 2015-11-02 15:14:17,957 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:18 INFO - 2015-11-02 15:14:18,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 173797376
15:14:18 INFO - 2015-11-02 15:14:18,530 DEBUG : BROWSER_OUTPUT:
15:14:19 INFO - 2015-11-02 15:14:19,368 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:19 INFO - 2015-11-02 15:14:19,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 174415872
15:14:19 INFO - 2015-11-02 15:14:19,943 DEBUG : BROWSER_OUTPUT:
15:14:20 INFO - 2015-11-02 15:14:20,842 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:21 INFO - 2015-11-02 15:14:21,417 DEBUG : BROWSER_OUTPUT: RSS: Main: 173121536
15:14:21 INFO - 2015-11-02 15:14:21,417 DEBUG : BROWSER_OUTPUT:
15:14:22 INFO - 2015-11-02 15:14:22,286 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:22 INFO - 2015-11-02 15:14:22,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 173019136
15:14:22 INFO - 2015-11-02 15:14:22,875 DEBUG : BROWSER_OUTPUT:
15:14:23 INFO - 2015-11-02 15:14:23,743 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:24 INFO - 2015-11-02 15:14:24,318 DEBUG : BROWSER_OUTPUT: RSS: Main: 173035520
15:14:24 INFO - 2015-11-02 15:14:24,318 DEBUG : BROWSER_OUTPUT:
15:14:25 INFO - 2015-11-02 15:14:25,201 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:25 INFO - 2015-11-02 15:14:25,776 DEBUG : BROWSER_OUTPUT: RSS: Main: 173502464
15:14:25 INFO - 2015-11-02 15:14:25,776 DEBUG : BROWSER_OUTPUT:
15:14:26 INFO - 2015-11-02 15:14:26,660 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:27 INFO - 2015-11-02 15:14:27,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 173195264
15:14:27 INFO - 2015-11-02 15:14:27,233 DEBUG : BROWSER_OUTPUT:
15:14:28 INFO - 2015-11-02 15:14:28,102 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:28 INFO - 2015-11-02 15:14:28,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 172961792
15:14:28 INFO - 2015-11-02 15:14:28,677 DEBUG : BROWSER_OUTPUT:
15:14:29 INFO - 2015-11-02 15:14:29,561 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:30 INFO - 2015-11-02 15:14:30,134 DEBUG : BROWSER_OUTPUT: RSS: Main: 173117440
15:14:30 INFO - 2015-11-02 15:14:30,134 DEBUG : BROWSER_OUTPUT:
15:14:31 INFO - 2015-11-02 15:14:30,989 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:31 INFO - 2015-11-02 15:14:31,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 173060096
15:14:31 INFO - 2015-11-02 15:14:31,562 DEBUG : BROWSER_OUTPUT:
15:14:32 INFO - 2015-11-02 15:14:32,415 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:32 INFO - 2015-11-02 15:14:32,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 173043712
15:14:32 INFO - 2015-11-02 15:14:32,990 DEBUG : BROWSER_OUTPUT:
15:14:33 INFO - 2015-11-02 15:14:33,828 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:34 INFO - 2015-11-02 15:14:34,401 DEBUG : BROWSER_OUTPUT: RSS: Main: 173514752
15:14:34 INFO - 2015-11-02 15:14:34,401 DEBUG : BROWSER_OUTPUT:
15:14:35 INFO - 2015-11-02 15:14:35,286 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:35 INFO - 2015-11-02 15:14:35,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 171958272
15:14:35 INFO - 2015-11-02 15:14:35,859 DEBUG : BROWSER_OUTPUT:
15:14:36 INFO - 2015-11-02 15:14:36,743 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///C:/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///C:/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html)
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 173375488
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT:
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: __start_tp_report
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;1297;1454;1459;1465;1493;1489;1433;1463;1457;1498;1456;1454;1463;1350;1454;1433;1475;1506;1461;1490;1475;1446;1484;1466;1525
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;326;323;321;333;306;326;327;325;335;322;336;323;322;320;323;331;326;323;307;326;336;326;326;324;321
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: __end_tp_report
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: __start_cc_report
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,5869
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: __end_cc_report
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506077004__endTimestamp
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT:
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: Number of tests: 2
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT:
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:1457.84 Median:1463.00 stddev:46.57 (3.2%) stddev-sans-first:33.04
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: Values: 1297.0 1454.0 1459.0 1465.0 1493.0 1489.0 1433.0 1463.0 1457.0 1498.0 1456.0 1454.0 1463.0 1350.0 1454.0 1433.0 1475.0 1506.0 1461.0 1490.0 1475.0 1446.0 1484.0 1466.0 1525.0
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT:
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:324.56 Median:325.00 stddev:7.14 (2.2%) stddev-sans-first:7.29
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: Values: 326.0 323.0 321.0 333.0 306.0 326.0 327.0 325.0 335.0 322.0 336.0 323.0 322.0 320.0 323.0 331.0 326.0 323.0 307.0 326.0 336.0 326.0 326.0 324.0 321.0
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
15:14:36 INFO - 2015-11-02 15:14:36,992 DEBUG : BROWSER_OUTPUT:
15:14:37 INFO - 2015-11-02 15:14:37,612 INFO : Browser exited with error code: 0
15:14:37 INFO - 2015-11-02 15:14:37,674 INFO : Completed test a11yr (00:01:47)
15:14:37 INFO - 2015-11-02 15:14:37,674 INFO : Starting test ts_paint
15:14:37 INFO - 2015-11-02 15:14:37,674 DEBUG : operating with platform_type : win_
15:14:37 INFO - 2015-11-02 15:14:37,674 INFO : Initialising browser for ts_paint test...
15:14:37 INFO - 2015-11-02 15:14:37,690 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/getInfo.html
15:14:39 INFO - 2015-11-02 15:14:39,707 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:14:39 INFO - 2015-11-02 15:14:39,707 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:14:39 INFO - 2015-11-02 15:14:39,707 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
15:14:39 INFO - 2015-11-02 15:14:39,707 DEBUG : BROWSER_OUTPUT: __metrics
15:14:39 INFO - 2015-11-02 15:14:39,737 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: console.error:
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: Stack:
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:14:40 INFO - 2015-11-02 15:14:40,017 DEBUG : BROWSER_OUTPUT:
15:14:40 INFO - 2015-11-02 15:14:40,545 INFO : Browser initialized.
15:14:40 INFO - 2015-11-02 15:14:40,545 INFO : Running cycle 1/20 for ts_paint test...
15:14:40 INFO - 2015-11-02 15:14:40,545 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:42 INFO - 2015-11-02 15:14:42,174 DEBUG : BROWSER_OUTPUT: __start_report1643__end_report
15:14:42 INFO - 2015-11-02 15:14:42,174 DEBUG : BROWSER_OUTPUT:
15:14:42 INFO - 2015-11-02 15:14:42,188 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506082201__endTimestamp
15:14:43 INFO - 2015-11-02 15:14:43,058 INFO : Browser exited with error code: 0
15:14:43 INFO - 2015-11-02 15:14:43,058 INFO : Running cycle 2/20 for ts_paint test...
15:14:43 INFO - 2015-11-02 15:14:43,058 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:44 INFO - 2015-11-02 15:14:44,221 DEBUG : BROWSER_OUTPUT: __start_report1184__end_report
15:14:44 INFO - 2015-11-02 15:14:44,221 DEBUG : BROWSER_OUTPUT:
15:14:44 INFO - 2015-11-02 15:14:44,236 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506084255__endTimestamp
15:14:45 INFO - 2015-11-02 15:14:45,013 INFO : Browser exited with error code: 0
15:14:45 INFO - 2015-11-02 15:14:45,013 INFO : Running cycle 3/20 for ts_paint test...
15:14:45 INFO - 2015-11-02 15:14:45,013 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:46 INFO - 2015-11-02 15:14:46,161 DEBUG : BROWSER_OUTPUT: __start_report1171__end_report
15:14:46 INFO - 2015-11-02 15:14:46,161 DEBUG : BROWSER_OUTPUT:
15:14:46 INFO - 2015-11-02 15:14:46,176 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506086195__endTimestamp
15:14:47 INFO - 2015-11-02 15:14:47,013 INFO : Browser exited with error code: 0
15:14:47 INFO - 2015-11-02 15:14:47,013 INFO : Running cycle 4/20 for ts_paint test...
15:14:47 INFO - 2015-11-02 15:14:47,013 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:48 INFO - 2015-11-02 15:14:48,193 DEBUG : BROWSER_OUTPUT: __start_report1193__end_report
15:14:48 INFO - 2015-11-02 15:14:48,193 DEBUG : BROWSER_OUTPUT:
15:14:48 INFO - 2015-11-02 15:14:48,209 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506088226__endTimestamp
15:14:49 INFO - 2015-11-02 15:14:49,062 INFO : Browser exited with error code: 0
15:14:49 INFO - 2015-11-02 15:14:49,062 INFO : Running cycle 5/20 for ts_paint test...
15:14:49 INFO - 2015-11-02 15:14:49,062 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:50 INFO - 2015-11-02 15:14:50,224 DEBUG : BROWSER_OUTPUT: __start_report1180__end_report
15:14:50 INFO - 2015-11-02 15:14:50,224 DEBUG : BROWSER_OUTPUT:
15:14:50 INFO - 2015-11-02 15:14:50,240 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506090261__endTimestamp
15:14:51 INFO - 2015-11-02 15:14:51,125 INFO : Browser exited with error code: 0
15:14:51 INFO - 2015-11-02 15:14:51,125 INFO : Running cycle 6/20 for ts_paint test...
15:14:51 INFO - 2015-11-02 15:14:51,125 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:52 INFO - 2015-11-02 15:14:52,303 DEBUG : BROWSER_OUTPUT: __start_report1191__end_report
15:14:52 INFO - 2015-11-02 15:14:52,303 DEBUG : BROWSER_OUTPUT:
15:14:52 INFO - 2015-11-02 15:14:52,319 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506092331__endTimestamp
15:14:53 INFO - 2015-11-02 15:14:53,173 INFO : Browser exited with error code: 0
15:14:53 INFO - 2015-11-02 15:14:53,173 INFO : Running cycle 7/20 for ts_paint test...
15:14:53 INFO - 2015-11-02 15:14:53,173 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:54 INFO - 2015-11-02 15:14:54,368 DEBUG : BROWSER_OUTPUT: __start_report1202__end_report
15:14:54 INFO - 2015-11-02 15:14:54,368 DEBUG : BROWSER_OUTPUT:
15:14:54 INFO - 2015-11-02 15:14:54,368 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506094389__endTimestamp
15:14:55 INFO - 2015-11-02 15:14:55,282 INFO : Browser exited with error code: 0
15:14:55 INFO - 2015-11-02 15:14:55,282 INFO : Running cycle 8/20 for ts_paint test...
15:14:55 INFO - 2015-11-02 15:14:55,282 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:56 INFO - 2015-11-02 15:14:56,476 DEBUG : BROWSER_OUTPUT: __start_report1200__end_report
15:14:56 INFO - 2015-11-02 15:14:56,476 DEBUG : BROWSER_OUTPUT:
15:14:56 INFO - 2015-11-02 15:14:56,493 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506096506__endTimestamp
15:14:57 INFO - 2015-11-02 15:14:57,392 INFO : Browser exited with error code: 0
15:14:57 INFO - 2015-11-02 15:14:57,392 INFO : Running cycle 9/20 for ts_paint test...
15:14:57 INFO - 2015-11-02 15:14:57,392 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:14:58 INFO - 2015-11-02 15:14:58,586 DEBUG : BROWSER_OUTPUT: __start_report1208__end_report
15:14:58 INFO - 2015-11-02 15:14:58,586 DEBUG : BROWSER_OUTPUT:
15:14:58 INFO - 2015-11-02 15:14:58,586 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506098606__endTimestamp
15:14:59 INFO - 2015-11-02 15:14:59,502 INFO : Browser exited with error code: 0
15:14:59 INFO - 2015-11-02 15:14:59,502 INFO : Running cycle 10/20 for ts_paint test...
15:14:59 INFO - 2015-11-02 15:14:59,502 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:00 INFO - 2015-11-02 15:15:00,680 DEBUG : BROWSER_OUTPUT: __start_report1205__end_report
15:15:00 INFO - 2015-11-02 15:15:00,680 DEBUG : BROWSER_OUTPUT:
15:15:00 INFO - 2015-11-02 15:15:00,680 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506100699__endTimestamp
15:15:01 INFO - 2015-11-02 15:15:01,596 INFO : Browser exited with error code: 0
15:15:01 INFO - 2015-11-02 15:15:01,596 INFO : Running cycle 11/20 for ts_paint test...
15:15:01 INFO - 2015-11-02 15:15:01,596 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:02 INFO - 2015-11-02 15:15:02,776 DEBUG : BROWSER_OUTPUT: __start_report1180__end_report
15:15:02 INFO - 2015-11-02 15:15:02,776 DEBUG : BROWSER_OUTPUT:
15:15:02 INFO - 2015-11-02 15:15:02,776 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506102792__endTimestamp
15:15:03 INFO - 2015-11-02 15:15:03,660 INFO : Browser exited with error code: 0
15:15:03 INFO - 2015-11-02 15:15:03,660 INFO : Running cycle 12/20 for ts_paint test...
15:15:03 INFO - 2015-11-02 15:15:03,660 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:04 INFO - 2015-11-02 15:15:04,855 DEBUG : BROWSER_OUTPUT: __start_report1218__end_report
15:15:04 INFO - 2015-11-02 15:15:04,855 DEBUG : BROWSER_OUTPUT:
15:15:04 INFO - 2015-11-02 15:15:04,869 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506104877__endTimestamp
15:15:05 INFO - 2015-11-02 15:15:05,815 INFO : Browser exited with error code: 0
15:15:05 INFO - 2015-11-02 15:15:05,815 INFO : Running cycle 13/20 for ts_paint test...
15:15:05 INFO - 2015-11-02 15:15:05,815 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:06 INFO - 2015-11-02 15:15:06,994 DEBUG : BROWSER_OUTPUT: __start_report1200__end_report
15:15:06 INFO - 2015-11-02 15:15:06,994 DEBUG : BROWSER_OUTPUT:
15:15:06 INFO - 2015-11-02 15:15:06,994 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506107012__endTimestamp
15:15:07 INFO - 2015-11-02 15:15:07,926 INFO : Browser exited with error code: 0
15:15:07 INFO - 2015-11-02 15:15:07,926 INFO : Running cycle 14/20 for ts_paint test...
15:15:07 INFO - 2015-11-02 15:15:07,926 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:09 INFO - 2015-11-02 15:15:09,105 DEBUG : BROWSER_OUTPUT: __start_report1196__end_report
15:15:09 INFO - 2015-11-02 15:15:09,105 DEBUG : BROWSER_OUTPUT:
15:15:09 INFO - 2015-11-02 15:15:09,105 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506109120__endTimestamp
15:15:10 INFO - 2015-11-02 15:15:10,020 INFO : Browser exited with error code: 0
15:15:10 INFO - 2015-11-02 15:15:10,020 INFO : Running cycle 15/20 for ts_paint test...
15:15:10 INFO - 2015-11-02 15:15:10,020 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:11 INFO - 2015-11-02 15:15:11,200 DEBUG : BROWSER_OUTPUT: __start_report1188__end_report
15:15:11 INFO - 2015-11-02 15:15:11,200 DEBUG : BROWSER_OUTPUT:
15:15:11 INFO - 2015-11-02 15:15:11,214 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506111226__endTimestamp
15:15:12 INFO - 2015-11-02 15:15:12,130 INFO : Browser exited with error code: 0
15:15:12 INFO - 2015-11-02 15:15:12,130 INFO : Running cycle 16/20 for ts_paint test...
15:15:12 INFO - 2015-11-02 15:15:12,130 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:13 INFO - 2015-11-02 15:15:13,355 DEBUG : BROWSER_OUTPUT: __start_report1230__end_report
15:15:13 INFO - 2015-11-02 15:15:13,355 DEBUG : BROWSER_OUTPUT:
15:15:13 INFO - 2015-11-02 15:15:13,355 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506113370__endTimestamp
15:15:14 INFO - 2015-11-02 15:15:14,332 INFO : Browser exited with error code: 0
15:15:14 INFO - 2015-11-02 15:15:14,332 INFO : Running cycle 17/20 for ts_paint test...
15:15:14 INFO - 2015-11-02 15:15:14,332 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:15 INFO - 2015-11-02 15:15:15,512 DEBUG : BROWSER_OUTPUT: __start_report1203__end_report
15:15:15 INFO - 2015-11-02 15:15:15,512 DEBUG : BROWSER_OUTPUT:
15:15:15 INFO - 2015-11-02 15:15:15,528 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506115539__endTimestamp
15:15:16 INFO - 2015-11-02 15:15:16,443 INFO : Browser exited with error code: 0
15:15:16 INFO - 2015-11-02 15:15:16,443 INFO : Running cycle 18/20 for ts_paint test...
15:15:16 INFO - 2015-11-02 15:15:16,443 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:17 INFO - 2015-11-02 15:15:17,607 DEBUG : BROWSER_OUTPUT: __start_report1187__end_report
15:15:17 INFO - 2015-11-02 15:15:17,607 DEBUG : BROWSER_OUTPUT:
15:15:17 INFO - 2015-11-02 15:15:17,621 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506117637__endTimestamp
15:15:18 INFO - 2015-11-02 15:15:18,552 INFO : Browser exited with error code: 0
15:15:18 INFO - 2015-11-02 15:15:18,552 INFO : Running cycle 19/20 for ts_paint test...
15:15:18 INFO - 2015-11-02 15:15:18,552 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:19 INFO - 2015-11-02 15:15:19,700 DEBUG : BROWSER_OUTPUT: __start_report1172__end_report
15:15:19 INFO - 2015-11-02 15:15:19,700 DEBUG : BROWSER_OUTPUT:
15:15:19 INFO - 2015-11-02 15:15:19,717 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506119734__endTimestamp
15:15:20 INFO - 2015-11-02 15:15:20,709 INFO : Browser exited with error code: 0
15:15:20 INFO - 2015-11-02 15:15:20,709 INFO : Running cycle 20/20 for ts_paint test...
15:15:20 INFO - 2015-11-02 15:15:20,709 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpobvwxt\profile http://localhost:1417/startup_test/tspaint_test.html
15:15:21 INFO - 2015-11-02 15:15:21,872 DEBUG : BROWSER_OUTPUT: __start_report1192__end_report
15:15:21 INFO - 2015-11-02 15:15:21,872 DEBUG : BROWSER_OUTPUT:
15:15:21 INFO - 2015-11-02 15:15:21,888 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506121903__endTimestamp
15:15:22 INFO - 2015-11-02 15:15:22,865 INFO : Browser exited with error code: 0
15:15:22 INFO - 2015-11-02 15:15:22,927 INFO : Completed test ts_paint (00:00:45)
15:15:22 INFO - 2015-11-02 15:15:22,927 INFO : Starting test tpaint
15:15:22 INFO - 2015-11-02 15:15:22,927 DEBUG : operating with platform_type : win_
15:15:22 INFO - 2015-11-02 15:15:22,927 INFO : Initialising browser for tpaint test...
15:15:22 INFO - 2015-11-02 15:15:22,959 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz0oexc\profile http://localhost:1417/getInfo.html
15:15:24 INFO - 2015-11-02 15:15:24,867 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:15:24 INFO - 2015-11-02 15:15:24,867 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:15:24 INFO - 2015-11-02 15:15:24,867 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
15:15:24 INFO - 2015-11-02 15:15:24,867 DEBUG : BROWSER_OUTPUT: __metrics
15:15:24 INFO - 2015-11-02 15:15:24,898 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: console.error:
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: Stack:
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
15:15:25 INFO - 2015-11-02 15:15:25,207 DEBUG : BROWSER_OUTPUT:
15:15:25 INFO - 2015-11-02 15:15:25,736 INFO : Browser initialized.
15:15:25 INFO - 2015-11-02 15:15:25,736 INFO : Running cycle 1/1 for tpaint test...
15:15:25 INFO - 2015-11-02 15:15:25,736 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz0oexc\profile file://C:\slave\test\build\tests\talos\talos/startup_test/tpaint.html?auto=1
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: __start_report233.35999999999876|236.51|237.76500000000033|237.76999999999998|237.9300000000003|238.51500000000033|241.6299999999992|242.79999999999927|244.4300000000003|247.27000000000044|248.11499999999978|249.28000000000065|249.84499999999935|253.29000000000087|256.15499999999975|258.5600000000013|273.4399999999987|280.65000000000146|288.1100000000006|290.59500000000116__end_report__startTimestamp1446506152230__endTimestamp
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: openingTimes=236.51,237.76500000000033,237.76999999999998,237.9300000000003,238.51500000000033,241.6299999999992,242.79999999999927,244.4300000000003,247.27000000000044,248.11499999999978,249.28000000000065,249.84499999999935,253.29000000000087,256.15499999999975,258.5600000000013,273.4399999999987,280.65000000000146,288.1100000000006,290.59500000000116
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: avgOpenTime:252.30
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: minOpenTime:233.36
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: maxOpenTime:290.60
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: medOpenTime:247.6925000000001
15:15:52 INFO - 2015-11-02 15:15:52,217 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:247.6925000000001
15:15:53 INFO - 2015-11-02 15:15:53,055 INFO : Browser exited with error code: 0
15:15:53 INFO - 2015-11-02 15:15:53,101 INFO : Completed test tpaint (00:00:30)
15:15:53 INFO - 2015-11-02 15:15:53,101 INFO : Starting test sessionrestore
15:15:53 INFO - 2015-11-02 15:15:53,101 DEBUG : operating with platform_type : win_
15:15:53 INFO - 2015-11-02 15:15:53,101 INFO : Initialising browser for sessionrestore test...
15:15:53 INFO - 2015-11-02 15:15:53,132 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/getInfo.html
15:15:58 INFO - 2015-11-02 15:15:58,144 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:15:58 INFO - 2015-11-02 15:15:58,144 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:15:58 INFO - 2015-11-02 15:15:58,144 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
15:15:58 INFO - 2015-11-02 15:15:58,144 DEBUG : BROWSER_OUTPUT: __metrics
15:15:58 INFO - 2015-11-02 15:15:58,220 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:15:59 INFO - 2015-11-02 15:15:59,819 INFO : Browser initialized.
15:15:59 INFO - 2015-11-02 15:15:59,819 INFO : Running cycle 1/10 for sessionrestore test...
15:15:59 INFO - 2015-11-02 15:15:59,819 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:15:59 INFO - 2015-11-02 15:15:59,819 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:15:59 INFO - 2015-11-02 15:15:59,819 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:04 INFO - 2015-11-02 15:16:04,596 DEBUG : BROWSER_OUTPUT: __start_report2513__end_report
15:16:04 INFO - 2015-11-02 15:16:04,596 DEBUG : BROWSER_OUTPUT:
15:16:04 INFO - 2015-11-02 15:16:04,596 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506164606__endTimestamp
15:16:04 INFO - 2015-11-02 15:16:04,596 DEBUG : BROWSER_OUTPUT:
15:16:06 INFO - 2015-11-02 15:16:06,256 INFO : Browser exited with error code: 0
15:16:06 INFO - 2015-11-02 15:16:06,256 INFO : Running cycle 2/10 for sessionrestore test...
15:16:06 INFO - 2015-11-02 15:16:06,256 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:06 INFO - 2015-11-02 15:16:06,256 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:06 INFO - 2015-11-02 15:16:06,256 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:10 INFO - 2015-11-02 15:16:10,446 DEBUG : BROWSER_OUTPUT: __start_report2226__end_report
15:16:10 INFO - 2015-11-02 15:16:10,446 DEBUG : BROWSER_OUTPUT:
15:16:10 INFO - 2015-11-02 15:16:10,446 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506170466__endTimestamp
15:16:10 INFO - 2015-11-02 15:16:10,446 DEBUG : BROWSER_OUTPUT:
15:16:12 INFO - 2015-11-02 15:16:12,089 INFO : Browser exited with error code: 0
15:16:12 INFO - 2015-11-02 15:16:12,089 INFO : Running cycle 3/10 for sessionrestore test...
15:16:12 INFO - 2015-11-02 15:16:12,089 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:12 INFO - 2015-11-02 15:16:12,089 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:12 INFO - 2015-11-02 15:16:12,089 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:16 INFO - 2015-11-02 15:16:16,293 DEBUG : BROWSER_OUTPUT: __start_report2238__end_report
15:16:16 INFO - 2015-11-02 15:16:16,293 DEBUG : BROWSER_OUTPUT:
15:16:16 INFO - 2015-11-02 15:16:16,293 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506176308__endTimestamp
15:16:16 INFO - 2015-11-02 15:16:16,293 DEBUG : BROWSER_OUTPUT:
15:16:17 INFO - 2015-11-02 15:16:17,923 INFO : Browser exited with error code: 0
15:16:17 INFO - 2015-11-02 15:16:17,923 INFO : Running cycle 4/10 for sessionrestore test...
15:16:17 INFO - 2015-11-02 15:16:17,923 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:17 INFO - 2015-11-02 15:16:17,923 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:17 INFO - 2015-11-02 15:16:17,923 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:22 INFO - 2015-11-02 15:16:22,127 DEBUG : BROWSER_OUTPUT: __start_report2234__end_report
15:16:22 INFO - 2015-11-02 15:16:22,127 DEBUG : BROWSER_OUTPUT:
15:16:22 INFO - 2015-11-02 15:16:22,127 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506182153__endTimestamp
15:16:22 INFO - 2015-11-02 15:16:22,127 DEBUG : BROWSER_OUTPUT:
15:16:23 INFO - 2015-11-02 15:16:23,818 INFO : Browser exited with error code: 0
15:16:23 INFO - 2015-11-02 15:16:23,818 INFO : Running cycle 5/10 for sessionrestore test...
15:16:23 INFO - 2015-11-02 15:16:23,818 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:23 INFO - 2015-11-02 15:16:23,818 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:23 INFO - 2015-11-02 15:16:23,818 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:28 INFO - 2015-11-02 15:16:28,053 DEBUG : BROWSER_OUTPUT: __start_report2274__end_report
15:16:28 INFO - 2015-11-02 15:16:28,053 DEBUG : BROWSER_OUTPUT:
15:16:28 INFO - 2015-11-02 15:16:28,053 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506188074__endTimestamp
15:16:28 INFO - 2015-11-02 15:16:28,053 DEBUG : BROWSER_OUTPUT:
15:16:29 INFO - 2015-11-02 15:16:29,852 INFO : Browser exited with error code: 0
15:16:29 INFO - 2015-11-02 15:16:29,852 INFO : Running cycle 6/10 for sessionrestore test...
15:16:29 INFO - 2015-11-02 15:16:29,852 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:29 INFO - 2015-11-02 15:16:29,852 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:29 INFO - 2015-11-02 15:16:29,852 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:34 INFO - 2015-11-02 15:16:34,026 DEBUG : BROWSER_OUTPUT: __start_report2242__end_report
15:16:34 INFO - 2015-11-02 15:16:34,026 DEBUG : BROWSER_OUTPUT:
15:16:34 INFO - 2015-11-02 15:16:34,026 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506194042__endTimestamp
15:16:34 INFO - 2015-11-02 15:16:34,026 DEBUG : BROWSER_OUTPUT:
15:16:35 INFO - 2015-11-02 15:16:35,779 INFO : Browser exited with error code: 0
15:16:35 INFO - 2015-11-02 15:16:35,779 INFO : Running cycle 7/10 for sessionrestore test...
15:16:35 INFO - 2015-11-02 15:16:35,779 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:35 INFO - 2015-11-02 15:16:35,779 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:35 INFO - 2015-11-02 15:16:35,779 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:39 INFO - 2015-11-02 15:16:39,967 DEBUG : BROWSER_OUTPUT: __start_report2233__end_report
15:16:39 INFO - 2015-11-02 15:16:39,967 DEBUG : BROWSER_OUTPUT:
15:16:39 INFO - 2015-11-02 15:16:39,967 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506199996__endTimestamp
15:16:39 INFO - 2015-11-02 15:16:39,967 DEBUG : BROWSER_OUTPUT:
15:16:41 INFO - 2015-11-02 15:16:41,704 INFO : Browser exited with error code: 0
15:16:41 INFO - 2015-11-02 15:16:41,704 INFO : Running cycle 8/10 for sessionrestore test...
15:16:41 INFO - 2015-11-02 15:16:41,704 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:41 INFO - 2015-11-02 15:16:41,704 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:41 INFO - 2015-11-02 15:16:41,704 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:45 INFO - 2015-11-02 15:16:45,910 DEBUG : BROWSER_OUTPUT: __start_report2244__end_report
15:16:45 INFO - 2015-11-02 15:16:45,910 DEBUG : BROWSER_OUTPUT:
15:16:45 INFO - 2015-11-02 15:16:45,910 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506205924__endTimestamp
15:16:45 INFO - 2015-11-02 15:16:45,910 DEBUG : BROWSER_OUTPUT:
15:16:47 INFO - 2015-11-02 15:16:47,647 INFO : Browser exited with error code: 0
15:16:47 INFO - 2015-11-02 15:16:47,647 INFO : Running cycle 9/10 for sessionrestore test...
15:16:47 INFO - 2015-11-02 15:16:47,647 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:47 INFO - 2015-11-02 15:16:47,647 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:47 INFO - 2015-11-02 15:16:47,663 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:51 INFO - 2015-11-02 15:16:51,944 DEBUG : BROWSER_OUTPUT: __start_report2290__end_report
15:16:51 INFO - 2015-11-02 15:16:51,944 DEBUG : BROWSER_OUTPUT:
15:16:51 INFO - 2015-11-02 15:16:51,944 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506211947__endTimestamp
15:16:51 INFO - 2015-11-02 15:16:51,944 DEBUG : BROWSER_OUTPUT:
15:16:53 INFO - 2015-11-02 15:16:53,743 INFO : Browser exited with error code: 0
15:16:53 INFO - 2015-11-02 15:16:53,743 INFO : Running cycle 10/10 for sessionrestore test...
15:16:53 INFO - 2015-11-02 15:16:53,743 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionstore.js
15:16:53 INFO - 2015-11-02 15:16:53,743 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile\sessionCheckpoints.json
15:16:53 INFO - 2015-11-02 15:16:53,743 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpz_snzo\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:16:57 INFO - 2015-11-02 15:16:57,933 DEBUG : BROWSER_OUTPUT: __start_report2227__end_report
15:16:57 INFO - 2015-11-02 15:16:57,933 DEBUG : BROWSER_OUTPUT:
15:16:57 INFO - 2015-11-02 15:16:57,933 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506217958__endTimestamp
15:16:57 INFO - 2015-11-02 15:16:57,933 DEBUG : BROWSER_OUTPUT:
15:16:59 INFO - 2015-11-02 15:16:59,732 INFO : Browser exited with error code: 0
15:16:59 INFO - 2015-11-02 15:16:59,809 INFO : Completed test sessionrestore (00:01:06)
15:16:59 INFO - 2015-11-02 15:16:59,809 INFO : Starting test sessionrestore_no_auto_restore
15:16:59 INFO - 2015-11-02 15:16:59,809 DEBUG : operating with platform_type : win_
15:16:59 INFO - 2015-11-02 15:16:59,809 INFO : Initialising browser for sessionrestore_no_auto_restore test...
15:16:59 INFO - 2015-11-02 15:16:59,825 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/getInfo.html
15:17:01 INFO - 2015-11-02 15:17:01,825 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
15:17:01 INFO - 2015-11-02 15:17:01,825 DEBUG : BROWSER_OUTPUT: colorDepth:24
15:17:01 INFO - 2015-11-02 15:17:01,825 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
15:17:01 INFO - 2015-11-02 15:17:01,825 DEBUG : BROWSER_OUTPUT: __metrics
15:17:01 INFO - 2015-11-02 15:17:01,842 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
15:17:02 INFO - 2015-11-02 15:17:02,680 INFO : Browser initialized.
15:17:02 INFO - 2015-11-02 15:17:02,680 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test...
15:17:02 INFO - 2015-11-02 15:17:02,680 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:02 INFO - 2015-11-02 15:17:02,680 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:02 INFO - 2015-11-02 15:17:02,680 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:04 INFO - 2015-11-02 15:17:04,323 DEBUG : BROWSER_OUTPUT: __start_report1077__end_report
15:17:04 INFO - 2015-11-02 15:17:04,323 DEBUG : BROWSER_OUTPUT:
15:17:04 INFO - 2015-11-02 15:17:04,323 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506224342__endTimestamp
15:17:04 INFO - 2015-11-02 15:17:04,323 DEBUG : BROWSER_OUTPUT:
15:17:05 INFO - 2015-11-02 15:17:05,084 INFO : Browser exited with error code: 0
15:17:05 INFO - 2015-11-02 15:17:05,084 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test...
15:17:05 INFO - 2015-11-02 15:17:05,084 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:05 INFO - 2015-11-02 15:17:05,084 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:05 INFO - 2015-11-02 15:17:05,084 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:06 INFO - 2015-11-02 15:17:06,403 DEBUG : BROWSER_OUTPUT: __start_report875__end_report
15:17:06 INFO - 2015-11-02 15:17:06,403 DEBUG : BROWSER_OUTPUT:
15:17:06 INFO - 2015-11-02 15:17:06,403 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506226413__endTimestamp
15:17:06 INFO - 2015-11-02 15:17:06,403 DEBUG : BROWSER_OUTPUT:
15:17:07 INFO - 2015-11-02 15:17:07,148 INFO : Browser exited with error code: 0
15:17:07 INFO - 2015-11-02 15:17:07,148 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test...
15:17:07 INFO - 2015-11-02 15:17:07,148 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:07 INFO - 2015-11-02 15:17:07,148 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:07 INFO - 2015-11-02 15:17:07,148 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:08 INFO - 2015-11-02 15:17:08,450 DEBUG : BROWSER_OUTPUT: __start_report860__end_report
15:17:08 INFO - 2015-11-02 15:17:08,450 DEBUG : BROWSER_OUTPUT:
15:17:08 INFO - 2015-11-02 15:17:08,450 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506228463__endTimestamp
15:17:08 INFO - 2015-11-02 15:17:08,450 DEBUG : BROWSER_OUTPUT:
15:17:09 INFO - 2015-11-02 15:17:09,303 INFO : Browser exited with error code: 0
15:17:09 INFO - 2015-11-02 15:17:09,303 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test...
15:17:09 INFO - 2015-11-02 15:17:09,303 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:09 INFO - 2015-11-02 15:17:09,303 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:09 INFO - 2015-11-02 15:17:09,303 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:10 INFO - 2015-11-02 15:17:10,607 DEBUG : BROWSER_OUTPUT: __start_report866__end_report
15:17:10 INFO - 2015-11-02 15:17:10,607 DEBUG : BROWSER_OUTPUT:
15:17:10 INFO - 2015-11-02 15:17:10,607 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506230623__endTimestamp
15:17:10 INFO - 2015-11-02 15:17:10,607 DEBUG : BROWSER_OUTPUT:
15:17:11 INFO - 2015-11-02 15:17:11,460 INFO : Browser exited with error code: 0
15:17:11 INFO - 2015-11-02 15:17:11,460 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test...
15:17:11 INFO - 2015-11-02 15:17:11,460 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:11 INFO - 2015-11-02 15:17:11,460 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:11 INFO - 2015-11-02 15:17:11,460 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:12 INFO - 2015-11-02 15:17:12,779 DEBUG : BROWSER_OUTPUT: __start_report885__end_report
15:17:12 INFO - 2015-11-02 15:17:12,779 DEBUG : BROWSER_OUTPUT:
15:17:12 INFO - 2015-11-02 15:17:12,779 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506232795__endTimestamp
15:17:12 INFO - 2015-11-02 15:17:12,779 DEBUG : BROWSER_OUTPUT:
15:17:13 INFO - 2015-11-02 15:17:13,663 INFO : Browser exited with error code: 0
15:17:13 INFO - 2015-11-02 15:17:13,663 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test...
15:17:13 INFO - 2015-11-02 15:17:13,663 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:13 INFO - 2015-11-02 15:17:13,663 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:13 INFO - 2015-11-02 15:17:13,678 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:14 INFO - 2015-11-02 15:17:14,996 DEBUG : BROWSER_OUTPUT: __start_report900__end_report
15:17:14 INFO - 2015-11-02 15:17:14,996 DEBUG : BROWSER_OUTPUT:
15:17:14 INFO - 2015-11-02 15:17:14,996 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506235014__endTimestamp
15:17:14 INFO - 2015-11-02 15:17:14,996 DEBUG : BROWSER_OUTPUT:
15:17:15 INFO - 2015-11-02 15:17:15,927 INFO : Browser exited with error code: 0
15:17:15 INFO - 2015-11-02 15:17:15,927 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test...
15:17:15 INFO - 2015-11-02 15:17:15,927 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:15 INFO - 2015-11-02 15:17:15,927 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:15 INFO - 2015-11-02 15:17:15,927 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:17 INFO - 2015-11-02 15:17:17,230 DEBUG : BROWSER_OUTPUT: __start_report874__end_report
15:17:17 INFO - 2015-11-02 15:17:17,230 DEBUG : BROWSER_OUTPUT:
15:17:17 INFO - 2015-11-02 15:17:17,230 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506237245__endTimestamp
15:17:17 INFO - 2015-11-02 15:17:17,230 DEBUG : BROWSER_OUTPUT:
15:17:18 INFO - 2015-11-02 15:17:18,131 INFO : Browser exited with error code: 0
15:17:18 INFO - 2015-11-02 15:17:18,131 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test...
15:17:18 INFO - 2015-11-02 15:17:18,131 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:18 INFO - 2015-11-02 15:17:18,147 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:18 INFO - 2015-11-02 15:17:18,147 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:19 INFO - 2015-11-02 15:17:19,434 DEBUG : BROWSER_OUTPUT: __start_report868__end_report
15:17:19 INFO - 2015-11-02 15:17:19,434 DEBUG : BROWSER_OUTPUT:
15:17:19 INFO - 2015-11-02 15:17:19,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506239443__endTimestamp
15:17:19 INFO - 2015-11-02 15:17:19,434 DEBUG : BROWSER_OUTPUT:
15:17:20 INFO - 2015-11-02 15:17:20,349 INFO : Browser exited with error code: 0
15:17:20 INFO - 2015-11-02 15:17:20,349 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test...
15:17:20 INFO - 2015-11-02 15:17:20,349 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:20 INFO - 2015-11-02 15:17:20,349 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:20 INFO - 2015-11-02 15:17:20,349 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:21 INFO - 2015-11-02 15:17:21,653 DEBUG : BROWSER_OUTPUT: __start_report862__end_report
15:17:21 INFO - 2015-11-02 15:17:21,653 DEBUG : BROWSER_OUTPUT:
15:17:21 INFO - 2015-11-02 15:17:21,653 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506241661__endTimestamp
15:17:21 INFO - 2015-11-02 15:17:21,653 DEBUG : BROWSER_OUTPUT:
15:17:22 INFO - 2015-11-02 15:17:22,552 INFO : Browser exited with error code: 0
15:17:22 INFO - 2015-11-02 15:17:22,552 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test...
15:17:22 INFO - 2015-11-02 15:17:22,552 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionstore.js
15:17:22 INFO - 2015-11-02 15:17:22,552 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile\sessionCheckpoints.json
15:17:22 INFO - 2015-11-02 15:17:22,552 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp0gmopz\profile http://localhost:1417/startup_test/sessionrestore/index.html
15:17:23 INFO - 2015-11-02 15:17:23,887 DEBUG : BROWSER_OUTPUT: __start_report897__end_report
15:17:23 INFO - 2015-11-02 15:17:23,887 DEBUG : BROWSER_OUTPUT:
15:17:23 INFO - 2015-11-02 15:17:23,887 DEBUG : BROWSER_OUTPUT: __startTimestamp1446506243902__endTimestamp
15:17:23 INFO - 2015-11-02 15:17:23,887 DEBUG : BROWSER_OUTPUT:
15:17:24 INFO - 2015-11-02 15:17:24,770 INFO : Browser exited with error code: 0
15:17:24 INFO - 2015-11-02 15:17:24,832 INFO : Completed test sessionrestore_no_auto_restore (00:00:25)
15:17:25 INFO - 2015-11-02 15:17:25,252 INFO : Completed test suite (00:04:35)
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Working with test: a11yr
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Generating results file: a11yr
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Working with test: ts_paint
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Generating results file: ts_paint
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Working with test: tpaint
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Generating results file: tpaint
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Working with test: sessionrestore
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Generating results file: sessionrestore
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Working with test: sessionrestore_no_auto_restore
15:17:25 INFO - 2015-11-02 15:17:25,252 DEBUG : Generating results file: sessionrestore_no_auto_restore
15:17:25 INFO - 2015-11-02 15:17:25,252 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:17:25 INFO - 2015-11-02 15:17:25,298 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,298 DEBUG : process_Request line: a11yr_paint 689.31 graph.html#tests=[[223,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,298 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:17:25 INFO - 2015-11-02 15:17:25,359 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,359 DEBUG : process_Request line: ts_paint 1213.84 graph.html#tests=[[83,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,359 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:17:25 INFO - 2015-11-02 15:17:25,407 DEBUG : process_Request line: tpaint graph.html#tests=[[82,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,407 DEBUG : process_Request line: tpaint 249.84 graph.html#tests=[[82,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,407 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:17:25 INFO - 2015-11-02 15:17:25,453 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,453 DEBUG : process_Request line: sessionrestore 2270.68 graph.html#tests=[[313,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,453 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
15:17:25 INFO - 2015-11-02 15:17:25,500 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,500 DEBUG : process_Request line: sessionrestore_no_auto_restore 894.49 graph.html#tests=[[315,131,37]]
15:17:25 INFO - 2015-11-02 15:17:25,500 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"dhtml.html": [1297.0, 1454.0, 1459.0, 1465.0, 1493.0, 1489.0, 1433.0, 1463.0, 1457.0, 1498.0, 1456.0, 1454.0, 1463.0, 1350.0, 1454.0, 1433.0, 1475.0, 1506.0, 1461.0, 1490.0, 1475.0, 1446.0, 1484.0, 1466.0, 1525.0], "tablemutation.html": [326.0, 323.0, 321.0, 333.0, 306.0, 326.0, 327.0, 325.0, 335.0, 322.0, 336.0, 323.0, 322.0, 320.0, 323.0, 331.0, 326.0, 323.0, 307.0, 326.0, 336.0, 326.0, 326.0, 324.0, 321.0]}, "summary": {"suite": 689.3129724987064, "subtests": {"dhtml.html": {"filtered": 1463.0, "value": 1463.0}, "tablemutation.html": {"filtered": 324.5, "value": 324.5}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "T-XP32-IX-165"}, "testrun": {"date": 1446505970, "suite": "a11yr", "options": {"responsiveness": false, "tpmozafterpaint": true, "tpchrome": true, "tppagecycles": 25, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102133708", "branch": "Mozilla-Inbound-Non-PGO", "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"}}, {"talos_counters": {}, "results": {"ts_paint": [1643.0, 1184.0, 1171.0, 1193.0, 1180.0, 1191.0, 1202.0, 1200.0, 1208.0, 1205.0, 1180.0, 1218.0, 1200.0, 1196.0, 1188.0, 1230.0, 1203.0, 1187.0, 1172.0, 1192.0]}, "summary": {"suite": 1193.0, "subtests": {"ts_paint": {"filtered": 1193.0, "value": 1193.0}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "T-XP32-IX-165"}, "testrun": {"date": 1446505970, "suite": "ts_paint", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102133708", "branch": "Mozilla-Inbound-Non-PGO", "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"}}, {"talos_counters": {}, "results": {"tpaint": [233.35999999999876, 236.51, 237.76500000000033, 237.76999999999998, 237.9300000000003, 238.51500000000033, 241.6299999999992, 242.79999999999927, 244.4300000000003, 247.27000000000044, 248.11499999999978, 249.28000000000065, 249.84499999999935, 253.29000000000087, 256.15499999999975, 258.5600000000013, 273.4399999999987, 280.65000000000146, 288.1100000000006, 290.59500000000116]}, "summary": {"suite": 249.84499999999935, "subtests": {"tpaint": {"filtered": 249.84499999999935, "value": 249.84499999999935}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "T-XP32-IX-165"}, "testrun": {"date": 1446505970, "suite": "tpaint", "options": {"responsiveness": false, "cycles": 1, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102133708", "branch": "Mozilla-Inbound-Non-PGO", "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"}}, {"talos_counters": {}, "results": {"sessionrestore": [2513.0, 2226.0, 2238.0, 2234.0, 2274.0, 2242.0, 2233.0, 2244.0, 2290.0, 2227.0]}, "summary": {"suite": 2238.0, "subtests": {"sessionrestore": {"filtered": 2238.0, "value": 2238.0}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "T-XP32-IX-165"}, "testrun": {"date": 1446505970, "suite": "sessionrestore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102133708", "branch": "Mozilla-Inbound-Non-PGO", "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"}}, {"talos_counters": {}, "results": {"sessionrestore_no_auto_restore": [1077.0, 875.0, 860.0, 866.0, 885.0, 900.0, 874.0, 868.0, 862.0, 897.0]}, "summary": {"suite": 874.0, "subtests": {"sessionrestore_no_auto_restore": {"filtered": 874.0, "value": 874.0}}}, "test_machine": {"platform": "x86", "osversion": "5.1.2600", "os": "win", "name": "T-XP32-IX-165"}, "testrun": {"date": 1446505970, "suite": "sessionrestore_no_auto_restore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102133708", "branch": "Mozilla-Inbound-Non-PGO", "revision": "4a48d855141720d3cf8e5c579db79fba4155b4fa"}}]
15:17:25 INFO - RETURN: a11yr_paint: 689.31
15:17:25 INFO - RETURN: ts_paint: 1213.84
15:17:25 INFO - RETURN: tpaint: 249.84
15:17:25 INFO - RETURN: sessionrestore: 2270.68
15:17:25 INFO - RETURN: sessionrestore_no_auto_restore: 894.49
15:17:25 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,131,37]]", "result": "2270.68"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,131,37]]", "result": "689.31"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,131,37]]", "result": "249.84"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,131,37]]", "result": "1213.84"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,131,37]]", "result": "894.49"}}}
15:17:25 INFO - Return code: 0
15:17:25 INFO - # TBPL SUCCESS #
15:17:25 INFO - Running post-action listener: _resource_record_post_action
15:17:25 INFO - Running post-run listener: _resource_record_post_run
15:17:26 INFO - Total resource usage - Wall time: 281s; CPU: 11.0%; Read bytes: 47937024; Write bytes: 276763136; Read time: 342802; Write time: 355356
15:17:26 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 54522368; Read time: 0; Write time: 55264
15:17:26 INFO - run-tests - Wall time: 279s; CPU: 11.0%; Read bytes: 47937024; Write bytes: 189861888; Read time: 342802; Write time: 269192
15:17:26 INFO - Running post-run listener: _upload_blobber_files
15:17:26 INFO - Blob upload gear active.
15:17:26 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
15:17:26 INFO - Copying logs to upload dir...
15:17:26 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=385.070000
========= master_lag: 5.16 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 6 mins, 30 secs) (at 2015-11-02 15:17:24.897255) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:24.903480) =========
'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:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test\properties
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.108000
build_url: 'https://queue.taskcluster.net/v1/task/dGthH-uvS1yW1UZVpirVeQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip'
========= master_lag: 0.01 =========
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:25.020285) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:25.020606) =========
'rm' '-f' 'oauth.txt'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-165\Application Data
CLIENTNAME=Console
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-165
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=\Documents and Settings\cltbld.T-XP32-IX-165
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-165
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;.PSC1
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PWD=C:\slave\test
RUNLOGFILE=C:\slave\\runslave.log
SESSIONNAME=Console
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\WINDOWS
TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
TEST1=testie
TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp
USERDOMAIN=T-XP32-IX-165
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-165
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.977000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:26.018005) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:26.018327) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-02 15:17:26.569021) =========
========= Total master_lag: 6.75 =========