builder: fx-team_xp-ix_test-other
slave: t-xp32-ix-142
starttime: 1447871551.34
results: success (0)
buildid: 20151118091623
builduid: 28474222b74b4731a603f3978a06f218
revision: 1d6ba006a526d17294369eed770a9a0ce2794bde
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.337074) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.337516) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.337814) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test
program finished with exit code 0
elapsedTime=0.232000
basedir: 'C:\\slave\\test'
========= master_lag: 0.06 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.629749) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.630023) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.690395) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.690788) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.216000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.916348) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.916651) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.917016) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:31.917285) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
--10:32:35-- 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% 7.51 MB/s
10:32:35 (7.51 MB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.602000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:32.539076) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 10:32:32.539394) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=1.946000
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 10:32:34.525762) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 10:32:34.526108) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 1d6ba006a526d17294369eed770a9a0ce2794bde --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1d6ba006a526d17294369eed770a9a0ce2794bde --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-11-18 10:32:38,279 truncating revision to first 12 chars
2015-11-18 10:32:38,279 Setting DEBUG logging.
2015-11-18 10:32:38,279 attempt 1/10
2015-11-18 10:32:38,279 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1d6ba006a526?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-11-18 10:32:38,618 unpacking tar archive at: fx-team-1d6ba006a526/testing/mozharness/
program finished with exit code 0
elapsedTime=1.113000
========= master_lag: 0.09 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-18 10:32:35.727765) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:35.728085) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:35.740987) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:35.741271) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 10:32:35.741651) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 6 mins, 12 secs) (at 2015-11-18 10:32:35.741945) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'other' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'other', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
10:32:39 INFO - MultiFileLogger online at 20151118 10:32:39 in C:\slave\test
10:32:39 INFO - Run as scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO
10:32:39 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
10:32:39 INFO - {'append_to_log': False,
10:32:39 INFO - 'base_work_dir': 'C:\\slave\\test',
10:32:39 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
10:32:39 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
10:32:39 INFO - 'branch': 'Fx-Team-Non-PGO',
10:32:39 INFO - 'buildbot_json_path': 'buildprops.json',
10:32:39 INFO - 'config_files': ('talos/windows_config.py',),
10:32:39 INFO - 'default_actions': ('clobber',
10:32:39 INFO - 'read-buildbot-config',
10:32:39 INFO - 'download-and-extract',
10:32:39 INFO - 'populate-webroot',
10:32:39 INFO - 'create-virtualenv',
10:32:39 INFO - 'install',
10:32:39 INFO - 'run-tests'),
10:32:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
10:32:39 INFO - 'download_minidump_stackwalk': True,
10:32:39 INFO - 'download_symbols': 'ondemand',
10:32:39 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
10:32:39 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
10:32:39 INFO - 'hg': 'c:/mozilla-build/hg/hg',
10:32:39 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
10:32:39 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
10:32:39 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
10:32:39 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
10:32:39 INFO - 'C:/mozilla-build/tooltool.py'),
10:32:39 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
10:32:39 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
10:32:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
10:32:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
10:32:39 INFO - 'installer_path': 'installer.exe',
10:32:39 INFO - 'log_level': 'info',
10:32:39 INFO - 'log_name': 'talos',
10:32:39 INFO - 'log_to_console': True,
10:32:39 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
10:32:39 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
10:32:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
10:32:39 INFO - 'opt_config_files': (),
10:32:39 INFO - 'pip_index': False,
10:32:39 INFO - 'sps_profile': False,
10:32:39 INFO - 'sps_profile_interval': 0,
10:32:39 INFO - 'suite': 'other',
10:32:39 INFO - 'system_bits': '32',
10:32:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
10:32:39 INFO - 'title': 'T-XP32-IX-142',
10:32:39 INFO - 'use_talos_json': True,
10:32:39 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
10:32:39 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
10:32:39 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
10:32:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
10:32:39 INFO - 'work_dir': 'build'}
10:32:39 INFO - #####
10:32:39 INFO - ##### Running clobber step.
10:32:39 INFO - #####
10:32:39 INFO - Running pre-action listener: _resource_record_pre_action
10:32:39 INFO - Running main action method: clobber
10:32:39 INFO - rmtree: C:\slave\test\build
10:32:39 INFO - Using _rmtree_windows ...
10:32:39 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
10:32:49 INFO - Running post-action listener: _resource_record_post_action
10:32:49 INFO - #####
10:32:49 INFO - ##### Running read-buildbot-config step.
10:32:49 INFO - #####
10:32:49 INFO - Running pre-action listener: _resource_record_pre_action
10:32:49 INFO - Running main action method: read_buildbot_config
10:32:49 INFO - Using buildbot properties:
10:32:49 INFO - {
10:32:49 INFO - "properties": {
10:32:49 INFO - "buildnumber": 198,
10:32:49 INFO - "product": "firefox",
10:32:49 INFO - "script_repo_revision": "production",
10:32:49 INFO - "builddir": "fx-team_xp-ix_test-other",
10:32:49 INFO - "repository": "",
10:32:49 INFO - "buildername": "Windows XP 32-bit fx-team talos other",
10:32:49 INFO - "buildid": "20151118091623",
10:32:49 INFO - "slavename": "t-xp32-ix-142",
10:32:49 INFO - "pgo_build": "False",
10:32:49 INFO - "basedir": "C:\\slave\\test",
10:32:49 INFO - "project": "",
10:32:49 INFO - "platform": "xp-ix",
10:32:49 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
10:32:49 INFO - "slavebuilddir": "test",
10:32:49 INFO - "scheduler": "tests-fx-team-win32-talos",
10:32:49 INFO - "branch": "fx-team",
10:32:49 INFO - "repo_path": "integration/fx-team",
10:32:49 INFO - "stage_platform": "win32",
10:32:49 INFO - "builduid": "28474222b74b4731a603f3978a06f218",
10:32:49 INFO - "revision": "1d6ba006a526d17294369eed770a9a0ce2794bde"
10:32:49 INFO - },
10:32:49 INFO - "sourcestamp": {
10:32:49 INFO - "repository": "",
10:32:49 INFO - "hasPatch": false,
10:32:49 INFO - "project": "",
10:32:49 INFO - "branch": "fx-team-win32-talos",
10:32:49 INFO - "changes": [
10:32:49 INFO - {
10:32:49 INFO - "category": null,
10:32:49 INFO - "files": [
10:32:49 INFO - {
10:32:49 INFO - "url": null,
10:32:49 INFO - "name": "https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip"
10:32:49 INFO - }
10:32:49 INFO - ],
10:32:49 INFO - "repository": "",
10:32:49 INFO - "rev": "1d6ba006a526d17294369eed770a9a0ce2794bde",
10:32:49 INFO - "who": "dtownsend@mozilla.com",
10:32:49 INFO - "when": 1447871493,
10:32:49 INFO - "number": 6678192,
10:32:49 INFO - "comments": "Bug 1220911: Remove most of the special casing around experiments. r=rhelmer\n\nExperiments should differ from normal add-ons in a few ways:\n\n* They can always be enabled regardless of compatibility info\n* They default to disabled when installed\n* They cannot be checked for updates\n* They only stay enabled for the lifetime of the current process\n* The UI doesn't give users the ability to enable/disable\n\nThis makes a few changes to keep these differences but remove much of the special casing code for experiments.\n\nBeing able to use regardless of compatibility was mostly fixed by bug 1220198 but I've also removed the redundant override in isCompatible.\n\nPreviously the \"enabled until restart\" feature worked with by not updating the DBAddonInternal object and instead using a hack to make the wrapper still seem enabled. This seems likely to break other code that relies on the state of the DBAddonInternal object so instead we update that as normal and simply don't persist the enabled state to disk.\n\nAlso switch the D",
10:32:49 INFO - "project": "",
10:32:49 INFO - "at": "Wed 18 Nov 2015 10:31:33",
10:32:49 INFO - "branch": "fx-team-win32-talos",
10:32:49 INFO - "revlink": "",
10:32:49 INFO - "properties": [
10:32:49 INFO - [
10:32:49 INFO - "buildid",
10:32:49 INFO - "20151118091623",
10:32:49 INFO - "Change"
10:32:49 INFO - ],
10:32:49 INFO - [
10:32:49 INFO - "builduid",
10:32:49 INFO - "28474222b74b4731a603f3978a06f218",
10:32:49 INFO - "Change"
10:32:49 INFO - ],
10:32:49 INFO - [
10:32:49 INFO - "pgo_build",
10:32:49 INFO - "False",
10:32:49 INFO - "Change"
10:32:49 INFO - ]
10:32:49 INFO - ],
10:32:49 INFO - "revision": "1d6ba006a526d17294369eed770a9a0ce2794bde"
10:32:49 INFO - }
10:32:49 INFO - ],
10:32:49 INFO - "revision": "1d6ba006a526d17294369eed770a9a0ce2794bde"
10:32:49 INFO - }
10:32:49 INFO - }
10:32:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip.
10:32:49 INFO - Running post-action listener: _resource_record_post_action
10:32:49 INFO - #####
10:32:49 INFO - ##### Running download-and-extract step.
10:32:49 INFO - #####
10:32:49 INFO - Running pre-action listener: _resource_record_pre_action
10:32:49 INFO - Running main action method: download_and_extract
10:32:49 INFO - mkdir: C:\slave\test\build\tests
10:32:49 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')]}
10:32:49 INFO - https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
10:32:49 INFO - trying https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/test_packages.json
10:32:49 INFO - Downloading https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
10:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
10:32:50 INFO - Downloaded 1221 bytes.
10:32:50 INFO - Reading from file C:\slave\test\build\test_packages.json
10:32:50 INFO - Using the following test package requirements:
10:32:50 INFO - {u'common': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
10:32:50 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.cppunittest.tests.zip'],
10:32:50 INFO - u'jittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'jsshell-win32.zip'],
10:32:50 INFO - u'mochitest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.mochitest.tests.zip'],
10:32:50 INFO - u'mozbase': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
10:32:50 INFO - u'reftest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.reftest.tests.zip'],
10:32:50 INFO - u'talos': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.talos.tests.zip'],
10:32:50 INFO - u'web-platform': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.web-platform.tests.zip'],
10:32:50 INFO - u'webapprt': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
10:32:50 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
10:32:50 INFO - u'firefox-45.0a1.en-US.win32.xpcshell.tests.zip']}
10:32:50 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip'] for test suite category: common
10:32:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:32:50 INFO - https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
10:32:50 INFO - trying https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
10:32:50 INFO - Downloading https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:32:52 INFO - Downloaded 18033111 bytes.
10:32:52 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
10:32:52 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
10:32:52 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
10:33:23 INFO - Return code: 0
10:33:23 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
10:33: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')]}
10:33:23 INFO - https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
10:33:23 INFO - trying https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
10:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:33:25 INFO - Downloaded 18033111 bytes.
10:33:25 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
10:33:25 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
10:33:25 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
10:33:40 INFO - Return code: 0
10:33:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:40 INFO - https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
10:33:40 INFO - trying https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip
10:33:40 INFO - Downloading https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:33:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:33:41 INFO - Downloaded 11130997 bytes.
10:33:41 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
10:33:41 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip
10:33:41 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
10:33:42 INFO - Return code: 0
10:33:42 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')]}
10:33:42 INFO - https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip matches https://queue.taskcluster.net
10:33:42 INFO - trying https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
10:33:42 INFO - Downloading https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.zip
10:33:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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
10:33:46 INFO - Downloaded 51670570 bytes.
10:33:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
10:33:46 INFO - mkdir: C:\slave\test\properties
10:33:46 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
10:33:46 INFO - Writing to file C:\slave\test\properties\build_url
10:33:46 INFO - Contents:
10:33:46 INFO - build_url:https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
10:33:46 INFO - Running post-action listener: _resource_record_post_action
10:33:46 INFO - Running post-action listener: set_extra_try_arguments
10:33:46 INFO - #####
10:33:46 INFO - ##### Running populate-webroot step.
10:33:46 INFO - #####
10:33:46 INFO - Running pre-action listener: _resource_record_pre_action
10:33:46 INFO - Running main action method: populate_webroot
10:33:46 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
10:33:46 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
10:33:46 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
10:33:46 INFO - u'../fennec_ids.txt'],
10:33:46 INFO - u'tests': [u'tcheck2']},
10:33:46 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
10:33:46 INFO - u'--tppagecycles',
10:33:46 INFO - u'7'],
10:33:46 INFO - u'tests': [u'tsvgm']}},
10:33:46 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
10:33:46 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'tresize', u'tcanvasmark']},
10:33:46 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
10:33:46 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'dromaeo_css',
10:33:46 INFO - u'kraken',
10:33:46 INFO - u'v8_7']},
10:33:46 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
10:33:46 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
10:33:46 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'tests': [u'damp', u'tps']},
10:33:46 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'damp', u'tps']},
10:33:46 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
10:33:46 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
10:33:46 INFO - u'other': {u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'other_l64': {u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'other_nol64': {u'tests': [u'a11yr',
10:33:46 INFO - u'ts_paint',
10:33:46 INFO - u'tpaint',
10:33:46 INFO - u'sessionrestore',
10:33:46 INFO - u'sessionrestore_no_auto_restore']},
10:33:46 INFO - u'svgr': {u'tests': [u'tsvgx',
10:33:46 INFO - u'tsvgr_opacity',
10:33:46 INFO - u'tart',
10:33:46 INFO - u'tscrollx',
10:33:46 INFO - u'cart']},
10:33:46 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'tsvgx',
10:33:46 INFO - u'tsvgr_opacity',
10:33:46 INFO - u'tart',
10:33:46 INFO - u'tscrollx',
10:33:46 INFO - u'cart']},
10:33:46 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'tests': [u'tp5o']},
10:33:46 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'talos_options': [u'--e10s'],
10:33:46 INFO - u'tests': [u'tp5o']},
10:33:46 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'talos_options': [u'--xperf_path',
10:33:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:33:46 INFO - u'tests': [u'tp5n']},
10:33:46 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
10:33:46 INFO - u'talos_options': [u'--e10s',
10:33:46 INFO - u'--xperf_path',
10:33:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:33:46 INFO - u'tests': [u'tp5n']}},
10:33:46 INFO - u'talos.zip': {u'path': u'',
10:33:46 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
10:33:46 INFO - Running post-action listener: _resource_record_post_action
10:33:46 INFO - #####
10:33:46 INFO - ##### Running create-virtualenv step.
10:33:46 INFO - #####
10:33:46 INFO - Running pre-action listener: _resource_record_pre_action
10:33:46 INFO - Running main action method: create_virtualenv
10:33:46 INFO - Creating virtualenv C:\slave\test\build/venv
10:33:46 INFO - mkdir: C:\slave\test\build\venv\Scripts
10:33:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
10:33:46 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'!
10:33:46 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
10:33:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
10:33:47 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
10:33:49 INFO - Installing distribute......................................................................................................................................................................................done.
10:33:50 INFO - Return code: 0
10:33:50 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
10:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:33:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:33:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
10:33:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
10:33:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:33:50 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:33:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:33:50 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:33:50 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:33:50 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:33:50 INFO - 'DCLOCATION': 'SCL3',
10:33:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:33:50 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:33:50 INFO - 'HOMEDRIVE': 'C:',
10:33:50 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:33:50 INFO - 'KTS_VERSION': '1.19c',
10:33:50 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:33:50 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:33:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:33:50 INFO - 'MOZ_NO_REMOTE': '1',
10:33:50 INFO - 'NO_EM_RESTART': '1',
10:33:50 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:33:50 INFO - 'OS': 'Windows_NT',
10:33:50 INFO - 'OURDRIVE': 'C:',
10:33:50 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:33:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:33:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:33:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:33:50 INFO - 'PROCESSOR_LEVEL': '6',
10:33:50 INFO - 'PROCESSOR_REVISION': '1e05',
10:33:50 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:33:50 INFO - 'PROMPT': '$P$G',
10:33:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:33:50 INFO - 'PWD': 'C:\\slave\\test',
10:33:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:33:50 INFO - 'SESSIONNAME': 'Console',
10:33:50 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:33:50 INFO - 'SYSTEMDRIVE': 'C:',
10:33:50 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:33:50 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:50 INFO - 'TEST1': 'testie',
10:33:50 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:50 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:33:50 INFO - 'USERNAME': 'cltbld',
10:33:50 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:50 INFO - 'WINDIR': 'C:\\WINDOWS',
10:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:33:50 INFO - Ignoring indexes: http://pypi.python.org/simple/
10:33:50 INFO - Downloading/unpacking pip>=1.5
10:33:50 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
10:33:56 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz
10:33:56 INFO - Running setup.py egg_info for package pip
10:33:56 INFO - warning: no files found matching 'pip\cacert.pem'
10:33:56 INFO - warning: no files found matching '*.html' under directory 'docs'
10:33:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
10:33:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
10:33:56 INFO - Installing collected packages: pip
10:33:56 INFO - Found existing installation: pip 0.8.2
10:33:56 INFO - Uninstalling pip:
10:33:56 INFO - Successfully uninstalled pip
10:33:56 INFO - Running setup.py install for pip
10:33:56 INFO - warning: no files found matching 'pip\cacert.pem'
10:33:56 INFO - warning: no files found matching '*.html' under directory 'docs'
10:33:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
10:33:56 INFO - no previously-included directories found matching 'docs\_build\_sources'
10:33:56 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
10:33:56 INFO - Successfully installed pip
10:33:56 INFO - Cleaning up...
10:33:56 INFO - Return code: 0
10:33:56 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
10:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:33:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:33:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build
10:33:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1
10:33:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:33:56 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:33:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:33:56 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:33:56 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:33:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:33:56 INFO - 'DCLOCATION': 'SCL3',
10:33:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:33:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:33:56 INFO - 'HOMEDRIVE': 'C:',
10:33:56 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:33:56 INFO - 'KTS_VERSION': '1.19c',
10:33:56 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:33:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:33:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:33:56 INFO - 'MOZ_NO_REMOTE': '1',
10:33:56 INFO - 'NO_EM_RESTART': '1',
10:33:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:33:56 INFO - 'OS': 'Windows_NT',
10:33:56 INFO - 'OURDRIVE': 'C:',
10:33:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:33:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:33:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:33:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:33:56 INFO - 'PROCESSOR_LEVEL': '6',
10:33:56 INFO - 'PROCESSOR_REVISION': '1e05',
10:33:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:33:56 INFO - 'PROMPT': '$P$G',
10:33:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:33:56 INFO - 'PWD': 'C:\\slave\\test',
10:33:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:33:56 INFO - 'SESSIONNAME': 'Console',
10:33:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:33:56 INFO - 'SYSTEMDRIVE': 'C:',
10:33:56 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:33:56 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:56 INFO - 'TEST1': 'testie',
10:33:56 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:56 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:33:56 INFO - 'USERNAME': 'cltbld',
10:33:56 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:56 INFO - 'WINDIR': 'C:\\WINDOWS',
10:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:33:57 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:33:57 INFO - Downloading/unpacking psutil>=0.7.1
10:33:57 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
10:33:57 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
10:33:57 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
10:33:57 INFO - Installing collected packages: psutil
10:33:58 INFO - Successfully installed psutil
10:33:58 INFO - Cleaning up...
10:33:58 INFO - Return code: 0
10:33:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
10:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:33:58 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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:33:58 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
10:33:58 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
10:33:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:33:58 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:33:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:33:58 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:33:58 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:33:58 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:33:58 INFO - 'DCLOCATION': 'SCL3',
10:33:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:33:58 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:33:58 INFO - 'HOMEDRIVE': 'C:',
10:33:58 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:33:58 INFO - 'KTS_VERSION': '1.19c',
10:33:58 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:33:58 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:33:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:33:58 INFO - 'MOZ_NO_REMOTE': '1',
10:33:58 INFO - 'NO_EM_RESTART': '1',
10:33:58 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:33:58 INFO - 'OS': 'Windows_NT',
10:33:58 INFO - 'OURDRIVE': 'C:',
10:33:58 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;',
10:33:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:33:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:33:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:33:58 INFO - 'PROCESSOR_LEVEL': '6',
10:33:58 INFO - 'PROCESSOR_REVISION': '1e05',
10:33:58 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:33:58 INFO - 'PROMPT': '$P$G',
10:33:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:33:58 INFO - 'PWD': 'C:\\slave\\test',
10:33:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:33:58 INFO - 'SESSIONNAME': 'Console',
10:33:58 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:33:58 INFO - 'SYSTEMDRIVE': 'C:',
10:33:58 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:33:58 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:58 INFO - 'TEST1': 'testie',
10:33:58 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:58 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:33:58 INFO - 'USERNAME': 'cltbld',
10:33:58 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:58 INFO - 'WINDIR': 'C:\\WINDOWS',
10:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:33:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
10:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:33:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:33:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz
10:33:59 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
10:33:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
10:33:59 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)
10:33:59 INFO - Installing collected packages: mozsystemmonitor
10:33:59 INFO - Running setup.py install for mozsystemmonitor
10:33:59 INFO - Successfully installed mozsystemmonitor
10:33:59 INFO - Cleaning up...
10:33:59 INFO - Return code: 0
10:33:59 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
10:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:33:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:33:59 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:33:59 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build
10:33:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
10:33:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:33:59 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:33:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:33:59 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:33:59 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:33:59 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:33:59 INFO - 'DCLOCATION': 'SCL3',
10:33:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:33:59 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:33:59 INFO - 'HOMEDRIVE': 'C:',
10:33:59 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:33:59 INFO - 'KTS_VERSION': '1.19c',
10:33:59 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:33:59 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:33:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:33:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:33:59 INFO - 'MOZ_NO_REMOTE': '1',
10:33:59 INFO - 'NO_EM_RESTART': '1',
10:33:59 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:33:59 INFO - 'OS': 'Windows_NT',
10:33:59 INFO - 'OURDRIVE': 'C:',
10:33:59 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:33:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:33:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:33:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:33:59 INFO - 'PROCESSOR_LEVEL': '6',
10:33:59 INFO - 'PROCESSOR_REVISION': '1e05',
10:33:59 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:33:59 INFO - 'PROMPT': '$P$G',
10:33:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:33:59 INFO - 'PWD': 'C:\\slave\\test',
10:33:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:33:59 INFO - 'SESSIONNAME': 'Console',
10:33:59 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:33:59 INFO - 'SYSTEMDRIVE': 'C:',
10:33:59 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:33:59 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:59 INFO - 'TEST1': 'testie',
10:33:59 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:33:59 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:33:59 INFO - 'USERNAME': 'cltbld',
10:33:59 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:33:59 INFO - 'WINDIR': 'C:\\WINDOWS',
10:33:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:34:00 INFO - Downloading/unpacking blobuploader==1.2.4
10:34:00 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
10:34:00 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
10:34:01 INFO - Downloading blobuploader-1.2.4.tar.gz
10:34:01 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
10:34:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
10:34:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
10:34:01 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
10:34:01 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
10:34:01 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
10:34:01 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
10:34:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
10:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
10:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
10:34:02 INFO - Downloading docopt-0.6.1.tar.gz
10:34:02 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
10:34:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
10:34:02 INFO - Installing collected packages: blobuploader, requests, docopt
10:34:02 INFO - Running setup.py install for blobuploader
10:34:02 INFO - Running setup.py install for requests
10:34:03 INFO - Running setup.py install for docopt
10:34:03 INFO - Successfully installed blobuploader requests docopt
10:34:03 INFO - Cleaning up...
10:34:03 INFO - Return code: 0
10:34:03 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:34:03 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')]}
10:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:03 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')]}
10:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:03 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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:34:03 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
10:34:03 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
10:34:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:34:03 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:34:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:34:03 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:34:03 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:34:03 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:34:03 INFO - 'DCLOCATION': 'SCL3',
10:34:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:34:03 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:34:03 INFO - 'HOMEDRIVE': 'C:',
10:34:03 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:34:03 INFO - 'KTS_VERSION': '1.19c',
10:34:03 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:34:03 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:34:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:03 INFO - 'MOZ_NO_REMOTE': '1',
10:34:03 INFO - 'NO_EM_RESTART': '1',
10:34:03 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:34:03 INFO - 'OS': 'Windows_NT',
10:34:03 INFO - 'OURDRIVE': 'C:',
10:34:03 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;',
10:34:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:34:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:34:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:34:03 INFO - 'PROCESSOR_LEVEL': '6',
10:34:03 INFO - 'PROCESSOR_REVISION': '1e05',
10:34:03 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:34:03 INFO - 'PROMPT': '$P$G',
10:34:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:34:03 INFO - 'PWD': 'C:\\slave\\test',
10:34:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:34:03 INFO - 'SESSIONNAME': 'Console',
10:34:03 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:34:03 INFO - 'SYSTEMDRIVE': 'C:',
10:34:03 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:34:03 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:03 INFO - 'TEST1': 'testie',
10:34:03 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:03 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:34:03 INFO - 'USERNAME': 'cltbld',
10:34:03 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:03 INFO - 'WINDIR': 'C:\\WINDOWS',
10:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:34:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
10:34:04 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xzjjqb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
10:34:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
10:34:04 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-nfin8j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
10:34:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
10:34:04 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-me2vmu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
10:34:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
10:34:04 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-5yrgwe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wplvsj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-edyls6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-qoof9g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ggvzlf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ndf9hd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
10:34:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
10:34:05 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-musw92-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
10:34:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
10:34:06 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-7tv2n4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
10:34:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
10:34:06 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-cvcxg6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
10:34:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
10:34:06 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-dywtnn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
10:34:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
10:34:06 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-igt6xu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
10:34:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
10:34:06 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-chnl7i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
10:34:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
10:34:07 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-cyhsxp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
10:34:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
10:34:07 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wnkysf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
10:34:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
10:34:07 INFO - Running setup.py install for manifestparser
10:34:07 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
10:34:07 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
10:34:07 INFO - Running setup.py install for mozcrash
10:34:08 INFO - Running setup.py install for mozdebug
10:34:08 INFO - Running setup.py install for mozdevice
10:34:08 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
10:34:08 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
10:34:08 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
10:34:08 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
10:34:08 INFO - Running setup.py install for mozfile
10:34:09 INFO - Running setup.py install for mozhttpd
10:34:09 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
10:34:09 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
10:34:09 INFO - Running setup.py install for mozinfo
10:34:09 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
10:34:09 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
10:34:09 INFO - Running setup.py install for mozInstall
10:34:10 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Running setup.py install for mozleak
10:34:10 INFO - Running setup.py install for mozlog
10:34:10 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
10:34:10 INFO - Running setup.py install for moznetwork
10:34:11 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Running setup.py install for mozprocess
10:34:11 INFO - Running setup.py install for mozprofile
10:34:11 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
10:34:11 INFO - Running setup.py install for mozrunner
10:34:12 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
10:34:12 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
10:34:12 INFO - Running setup.py install for mozscreenshot
10:34:12 INFO - Running setup.py install for moztest
10:34:12 INFO - Running setup.py install for mozversion
10:34:13 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
10:34:13 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
10:34:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
10:34:13 INFO - Cleaning up...
10:34:13 INFO - Return code: 0
10:34:13 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:34:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:34:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:13 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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:34:13 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
10:34:13 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
10:34:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:34:13 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:34:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:34:13 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:34:13 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:34:13 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:34:13 INFO - 'DCLOCATION': 'SCL3',
10:34:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:34:13 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:34:13 INFO - 'HOMEDRIVE': 'C:',
10:34:13 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:34:13 INFO - 'KTS_VERSION': '1.19c',
10:34:13 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:34:13 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:34:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:34:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:13 INFO - 'MOZ_NO_REMOTE': '1',
10:34:13 INFO - 'NO_EM_RESTART': '1',
10:34:13 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:34:13 INFO - 'OS': 'Windows_NT',
10:34:13 INFO - 'OURDRIVE': 'C:',
10:34:13 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;',
10:34:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:34:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:34:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:34:13 INFO - 'PROCESSOR_LEVEL': '6',
10:34:13 INFO - 'PROCESSOR_REVISION': '1e05',
10:34:13 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:34:13 INFO - 'PROMPT': '$P$G',
10:34:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:34:13 INFO - 'PWD': 'C:\\slave\\test',
10:34:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:34:13 INFO - 'SESSIONNAME': 'Console',
10:34:13 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:34:13 INFO - 'SYSTEMDRIVE': 'C:',
10:34:13 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:34:13 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:13 INFO - 'TEST1': 'testie',
10:34:13 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:13 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:34:13 INFO - 'USERNAME': 'cltbld',
10:34:13 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:13 INFO - 'WINDIR': 'C:\\WINDOWS',
10:34:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:34:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:34:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
10:34:13 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ly70ps-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rfn2bd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vprpja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wc3ake-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-5tw6b4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-4gcetx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
10:34:14 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))
10:34:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
10:34:14 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ccj9ee-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
10:34:15 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))
10:34:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
10:34:15 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-c7vsif-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
10:34:15 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))
10:34:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
10:34:15 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zplrxg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
10:34:15 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))
10:34:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
10:34:15 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-5dgkwh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
10:34:15 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))
10:34:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
10:34:15 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zzxkw0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
10:34:15 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))
10:34:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
10:34:15 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ds7xui-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
10:34:16 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))
10:34:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
10:34:16 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-_fnxso-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
10:34:16 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))
10:34:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
10:34:16 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-bsqbkq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
10:34:16 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))
10:34:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
10:34:16 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-nuxkec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
10:34:16 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))
10:34:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
10:34:16 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-7zv6zl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
10:34:16 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))
10:34:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
10:34:16 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xmkfjl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
10:34:17 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))
10:34:17 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))
10:34:17 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))
10:34:17 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))
10:34:17 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))
10:34:17 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))
10:34:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
10:34:17 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
10:34:17 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
10:34:18 INFO - Downloading blessings-1.5.1.tar.gz
10:34:18 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
10:34:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
10:34:18 INFO - Installing collected packages: blessings
10:34:18 INFO - Running setup.py install for blessings
10:34:18 INFO - Successfully installed blessings
10:34:18 INFO - Cleaning up...
10:34:18 INFO - Return code: 0
10:34:18 INFO - Done creating virtualenv C:\slave\test\build/venv.
10:34:18 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
10:34:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
10:34:19 INFO - Reading from file tmpfile_stdout
10:34:19 INFO - Using _rmtree_windows ...
10:34:19 INFO - Using _rmtree_windows ...
10:34:19 INFO - Current package versions:
10:34:19 INFO - blessings == 1.5.1
10:34:19 INFO - blobuploader == 1.2.4
10:34:19 INFO - docopt == 0.6.1
10:34:19 INFO - manifestparser == 1.1
10:34:19 INFO - mozInstall == 1.12
10:34:19 INFO - mozcrash == 0.16
10:34:19 INFO - mozdebug == 0.1
10:34:19 INFO - mozdevice == 0.47
10:34:19 INFO - mozfile == 1.2
10:34:19 INFO - mozhttpd == 0.7
10:34:19 INFO - mozinfo == 0.9
10:34:19 INFO - mozleak == 0.1
10:34:19 INFO - mozlog == 3.0
10:34:19 INFO - moznetwork == 0.27
10:34:19 INFO - mozprocess == 0.22
10:34:19 INFO - mozprofile == 0.27
10:34:19 INFO - mozrunner == 6.11
10:34:19 INFO - mozscreenshot == 0.1
10:34:19 INFO - mozsystemmonitor == 0.0
10:34:19 INFO - moztest == 0.7
10:34:19 INFO - mozversion == 1.4
10:34:19 INFO - psutil == 3.1.1
10:34:19 INFO - requests == 1.2.3
10:34:19 INFO - Installing None into virtualenv C:\slave\test\build/venv
10:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C96E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, '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 0x00D9D660>, '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-142', '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-142', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-142', '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', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\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-142'}}, attempt #1
10:34:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\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
10:34:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
10:34:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:34:19 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:34:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:34:19 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:34:19 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:34:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:34:19 INFO - 'DCLOCATION': 'SCL3',
10:34:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:34:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:34:19 INFO - 'HOMEDRIVE': 'C:',
10:34:19 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:34:19 INFO - 'KTS_VERSION': '1.19c',
10:34:19 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:34:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:34:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:19 INFO - 'MOZ_NO_REMOTE': '1',
10:34:19 INFO - 'NO_EM_RESTART': '1',
10:34:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:34:19 INFO - 'OS': 'Windows_NT',
10:34:19 INFO - 'OURDRIVE': 'C:',
10:34:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
10:34:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:34:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:34:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:34:19 INFO - 'PROCESSOR_LEVEL': '6',
10:34:19 INFO - 'PROCESSOR_REVISION': '1e05',
10:34:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:34:19 INFO - 'PROMPT': '$P$G',
10:34:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:34:19 INFO - 'PWD': 'C:\\slave\\test',
10:34:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:34:19 INFO - 'SESSIONNAME': 'Console',
10:34:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:34:19 INFO - 'SYSTEMDRIVE': 'C:',
10:34:19 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:34:19 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:19 INFO - 'TEST1': 'testie',
10:34:19 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:19 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:34:19 INFO - 'USERNAME': 'cltbld',
10:34:19 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:19 INFO - 'WINDIR': 'C:\\WINDOWS',
10:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 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))
10:34:19 INFO - Cleaning up...
10:34:19 INFO - Return code: 0
10:34:19 INFO - Running post-action listener: _resource_record_post_action
10:34:19 INFO - Running post-action listener: _start_resource_monitoring
10:34:19 INFO - Starting resource monitoring.
10:34:20 INFO - #####
10:34:20 INFO - ##### Running install step.
10:34:20 INFO - #####
10:34:20 INFO - Running pre-action listener: _resource_record_pre_action
10:34:20 INFO - Running main action method: install
10:34:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
10:34:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
10:34:20 INFO - Reading from file tmpfile_stdout
10:34:20 INFO - Using _rmtree_windows ...
10:34:20 INFO - Using _rmtree_windows ...
10:34:20 INFO - Detecting whether we're running mozinstall >=1.0...
10:34:20 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
10:34:20 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
10:34:20 INFO - Reading from file tmpfile_stdout
10:34:20 INFO - Output received:
10:34:20 INFO - Usage: mozinstall-script.py [options] installer
10:34:20 INFO - Options:
10:34:20 INFO - -h, --help show this help message and exit
10:34:20 INFO - -d DEST, --destination=DEST
10:34:20 INFO - Directory to install application into. [default:
10:34:20 INFO - "C:\slave\test"]
10:34:20 INFO - --app=APP Application being installed. [default: firefox]
10:34:20 INFO - Using _rmtree_windows ...
10:34:20 INFO - Using _rmtree_windows ...
10:34:20 INFO - mkdir: C:\slave\test\build\application
10:34:20 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']
10:34:20 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
10:34:22 INFO - Reading from file tmpfile_stdout
10:34:22 INFO - Output received:
10:34:22 INFO - C:\slave\test\build\application\firefox\firefox.exe
10:34:22 INFO - Using _rmtree_windows ...
10:34:22 INFO - Using _rmtree_windows ...
10:34:22 INFO - Running post-action listener: _resource_record_post_action
10:34:22 INFO - #####
10:34:22 INFO - ##### Running run-tests step.
10:34:22 INFO - #####
10:34:22 INFO - Running pre-action listener: _resource_record_pre_action
10:34:22 INFO - Running main action method: run_tests
10:34:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
10:34:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
10:34:22 INFO - Python 2.7.3
10:34:22 INFO - Return code: 0
10:34:22 INFO - grabbing minidump binary from tooltool
10:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
10:34:22 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 0x00D9D660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
10:34:22 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
10:34:22 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
10:34:22 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
10:34:23 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp6w4qjm
10:34:23 INFO - INFO - File integrity verified, renaming tmp6w4qjm to win32-minidump_stackwalk.exe
10:34:23 INFO - Return code: 0
10:34:23 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
10:34:23 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
10:34:23 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
10:34:23 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
10:34:23 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
10:34:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-142', '--webServer', 'localhost'] in C:\slave\test\build
10:34:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite other --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip --title T-XP32-IX-142 --webServer localhost
10:34:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
10:34:23 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142\\Application Data',
10:34:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
10:34:23 INFO - 'COMPUTERNAME': 'T-XP32-IX-142',
10:34:23 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
10:34:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
10:34:23 INFO - 'DCLOCATION': 'SCL3',
10:34:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
10:34:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
10:34:23 INFO - 'HOMEDRIVE': 'C:',
10:34:23 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
10:34:23 INFO - 'KTS_VERSION': '1.19c',
10:34:23 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-142',
10:34:23 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
10:34:23 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
10:34:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
10:34:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
10:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:23 INFO - 'MOZ_NO_REMOTE': '1',
10:34:23 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
10:34:23 INFO - 'NO_EM_RESTART': '1',
10:34:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
10:34:23 INFO - 'OS': 'Windows_NT',
10:34:23 INFO - 'OURDRIVE': 'C:',
10:34: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;',
10:34:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
10:34:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
10:34:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
10:34:23 INFO - 'PROCESSOR_LEVEL': '6',
10:34:23 INFO - 'PROCESSOR_REVISION': '1e05',
10:34:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
10:34:23 INFO - 'PROMPT': '$P$G',
10:34:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
10:34:23 INFO - 'PWD': 'C:\\slave\\test',
10:34:23 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
10:34:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
10:34:23 INFO - 'SESSIONNAME': 'Console',
10:34:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
10:34:23 INFO - 'SYSTEMDRIVE': 'C:',
10:34:23 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
10:34:23 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:23 INFO - 'TEST1': 'testie',
10:34:23 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
10:34:23 INFO - 'USERDOMAIN': 'T-XP32-IX-142',
10:34:23 INFO - 'USERNAME': 'cltbld',
10:34:23 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-142',
10:34:23 INFO - 'WINDIR': 'C:\\WINDOWS',
10:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
10:34:23 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-142', '--webServer', 'localhost'] with output_timeout 3600
10:34:24 INFO - mozversion INFO | application_buildid: 20151118091623
10:34:24 INFO - mozversion INFO | application_changeset: 1d6ba006a526d17294369eed770a9a0ce2794bde
10:34:24 INFO - mozversion INFO | application_display_name: Nightly
10:34:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
10:34:24 INFO - mozversion INFO | application_name: Firefox
10:34:24 INFO - mozversion INFO | application_remotingname: firefox
10:34:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team
10:34:24 INFO - mozversion INFO | application_vendor: Mozilla
10:34:24 INFO - mozversion INFO | application_version: 45.0a1
10:34:24 INFO - mozversion INFO | platform_buildid: 20151118091623
10:34:24 INFO - mozversion INFO | platform_changeset: 1d6ba006a526d17294369eed770a9a0ce2794bde
10:34:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team
10:34:24 INFO - mozversion INFO | platform_version: 45.0a1
10:34:24 INFO - 2015-11-18 10:34:24,059 DEBUG : using testdate: 1447871664
10:34:24 INFO - 2015-11-18 10:34:24,059 DEBUG : actual date: 1447871664
10:34:24 INFO - 2015-11-18 10:34:24,105 INFO : starting webserver on 'localhost:1631'
10:34:24 INFO - 2015-11-18 10:34:24,121 INFO : Starting test suite T-XP32-IX-142
10:34:24 INFO - 2015-11-18 10:34:24,121 INFO : Starting test a11yr
10:34:24 INFO - 2015-11-18 10:34:24,121 DEBUG : operating with platform_type : win_
10:34:24 INFO - 2015-11-18 10:34:24,121 INFO : Initialising browser for a11yr test...
10:34:24 INFO - 2015-11-18 10:34:24,167 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpv3unrr\profile http://localhost:1631/getInfo.html
10:34:27 INFO - 2015-11-18 10:34:27,552 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
10:34:27 INFO - 2015-11-18 10:34:27,552 DEBUG : BROWSER_OUTPUT: colorDepth:24
10:34:27 INFO - 2015-11-18 10:34:27,552 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
10:34:27 INFO - 2015-11-18 10:34:27,552 DEBUG : BROWSER_OUTPUT: __metrics
10:34:27 INFO - 2015-11-18 10:34:27,582 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: console.error:
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: Stack:
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
10:34:27 INFO - 2015-11-18 10:34:27,969 DEBUG : BROWSER_OUTPUT:
10:34:28 INFO - 2015-11-18 10:34:28,525 INFO : Browser initialized.
10:34:28 INFO - 2015-11-18 10:34:28,525 INFO : Running cycle 1/1 for a11yr test...
10:34:28 INFO - 2015-11-18 10:34:28,525 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpv3unrr\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\a11y\a11y.manifest.develop -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25
10:34:40 INFO - 2015-11-18 10:34:40,019 DEBUG : BROWSER_OUTPUT: RSS: Main: 142540800
10:34:40 INFO - 2015-11-18 10:34:40,019 DEBUG : BROWSER_OUTPUT:
10:34:41 INFO - 2015-11-18 10:34:41,424 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)
10:34:42 INFO - 2015-11-18 10:34:42,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 172646400
10:34:42 INFO - 2015-11-18 10:34:42,012 DEBUG : BROWSER_OUTPUT:
10:34:43 INFO - 2015-11-18 10:34:43,526 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)
10:34:44 INFO - 2015-11-18 10:34:44,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 175833088
10:34:44 INFO - 2015-11-18 10:34:44,190 DEBUG : BROWSER_OUTPUT:
10:34:45 INFO - 2015-11-18 10:34:45,734 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)
10:34:46 INFO - 2015-11-18 10:34:46,384 DEBUG : BROWSER_OUTPUT: RSS: Main: 174612480
10:34:46 INFO - 2015-11-18 10:34:46,384 DEBUG : BROWSER_OUTPUT:
10:34:47 INFO - 2015-11-18 10:34:47,821 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)
10:34:48 INFO - 2015-11-18 10:34:48,484 DEBUG : BROWSER_OUTPUT: RSS: Main: 176177152
10:34:48 INFO - 2015-11-18 10:34:48,484 DEBUG : BROWSER_OUTPUT:
10:34:49 INFO - 2015-11-18 10:34:49,937 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)
10:34:50 INFO - 2015-11-18 10:34:50,601 DEBUG : BROWSER_OUTPUT: RSS: Main: 175984640
10:34:50 INFO - 2015-11-18 10:34:50,601 DEBUG : BROWSER_OUTPUT:
10:34:52 INFO - 2015-11-18 10:34:52,053 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)
10:34:52 INFO - 2015-11-18 10:34:52,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 176414720
10:34:52 INFO - 2015-11-18 10:34:52,717 DEBUG : BROWSER_OUTPUT:
10:34:54 INFO - 2015-11-18 10:34:54,279 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)
10:34:54 INFO - 2015-11-18 10:34:54,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 176738304
10:34:54 INFO - 2015-11-18 10:34:54,943 DEBUG : BROWSER_OUTPUT:
10:34:56 INFO - 2015-11-18 10:34:56,487 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)
10:34:57 INFO - 2015-11-18 10:34:57,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 177147904
10:34:57 INFO - 2015-11-18 10:34:57,167 DEBUG : BROWSER_OUTPUT:
10:34:58 INFO - 2015-11-18 10:34:58,697 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)
10:34:59 INFO - 2015-11-18 10:34:59,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 174817280
10:34:59 INFO - 2015-11-18 10:34:59,361 DEBUG : BROWSER_OUTPUT:
10:35:00 INFO - 2015-11-18 10:35:00,813 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)
10:35:01 INFO - 2015-11-18 10:35:01,463 DEBUG : BROWSER_OUTPUT: RSS: Main: 176803840
10:35:01 INFO - 2015-11-18 10:35:01,463 DEBUG : BROWSER_OUTPUT:
10:35:03 INFO - 2015-11-18 10:35:02,992 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)
10:35:03 INFO - 2015-11-18 10:35:03,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 174821376
10:35:03 INFO - 2015-11-18 10:35:03,657 DEBUG : BROWSER_OUTPUT:
10:35:05 INFO - 2015-11-18 10:35:05,151 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)
10:35:05 INFO - 2015-11-18 10:35:05,821 DEBUG : BROWSER_OUTPUT: RSS: Main: 176865280
10:35:05 INFO - 2015-11-18 10:35:05,821 DEBUG : BROWSER_OUTPUT:
10:35:07 INFO - 2015-11-18 10:35:07,377 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)
10:35:08 INFO - 2015-11-18 10:35:08,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 176353280
10:35:08 INFO - 2015-11-18 10:35:08,046 DEBUG : BROWSER_OUTPUT:
10:35:09 INFO - 2015-11-18 10:35:09,634 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)
10:35:10 INFO - 2015-11-18 10:35:10,302 DEBUG : BROWSER_OUTPUT: RSS: Main: 177500160
10:35:10 INFO - 2015-11-18 10:35:10,302 DEBUG : BROWSER_OUTPUT:
10:35:11 INFO - 2015-11-18 10:35:11,844 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)
10:35:12 INFO - 2015-11-18 10:35:12,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 176357376
10:35:12 INFO - 2015-11-18 10:35:12,513 DEBUG : BROWSER_OUTPUT:
10:35:14 INFO - 2015-11-18 10:35:14,052 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)
10:35:14 INFO - 2015-11-18 10:35:14,721 DEBUG : BROWSER_OUTPUT: RSS: Main: 176549888
10:35:14 INFO - 2015-11-18 10:35:14,721 DEBUG : BROWSER_OUTPUT:
10:35:16 INFO - 2015-11-18 10:35:16,170 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)
10:35:16 INFO - 2015-11-18 10:35:16,838 DEBUG : BROWSER_OUTPUT: RSS: Main: 174989312
10:35:16 INFO - 2015-11-18 10:35:16,838 DEBUG : BROWSER_OUTPUT:
10:35:18 INFO - 2015-11-18 10:35:18,411 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)
10:35:19 INFO - 2015-11-18 10:35:19,079 DEBUG : BROWSER_OUTPUT: RSS: Main: 177385472
10:35:19 INFO - 2015-11-18 10:35:19,079 DEBUG : BROWSER_OUTPUT:
10:35:20 INFO - 2015-11-18 10:35:20,526 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)
10:35:21 INFO - 2015-11-18 10:35:21,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 176898048
10:35:21 INFO - 2015-11-18 10:35:21,196 DEBUG : BROWSER_OUTPUT:
10:35:22 INFO - 2015-11-18 10:35:22,658 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)
10:35:23 INFO - 2015-11-18 10:35:23,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 176779264
10:35:23 INFO - 2015-11-18 10:35:23,328 DEBUG : BROWSER_OUTPUT:
10:35:24 INFO - 2015-11-18 10:35:24,790 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)
10:35:25 INFO - 2015-11-18 10:35:25,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 176709632
10:35:25 INFO - 2015-11-18 10:35:25,460 DEBUG : BROWSER_OUTPUT:
10:35:26 INFO - 2015-11-18 10:35:26,923 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)
10:35:27 INFO - 2015-11-18 10:35:27,592 DEBUG : BROWSER_OUTPUT: RSS: Main: 176721920
10:35:27 INFO - 2015-11-18 10:35:27,592 DEBUG : BROWSER_OUTPUT:
10:35:29 INFO - 2015-11-18 10:35:29,194 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)
10:35:29 INFO - 2015-11-18 10:35:29,335 DEBUG : BROWSER_OUTPUT: 1447871729359 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]
10:35:29 INFO - 2015-11-18 10:35:29,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 183087104
10:35:29 INFO - 2015-11-18 10:35:29,880 DEBUG : BROWSER_OUTPUT:
10:35:31 INFO - 2015-11-18 10:35:31,436 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)
10:35:32 INFO - 2015-11-18 10:35:32,135 DEBUG : BROWSER_OUTPUT: RSS: Main: 181477376
10:35:32 INFO - 2015-11-18 10:35:32,135 DEBUG : BROWSER_OUTPUT:
10:35:33 INFO - 2015-11-18 10:35:33,582 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)
10:35:34 INFO - 2015-11-18 10:35:34,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 179781632
10:35:34 INFO - 2015-11-18 10:35:34,252 DEBUG : BROWSER_OUTPUT:
10:35:35 INFO - 2015-11-18 10:35:35,170 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)
10:35:35 INFO - 2015-11-18 10:35:35,746 DEBUG : BROWSER_OUTPUT: RSS: Main: 178405376
10:35:35 INFO - 2015-11-18 10:35:35,746 DEBUG : BROWSER_OUTPUT:
10:35:36 INFO - 2015-11-18 10:35:36,601 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)
10:35:37 INFO - 2015-11-18 10:35:37,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 179249152
10:35:37 INFO - 2015-11-18 10:35:37,177 DEBUG : BROWSER_OUTPUT:
10:35:38 INFO - 2015-11-18 10:35:38,033 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)
10:35:38 INFO - 2015-11-18 10:35:38,609 DEBUG : BROWSER_OUTPUT: RSS: Main: 179683328
10:35:38 INFO - 2015-11-18 10:35:38,609 DEBUG : BROWSER_OUTPUT:
10:35:39 INFO - 2015-11-18 10:35:39,450 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)
10:35:40 INFO - 2015-11-18 10:35:40,040 DEBUG : BROWSER_OUTPUT: RSS: Main: 178749440
10:35:40 INFO - 2015-11-18 10:35:40,040 DEBUG : BROWSER_OUTPUT:
10:35:40 INFO - 2015-11-18 10:35:40,881 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)
10:35:41 INFO - 2015-11-18 10:35:41,457 DEBUG : BROWSER_OUTPUT: RSS: Main: 173592576
10:35:41 INFO - 2015-11-18 10:35:41,457 DEBUG : BROWSER_OUTPUT:
10:35:42 INFO - 2015-11-18 10:35:42,312 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)
10:35:42 INFO - 2015-11-18 10:35:42,904 DEBUG : BROWSER_OUTPUT: RSS: Main: 175050752
10:35:42 INFO - 2015-11-18 10:35:42,904 DEBUG : BROWSER_OUTPUT:
10:35:43 INFO - 2015-11-18 10:35:43,760 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)
10:35:44 INFO - 2015-11-18 10:35:44,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 175230976
10:35:44 INFO - 2015-11-18 10:35:44,335 DEBUG : BROWSER_OUTPUT:
10:35:45 INFO - 2015-11-18 10:35:45,191 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)
10:35:45 INFO - 2015-11-18 10:35:45,767 DEBUG : BROWSER_OUTPUT: RSS: Main: 174088192
10:35:45 INFO - 2015-11-18 10:35:45,767 DEBUG : BROWSER_OUTPUT:
10:35:46 INFO - 2015-11-18 10:35:46,624 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)
10:35:47 INFO - 2015-11-18 10:35:47,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 174915584
10:35:47 INFO - 2015-11-18 10:35:47,200 DEBUG : BROWSER_OUTPUT:
10:35:48 INFO - 2015-11-18 10:35:48,039 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)
10:35:48 INFO - 2015-11-18 10:35:48,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 175222784
10:35:48 INFO - 2015-11-18 10:35:48,631 DEBUG : BROWSER_OUTPUT:
10:35:49 INFO - 2015-11-18 10:35:49,486 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)
10:35:50 INFO - 2015-11-18 10:35:50,062 DEBUG : BROWSER_OUTPUT: RSS: Main: 175247360
10:35:50 INFO - 2015-11-18 10:35:50,062 DEBUG : BROWSER_OUTPUT:
10:35:50 INFO - 2015-11-18 10:35:50,918 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)
10:35:51 INFO - 2015-11-18 10:35:51,494 DEBUG : BROWSER_OUTPUT: RSS: Main: 175472640
10:35:51 INFO - 2015-11-18 10:35:51,494 DEBUG : BROWSER_OUTPUT:
10:35:52 INFO - 2015-11-18 10:35:52,351 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)
10:35:52 INFO - 2015-11-18 10:35:52,926 DEBUG : BROWSER_OUTPUT: RSS: Main: 175259648
10:35:52 INFO - 2015-11-18 10:35:52,926 DEBUG : BROWSER_OUTPUT:
10:35:53 INFO - 2015-11-18 10:35:53,782 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)
10:35:54 INFO - 2015-11-18 10:35:54,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 174788608
10:35:54 INFO - 2015-11-18 10:35:54,358 DEBUG : BROWSER_OUTPUT:
10:35:55 INFO - 2015-11-18 10:35:55,213 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)
10:35:55 INFO - 2015-11-18 10:35:55,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 174383104
10:35:55 INFO - 2015-11-18 10:35:55,789 DEBUG : BROWSER_OUTPUT:
10:35:56 INFO - 2015-11-18 10:35:56,645 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)
10:35:57 INFO - 2015-11-18 10:35:57,220 DEBUG : BROWSER_OUTPUT: RSS: Main: 175382528
10:35:57 INFO - 2015-11-18 10:35:57,220 DEBUG : BROWSER_OUTPUT:
10:35:58 INFO - 2015-11-18 10:35:58,092 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)
10:35:58 INFO - 2015-11-18 10:35:58,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 174555136
10:35:58 INFO - 2015-11-18 10:35:58,668 DEBUG : BROWSER_OUTPUT:
10:35:59 INFO - 2015-11-18 10:35:59,509 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)
10:36:00 INFO - 2015-11-18 10:36:00,099 DEBUG : BROWSER_OUTPUT: RSS: Main: 175439872
10:36:00 INFO - 2015-11-18 10:36:00,099 DEBUG : BROWSER_OUTPUT:
10:36:00 INFO - 2015-11-18 10:36:00,940 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)
10:36:01 INFO - 2015-11-18 10:36:01,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 175247360
10:36:01 INFO - 2015-11-18 10:36:01,532 DEBUG : BROWSER_OUTPUT:
10:36:02 INFO - 2015-11-18 10:36:02,388 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)
10:36:02 INFO - 2015-11-18 10:36:02,963 DEBUG : BROWSER_OUTPUT: RSS: Main: 175202304
10:36:02 INFO - 2015-11-18 10:36:02,963 DEBUG : BROWSER_OUTPUT:
10:36:03 INFO - 2015-11-18 10:36:03,819 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)
10:36:04 INFO - 2015-11-18 10:36:04,411 DEBUG : BROWSER_OUTPUT: RSS: Main: 174219264
10:36:04 INFO - 2015-11-18 10:36:04,411 DEBUG : BROWSER_OUTPUT:
10:36:05 INFO - 2015-11-18 10:36:05,266 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)
10:36:05 INFO - 2015-11-18 10:36:05,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 174141440
10:36:05 INFO - 2015-11-18 10:36:05,842 DEBUG : BROWSER_OUTPUT:
10:36:06 INFO - 2015-11-18 10:36:06,697 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)
10:36:07 INFO - 2015-11-18 10:36:07,273 DEBUG : BROWSER_OUTPUT: RSS: Main: 173895680
10:36:07 INFO - 2015-11-18 10:36:07,273 DEBUG : BROWSER_OUTPUT:
10:36:08 INFO - 2015-11-18 10:36:08,130 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)
10:36:08 INFO - 2015-11-18 10:36:08,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 174718976
10:36:08 INFO - 2015-11-18 10:36:08,706 DEBUG : BROWSER_OUTPUT:
10:36:09 INFO - 2015-11-18 10:36:09,562 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)
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 174026752
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT:
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: __start_tp_report
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;1296;1451;1510;1375;1367;1364;1472;1461;1458;1366;1467;1419;1492;1492;1489;1471;1380;1478;1389;1367;1393;1374;1512;1473;1383
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;339;328;336;314;328;339;319;340;329;330;341;328;338;333;332;325;332;339;329;329;352;327;332;331;336
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: __end_tp_report
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: __start_cc_report
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,6134
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: __end_cc_report
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871769840__endTimestamp
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT:
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: Number of tests: 2
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT:
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:1427.96 Median:1451.00 stddev:59.07 (4.1%) stddev-sans-first:53.40
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: Values: 1296.0 1451.0 1510.0 1375.0 1367.0 1364.0 1472.0 1461.0 1458.0 1366.0 1467.0 1419.0 1492.0 1492.0 1489.0 1471.0 1380.0 1478.0 1389.0 1367.0 1393.0 1374.0 1512.0 1473.0 1383.0
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT:
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:332.24 Median:332.00 stddev:7.66 (2.3%) stddev-sans-first:7.69
10:36:09 INFO - 2015-11-18 10:36:09,811 DEBUG : BROWSER_OUTPUT: Values: 339.0 328.0 336.0 314.0 328.0 339.0 319.0 340.0 329.0 330.0 341.0 328.0 338.0 333.0 332.0 325.0 332.0 339.0 329.0 329.0 352.0 327.0 332.0 331.0 336.0
10:36:09 INFO - 2015-11-18 10:36:09,825 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
10:36:09 INFO - 2015-11-18 10:36:09,825 DEBUG : BROWSER_OUTPUT:
10:36:10 INFO - 2015-11-18 10:36:10,371 INFO : Browser exited with error code: 0
10:36:10 INFO - 2015-11-18 10:36:10,401 INFO : Completed test a11yr (00:01:46)
10:36:10 INFO - 2015-11-18 10:36:10,401 INFO : Starting test ts_paint
10:36:10 INFO - 2015-11-18 10:36:10,401 DEBUG : operating with platform_type : win_
10:36:10 INFO - 2015-11-18 10:36:10,401 INFO : Initialising browser for ts_paint test...
10:36:10 INFO - 2015-11-18 10:36:10,417 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/getInfo.html
10:36:12 INFO - 2015-11-18 10:36:12,253 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
10:36:12 INFO - 2015-11-18 10:36:12,253 DEBUG : BROWSER_OUTPUT: colorDepth:24
10:36:12 INFO - 2015-11-18 10:36:12,253 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
10:36:12 INFO - 2015-11-18 10:36:12,253 DEBUG : BROWSER_OUTPUT: __metrics
10:36:12 INFO - 2015-11-18 10:36:12,269 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:36:13 INFO - 2015-11-18 10:36:13,078 INFO : Browser initialized.
10:36:13 INFO - 2015-11-18 10:36:13,078 INFO : Running cycle 1/20 for ts_paint test...
10:36:13 INFO - 2015-11-18 10:36:13,078 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:14 INFO - 2015-11-18 10:36:14,494 DEBUG : BROWSER_OUTPUT: __start_report1431__end_report
10:36:14 INFO - 2015-11-18 10:36:14,494 DEBUG : BROWSER_OUTPUT:
10:36:14 INFO - 2015-11-18 10:36:14,494 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871774505__endTimestamp
10:36:15 INFO - 2015-11-18 10:36:15,242 INFO : Browser exited with error code: 0
10:36:15 INFO - 2015-11-18 10:36:15,242 INFO : Running cycle 2/20 for ts_paint test...
10:36:15 INFO - 2015-11-18 10:36:15,242 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:16 INFO - 2015-11-18 10:36:16,253 DEBUG : BROWSER_OUTPUT: __start_report1032__end_report
10:36:16 INFO - 2015-11-18 10:36:16,253 DEBUG : BROWSER_OUTPUT:
10:36:16 INFO - 2015-11-18 10:36:16,253 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871776271__endTimestamp
10:36:17 INFO - 2015-11-18 10:36:17,000 INFO : Browser exited with error code: 0
10:36:17 INFO - 2015-11-18 10:36:17,000 INFO : Running cycle 3/20 for ts_paint test...
10:36:17 INFO - 2015-11-18 10:36:17,000 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:18 INFO - 2015-11-18 10:36:18,026 DEBUG : BROWSER_OUTPUT: __start_report1052__end_report
10:36:18 INFO - 2015-11-18 10:36:18,026 DEBUG : BROWSER_OUTPUT:
10:36:18 INFO - 2015-11-18 10:36:18,042 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871778049__endTimestamp
10:36:18 INFO - 2015-11-18 10:36:18,805 INFO : Browser exited with error code: 0
10:36:18 INFO - 2015-11-18 10:36:18,805 INFO : Running cycle 4/20 for ts_paint test...
10:36:18 INFO - 2015-11-18 10:36:18,805 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:19 INFO - 2015-11-18 10:36:19,848 DEBUG : BROWSER_OUTPUT: __start_report1041__end_report
10:36:19 INFO - 2015-11-18 10:36:19,848 DEBUG : BROWSER_OUTPUT:
10:36:19 INFO - 2015-11-18 10:36:19,848 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871779854__endTimestamp
10:36:20 INFO - 2015-11-18 10:36:20,611 INFO : Browser exited with error code: 0
10:36:20 INFO - 2015-11-18 10:36:20,611 INFO : Running cycle 5/20 for ts_paint test...
10:36:20 INFO - 2015-11-18 10:36:20,611 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:21 INFO - 2015-11-18 10:36:21,638 DEBUG : BROWSER_OUTPUT: __start_report1041__end_report
10:36:21 INFO - 2015-11-18 10:36:21,638 DEBUG : BROWSER_OUTPUT:
10:36:21 INFO - 2015-11-18 10:36:21,638 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871781658__endTimestamp
10:36:22 INFO - 2015-11-18 10:36:22,477 INFO : Browser exited with error code: 0
10:36:22 INFO - 2015-11-18 10:36:22,477 INFO : Running cycle 6/20 for ts_paint test...
10:36:22 INFO - 2015-11-18 10:36:22,477 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:23 INFO - 2015-11-18 10:36:23,490 DEBUG : BROWSER_OUTPUT: __start_report1030__end_report
10:36:23 INFO - 2015-11-18 10:36:23,490 DEBUG : BROWSER_OUTPUT:
10:36:23 INFO - 2015-11-18 10:36:23,490 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871783508__endTimestamp
10:36:24 INFO - 2015-11-18 10:36:24,329 INFO : Browser exited with error code: 0
10:36:24 INFO - 2015-11-18 10:36:24,329 INFO : Running cycle 7/20 for ts_paint test...
10:36:24 INFO - 2015-11-18 10:36:24,329 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:25 INFO - 2015-11-18 10:36:25,372 DEBUG : BROWSER_OUTPUT: __start_report1056__end_report
10:36:25 INFO - 2015-11-18 10:36:25,372 DEBUG : BROWSER_OUTPUT:
10:36:25 INFO - 2015-11-18 10:36:25,372 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871785379__endTimestamp
10:36:26 INFO - 2015-11-18 10:36:26,197 INFO : Browser exited with error code: 0
10:36:26 INFO - 2015-11-18 10:36:26,197 INFO : Running cycle 8/20 for ts_paint test...
10:36:26 INFO - 2015-11-18 10:36:26,197 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:27 INFO - 2015-11-18 10:36:27,193 DEBUG : BROWSER_OUTPUT: __start_report1015__end_report
10:36:27 INFO - 2015-11-18 10:36:27,193 DEBUG : BROWSER_OUTPUT:
10:36:27 INFO - 2015-11-18 10:36:27,209 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871787214__endTimestamp
10:36:28 INFO - 2015-11-18 10:36:28,049 INFO : Browser exited with error code: 0
10:36:28 INFO - 2015-11-18 10:36:28,049 INFO : Running cycle 9/20 for ts_paint test...
10:36:28 INFO - 2015-11-18 10:36:28,049 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:29 INFO - 2015-11-18 10:36:29,075 DEBUG : BROWSER_OUTPUT: __start_report1026__end_report
10:36:29 INFO - 2015-11-18 10:36:29,075 DEBUG : BROWSER_OUTPUT:
10:36:29 INFO - 2015-11-18 10:36:29,075 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871789090__endTimestamp
10:36:29 INFO - 2015-11-18 10:36:29,917 INFO : Browser exited with error code: 0
10:36:29 INFO - 2015-11-18 10:36:29,917 INFO : Running cycle 10/20 for ts_paint test...
10:36:29 INFO - 2015-11-18 10:36:29,917 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:30 INFO - 2015-11-18 10:36:30,927 DEBUG : BROWSER_OUTPUT: __start_report1019__end_report
10:36:30 INFO - 2015-11-18 10:36:30,927 DEBUG : BROWSER_OUTPUT:
10:36:30 INFO - 2015-11-18 10:36:30,927 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871790942__endTimestamp
10:36:31 INFO - 2015-11-18 10:36:31,767 INFO : Browser exited with error code: 0
10:36:31 INFO - 2015-11-18 10:36:31,767 INFO : Running cycle 11/20 for ts_paint test...
10:36:31 INFO - 2015-11-18 10:36:31,767 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:32 INFO - 2015-11-18 10:36:32,826 DEBUG : BROWSER_OUTPUT: __start_report1064__end_report
10:36:32 INFO - 2015-11-18 10:36:32,826 DEBUG : BROWSER_OUTPUT:
10:36:32 INFO - 2015-11-18 10:36:32,826 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871792835__endTimestamp
10:36:33 INFO - 2015-11-18 10:36:33,681 INFO : Browser exited with error code: 0
10:36:33 INFO - 2015-11-18 10:36:33,681 INFO : Running cycle 12/20 for ts_paint test...
10:36:33 INFO - 2015-11-18 10:36:33,681 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:34 INFO - 2015-11-18 10:36:34,694 DEBUG : BROWSER_OUTPUT: __start_report1026__end_report
10:36:34 INFO - 2015-11-18 10:36:34,694 DEBUG : BROWSER_OUTPUT:
10:36:34 INFO - 2015-11-18 10:36:34,710 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871794718__endTimestamp
10:36:35 INFO - 2015-11-18 10:36:35,533 INFO : Browser exited with error code: 0
10:36:35 INFO - 2015-11-18 10:36:35,533 INFO : Running cycle 13/20 for ts_paint test...
10:36:35 INFO - 2015-11-18 10:36:35,533 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:36 INFO - 2015-11-18 10:36:36,561 DEBUG : BROWSER_OUTPUT: __start_report1030__end_report
10:36:36 INFO - 2015-11-18 10:36:36,561 DEBUG : BROWSER_OUTPUT:
10:36:36 INFO - 2015-11-18 10:36:36,576 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871796578__endTimestamp
10:36:37 INFO - 2015-11-18 10:36:37,401 INFO : Browser exited with error code: 0
10:36:37 INFO - 2015-11-18 10:36:37,401 INFO : Running cycle 14/20 for ts_paint test...
10:36:37 INFO - 2015-11-18 10:36:37,401 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:38 INFO - 2015-11-18 10:36:38,413 DEBUG : BROWSER_OUTPUT: __start_report1031__end_report
10:36:38 INFO - 2015-11-18 10:36:38,413 DEBUG : BROWSER_OUTPUT:
10:36:38 INFO - 2015-11-18 10:36:38,428 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871798435__endTimestamp
10:36:39 INFO - 2015-11-18 10:36:39,315 INFO : Browser exited with error code: 0
10:36:39 INFO - 2015-11-18 10:36:39,315 INFO : Running cycle 15/20 for ts_paint test...
10:36:39 INFO - 2015-11-18 10:36:39,315 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:40 INFO - 2015-11-18 10:36:40,326 DEBUG : BROWSER_OUTPUT: __start_report1035__end_report
10:36:40 INFO - 2015-11-18 10:36:40,326 DEBUG : BROWSER_OUTPUT:
10:36:40 INFO - 2015-11-18 10:36:40,342 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871800353__endTimestamp
10:36:41 INFO - 2015-11-18 10:36:41,213 INFO : Browser exited with error code: 0
10:36:41 INFO - 2015-11-18 10:36:41,213 INFO : Running cycle 16/20 for ts_paint test...
10:36:41 INFO - 2015-11-18 10:36:41,213 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:42 INFO - 2015-11-18 10:36:42,240 DEBUG : BROWSER_OUTPUT: __start_report1033__end_report
10:36:42 INFO - 2015-11-18 10:36:42,240 DEBUG : BROWSER_OUTPUT:
10:36:42 INFO - 2015-11-18 10:36:42,240 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871802256__endTimestamp
10:36:43 INFO - 2015-11-18 10:36:43,082 INFO : Browser exited with error code: 0
10:36:43 INFO - 2015-11-18 10:36:43,082 INFO : Running cycle 17/20 for ts_paint test...
10:36:43 INFO - 2015-11-18 10:36:43,082 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:44 INFO - 2015-11-18 10:36:44,092 DEBUG : BROWSER_OUTPUT: __start_report1022__end_report
10:36:44 INFO - 2015-11-18 10:36:44,092 DEBUG : BROWSER_OUTPUT:
10:36:44 INFO - 2015-11-18 10:36:44,108 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871804112__endTimestamp
10:36:44 INFO - 2015-11-18 10:36:44,980 INFO : Browser exited with error code: 0
10:36:44 INFO - 2015-11-18 10:36:44,980 INFO : Running cycle 18/20 for ts_paint test...
10:36:44 INFO - 2015-11-18 10:36:44,980 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:46 INFO - 2015-11-18 10:36:46,006 DEBUG : BROWSER_OUTPUT: __start_report1029__end_report
10:36:46 INFO - 2015-11-18 10:36:46,006 DEBUG : BROWSER_OUTPUT:
10:36:46 INFO - 2015-11-18 10:36:46,006 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871806024__endTimestamp
10:36:46 INFO - 2015-11-18 10:36:46,894 INFO : Browser exited with error code: 0
10:36:46 INFO - 2015-11-18 10:36:46,894 INFO : Running cycle 19/20 for ts_paint test...
10:36:46 INFO - 2015-11-18 10:36:46,894 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:47 INFO - 2015-11-18 10:36:47,937 DEBUG : BROWSER_OUTPUT: __start_report1056__end_report
10:36:47 INFO - 2015-11-18 10:36:47,937 DEBUG : BROWSER_OUTPUT:
10:36:47 INFO - 2015-11-18 10:36:47,937 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871807955__endTimestamp
10:36:48 INFO - 2015-11-18 10:36:48,808 INFO : Browser exited with error code: 0
10:36:48 INFO - 2015-11-18 10:36:48,808 INFO : Running cycle 20/20 for ts_paint test...
10:36:48 INFO - 2015-11-18 10:36:48,808 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp3mbpmb\profile http://localhost:1631/startup_test/tspaint_test.html
10:36:49 INFO - 2015-11-18 10:36:49,835 DEBUG : BROWSER_OUTPUT: __start_report1045__end_report
10:36:49 INFO - 2015-11-18 10:36:49,835 DEBUG : BROWSER_OUTPUT:
10:36:49 INFO - 2015-11-18 10:36:49,851 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871809858__endTimestamp
10:36:50 INFO - 2015-11-18 10:36:50,723 INFO : Browser exited with error code: 0
10:36:50 INFO - 2015-11-18 10:36:50,769 INFO : Completed test ts_paint (00:00:40)
10:36:50 INFO - 2015-11-18 10:36:50,769 INFO : Starting test tpaint
10:36:50 INFO - 2015-11-18 10:36:50,769 DEBUG : operating with platform_type : win_
10:36:50 INFO - 2015-11-18 10:36:50,769 INFO : Initialising browser for tpaint test...
10:36:50 INFO - 2015-11-18 10:36:50,785 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpeipu1a\profile http://localhost:1631/getInfo.html
10:36:52 INFO - 2015-11-18 10:36:52,589 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
10:36:52 INFO - 2015-11-18 10:36:52,589 DEBUG : BROWSER_OUTPUT: colorDepth:24
10:36:52 INFO - 2015-11-18 10:36:52,589 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
10:36:52 INFO - 2015-11-18 10:36:52,589 DEBUG : BROWSER_OUTPUT: __metrics
10:36:52 INFO - 2015-11-18 10:36:52,605 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:36:53 INFO - 2015-11-18 10:36:53,384 INFO : Browser initialized.
10:36:53 INFO - 2015-11-18 10:36:53,384 INFO : Running cycle 1/1 for tpaint test...
10:36:53 INFO - 2015-11-18 10:36:53,384 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpeipu1a\profile file://C:\slave\test\build\tests\talos\talos/startup_test/tpaint.html?auto=1
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: __start_report233.85500000000002|236.19000000000233|237.59499999999753|238.1800000000003|238.2449999999999|239.78499999999985|239.84000000000015|240.61499999999796|241.4350000000004|246.64499999999998|247.29999999999927|247.72000000000116|248.33499999999913|248.875|255.91499999999996|257.6749999999993|270|273.53499999999985|280.5750000000007|311.09000000000015__end_report__startTimestamp1447871839732__endTimestamp
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: openingTimes=236.19000000000233,237.59499999999753,238.1800000000003,238.2449999999999,239.78499999999985,239.84000000000015,240.61499999999796,241.4350000000004,246.64499999999998,247.29999999999927,247.72000000000116,248.33499999999913,248.875,255.91499999999996,257.6749999999993,270,273.53499999999985,280.5750000000007,311.09000000000015
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: avgOpenTime:251.67
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: minOpenTime:233.86
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: maxOpenTime:311.09
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: medOpenTime:246.97249999999963
10:37:19 INFO - 2015-11-18 10:37:19,730 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:246.97249999999963
10:37:20 INFO - 2015-11-18 10:37:20,585 INFO : Browser exited with error code: 0
10:37:20 INFO - 2015-11-18 10:37:20,631 INFO : Completed test tpaint (00:00:29)
10:37:20 INFO - 2015-11-18 10:37:20,631 INFO : Starting test sessionrestore
10:37:20 INFO - 2015-11-18 10:37:20,631 DEBUG : operating with platform_type : win_
10:37:20 INFO - 2015-11-18 10:37:20,631 INFO : Initialising browser for sessionrestore test...
10:37:20 INFO - 2015-11-18 10:37:20,648 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/getInfo.html
10:37:25 INFO - 2015-11-18 10:37:25,486 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
10:37:25 INFO - 2015-11-18 10:37:25,486 DEBUG : BROWSER_OUTPUT: colorDepth:24
10:37:25 INFO - 2015-11-18 10:37:25,486 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
10:37:25 INFO - 2015-11-18 10:37:25,486 DEBUG : BROWSER_OUTPUT: __metrics
10:37:25 INFO - 2015-11-18 10:37:25,581 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:37:27 INFO - 2015-11-18 10:37:27,075 INFO : Browser initialized.
10:37:27 INFO - 2015-11-18 10:37:27,075 INFO : Running cycle 1/10 for sessionrestore test...
10:37:27 INFO - 2015-11-18 10:37:27,075 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\tmpblzus3\profile\sessionstore.js
10:37:27 INFO - 2015-11-18 10:37:27,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\tmpblzus3\profile\sessionCheckpoints.json
10:37:27 INFO - 2015-11-18 10:37:27,089 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:31 INFO - 2015-11-18 10:37:31,525 DEBUG : BROWSER_OUTPUT: __start_report2281__end_report
10:37:31 INFO - 2015-11-18 10:37:31,525 DEBUG : BROWSER_OUTPUT:
10:37:31 INFO - 2015-11-18 10:37:31,525 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871851527__endTimestamp
10:37:31 INFO - 2015-11-18 10:37:31,525 DEBUG : BROWSER_OUTPUT:
10:37:33 INFO - 2015-11-18 10:37:33,081 INFO : Browser exited with error code: 0
10:37:33 INFO - 2015-11-18 10:37:33,081 INFO : Running cycle 2/10 for sessionrestore test...
10:37:33 INFO - 2015-11-18 10:37:33,081 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\tmpblzus3\profile\sessionstore.js
10:37:33 INFO - 2015-11-18 10:37:33,081 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\tmpblzus3\profile\sessionCheckpoints.json
10:37:33 INFO - 2015-11-18 10:37:33,081 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:37 INFO - 2015-11-18 10:37:37,095 DEBUG : BROWSER_OUTPUT: __start_report2096__end_report
10:37:37 INFO - 2015-11-18 10:37:37,095 DEBUG : BROWSER_OUTPUT:
10:37:37 INFO - 2015-11-18 10:37:37,095 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871857102__endTimestamp
10:37:37 INFO - 2015-11-18 10:37:37,095 DEBUG : BROWSER_OUTPUT:
10:37:38 INFO - 2015-11-18 10:37:38,589 INFO : Browser exited with error code: 0
10:37:38 INFO - 2015-11-18 10:37:38,589 INFO : Running cycle 3/10 for sessionrestore test...
10:37:38 INFO - 2015-11-18 10:37:38,589 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\tmpblzus3\profile\sessionstore.js
10:37:38 INFO - 2015-11-18 10:37:38,589 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\tmpblzus3\profile\sessionCheckpoints.json
10:37:38 INFO - 2015-11-18 10:37:38,589 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:42 INFO - 2015-11-18 10:37:42,573 DEBUG : BROWSER_OUTPUT: __start_report2072__end_report
10:37:42 INFO - 2015-11-18 10:37:42,573 DEBUG : BROWSER_OUTPUT:
10:37:42 INFO - 2015-11-18 10:37:42,573 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871862582__endTimestamp
10:37:42 INFO - 2015-11-18 10:37:42,573 DEBUG : BROWSER_OUTPUT:
10:37:44 INFO - 2015-11-18 10:37:44,177 INFO : Browser exited with error code: 0
10:37:44 INFO - 2015-11-18 10:37:44,177 INFO : Running cycle 4/10 for sessionrestore test...
10:37:44 INFO - 2015-11-18 10:37:44,177 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\tmpblzus3\profile\sessionstore.js
10:37:44 INFO - 2015-11-18 10:37:44,193 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\tmpblzus3\profile\sessionCheckpoints.json
10:37:44 INFO - 2015-11-18 10:37:44,193 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:48 INFO - 2015-11-18 10:37:48,285 DEBUG : BROWSER_OUTPUT: __start_report2134__end_report
10:37:48 INFO - 2015-11-18 10:37:48,285 DEBUG : BROWSER_OUTPUT:
10:37:48 INFO - 2015-11-18 10:37:48,285 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871868284__endTimestamp
10:37:48 INFO - 2015-11-18 10:37:48,285 DEBUG : BROWSER_OUTPUT:
10:37:49 INFO - 2015-11-18 10:37:49,934 INFO : Browser exited with error code: 0
10:37:49 INFO - 2015-11-18 10:37:49,934 INFO : Running cycle 5/10 for sessionrestore test...
10:37:49 INFO - 2015-11-18 10:37:49,934 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\tmpblzus3\profile\sessionstore.js
10:37:49 INFO - 2015-11-18 10:37:49,934 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\tmpblzus3\profile\sessionCheckpoints.json
10:37:49 INFO - 2015-11-18 10:37:49,934 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:53 INFO - 2015-11-18 10:37:53,934 DEBUG : BROWSER_OUTPUT: __start_report2091__end_report
10:37:53 INFO - 2015-11-18 10:37:53,934 DEBUG : BROWSER_OUTPUT:
10:37:53 INFO - 2015-11-18 10:37:53,934 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871873948__endTimestamp
10:37:53 INFO - 2015-11-18 10:37:53,934 DEBUG : BROWSER_OUTPUT:
10:37:55 INFO - 2015-11-18 10:37:55,584 INFO : Browser exited with error code: 0
10:37:55 INFO - 2015-11-18 10:37:55,584 INFO : Running cycle 6/10 for sessionrestore test...
10:37:55 INFO - 2015-11-18 10:37:55,584 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\tmpblzus3\profile\sessionstore.js
10:37:55 INFO - 2015-11-18 10:37:55,584 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\tmpblzus3\profile\sessionCheckpoints.json
10:37:55 INFO - 2015-11-18 10:37:55,584 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:37:59 INFO - 2015-11-18 10:37:59,568 DEBUG : BROWSER_OUTPUT: __start_report2074__end_report
10:37:59 INFO - 2015-11-18 10:37:59,568 DEBUG : BROWSER_OUTPUT:
10:37:59 INFO - 2015-11-18 10:37:59,568 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871879576__endTimestamp
10:37:59 INFO - 2015-11-18 10:37:59,568 DEBUG : BROWSER_OUTPUT:
10:38:01 INFO - 2015-11-18 10:38:01,233 INFO : Browser exited with error code: 0
10:38:01 INFO - 2015-11-18 10:38:01,233 INFO : Running cycle 7/10 for sessionrestore test...
10:38:01 INFO - 2015-11-18 10:38:01,233 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\tmpblzus3\profile\sessionstore.js
10:38:01 INFO - 2015-11-18 10:38:01,233 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\tmpblzus3\profile\sessionCheckpoints.json
10:38:01 INFO - 2015-11-18 10:38:01,233 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:05 INFO - 2015-11-18 10:38:05,216 DEBUG : BROWSER_OUTPUT: __start_report2076__end_report
10:38:05 INFO - 2015-11-18 10:38:05,216 DEBUG : BROWSER_OUTPUT:
10:38:05 INFO - 2015-11-18 10:38:05,216 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871885221__endTimestamp
10:38:05 INFO - 2015-11-18 10:38:05,216 DEBUG : BROWSER_OUTPUT:
10:38:06 INFO - 2015-11-18 10:38:06,881 INFO : Browser exited with error code: 0
10:38:06 INFO - 2015-11-18 10:38:06,881 INFO : Running cycle 8/10 for sessionrestore test...
10:38:06 INFO - 2015-11-18 10:38:06,881 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\tmpblzus3\profile\sessionstore.js
10:38:06 INFO - 2015-11-18 10:38:06,881 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\tmpblzus3\profile\sessionCheckpoints.json
10:38:06 INFO - 2015-11-18 10:38:06,881 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:10 INFO - 2015-11-18 10:38:10,927 DEBUG : BROWSER_OUTPUT: __start_report2092__end_report
10:38:10 INFO - 2015-11-18 10:38:10,927 DEBUG : BROWSER_OUTPUT:
10:38:10 INFO - 2015-11-18 10:38:10,927 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871890930__endTimestamp
10:38:10 INFO - 2015-11-18 10:38:10,927 DEBUG : BROWSER_OUTPUT:
10:38:12 INFO - 2015-11-18 10:38:12,624 INFO : Browser exited with error code: 0
10:38:12 INFO - 2015-11-18 10:38:12,624 INFO : Running cycle 9/10 for sessionrestore test...
10:38:12 INFO - 2015-11-18 10:38:12,624 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\tmpblzus3\profile\sessionstore.js
10:38:12 INFO - 2015-11-18 10:38:12,624 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\tmpblzus3\profile\sessionCheckpoints.json
10:38:12 INFO - 2015-11-18 10:38:12,624 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:16 INFO - 2015-11-18 10:38:16,638 DEBUG : BROWSER_OUTPUT: __start_report2097__end_report
10:38:16 INFO - 2015-11-18 10:38:16,638 DEBUG : BROWSER_OUTPUT:
10:38:16 INFO - 2015-11-18 10:38:16,638 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871896644__endTimestamp
10:38:16 INFO - 2015-11-18 10:38:16,638 DEBUG : BROWSER_OUTPUT:
10:38:18 INFO - 2015-11-18 10:38:18,319 INFO : Browser exited with error code: 0
10:38:18 INFO - 2015-11-18 10:38:18,319 INFO : Running cycle 10/10 for sessionrestore test...
10:38:18 INFO - 2015-11-18 10:38:18,319 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\tmpblzus3\profile\sessionstore.js
10:38:18 INFO - 2015-11-18 10:38:18,319 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\tmpblzus3\profile\sessionCheckpoints.json
10:38:18 INFO - 2015-11-18 10:38:18,319 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpblzus3\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:22 INFO - 2015-11-18 10:38:22,334 DEBUG : BROWSER_OUTPUT: __start_report2095__end_report
10:38:22 INFO - 2015-11-18 10:38:22,334 DEBUG : BROWSER_OUTPUT:
10:38:22 INFO - 2015-11-18 10:38:22,334 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871902338__endTimestamp
10:38:22 INFO - 2015-11-18 10:38:22,334 DEBUG : BROWSER_OUTPUT:
10:38:24 INFO - 2015-11-18 10:38:24,015 INFO : Browser exited with error code: 0
10:38:24 INFO - 2015-11-18 10:38:24,062 INFO : Completed test sessionrestore (00:01:03)
10:38:24 INFO - 2015-11-18 10:38:24,062 INFO : Starting test sessionrestore_no_auto_restore
10:38:24 INFO - 2015-11-18 10:38:24,062 DEBUG : operating with platform_type : win_
10:38:24 INFO - 2015-11-18 10:38:24,062 INFO : Initialising browser for sessionrestore_no_auto_restore test...
10:38:24 INFO - 2015-11-18 10:38:24,076 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/getInfo.html
10:38:25 INFO - 2015-11-18 10:38:25,867 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
10:38:25 INFO - 2015-11-18 10:38:25,867 DEBUG : BROWSER_OUTPUT: colorDepth:24
10:38:25 INFO - 2015-11-18 10:38:25,867 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
10:38:25 INFO - 2015-11-18 10:38:25,867 DEBUG : BROWSER_OUTPUT: __metrics
10:38:25 INFO - 2015-11-18 10:38:25,881 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
10:38:26 INFO - 2015-11-18 10:38:26,723 INFO : Browser initialized.
10:38:26 INFO - 2015-11-18 10:38:26,723 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test...
10:38:26 INFO - 2015-11-18 10:38:26,723 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\tmpmsb_vf\profile\sessionstore.js
10:38:26 INFO - 2015-11-18 10:38:26,723 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:26 INFO - 2015-11-18 10:38:26,723 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:28 INFO - 2015-11-18 10:38:28,232 DEBUG : BROWSER_OUTPUT: __start_report991__end_report
10:38:28 INFO - 2015-11-18 10:38:28,232 DEBUG : BROWSER_OUTPUT:
10:38:28 INFO - 2015-11-18 10:38:28,232 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871908245__endTimestamp
10:38:28 INFO - 2015-11-18 10:38:28,232 DEBUG : BROWSER_OUTPUT:
10:38:28 INFO - 2015-11-18 10:38:28,979 INFO : Browser exited with error code: 0
10:38:28 INFO - 2015-11-18 10:38:28,979 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test...
10:38:28 INFO - 2015-11-18 10:38:28,979 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\tmpmsb_vf\profile\sessionstore.js
10:38:28 INFO - 2015-11-18 10:38:28,979 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:28 INFO - 2015-11-18 10:38:28,979 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:30 INFO - 2015-11-18 10:38:30,115 DEBUG : BROWSER_OUTPUT: __start_report781__end_report
10:38:30 INFO - 2015-11-18 10:38:30,115 DEBUG : BROWSER_OUTPUT:
10:38:30 INFO - 2015-11-18 10:38:30,115 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871910121__endTimestamp
10:38:30 INFO - 2015-11-18 10:38:30,115 DEBUG : BROWSER_OUTPUT:
10:38:30 INFO - 2015-11-18 10:38:30,846 INFO : Browser exited with error code: 0
10:38:30 INFO - 2015-11-18 10:38:30,846 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test...
10:38:30 INFO - 2015-11-18 10:38:30,846 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\tmpmsb_vf\profile\sessionstore.js
10:38:30 INFO - 2015-11-18 10:38:30,846 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:30 INFO - 2015-11-18 10:38:30,846 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:31 INFO - 2015-11-18 10:38:31,983 DEBUG : BROWSER_OUTPUT: __start_report783__end_report
10:38:31 INFO - 2015-11-18 10:38:31,983 DEBUG : BROWSER_OUTPUT:
10:38:31 INFO - 2015-11-18 10:38:31,983 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871911990__endTimestamp
10:38:31 INFO - 2015-11-18 10:38:31,983 DEBUG : BROWSER_OUTPUT:
10:38:32 INFO - 2015-11-18 10:38:32,760 INFO : Browser exited with error code: 0
10:38:32 INFO - 2015-11-18 10:38:32,760 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test...
10:38:32 INFO - 2015-11-18 10:38:32,760 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\tmpmsb_vf\profile\sessionstore.js
10:38:32 INFO - 2015-11-18 10:38:32,760 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:32 INFO - 2015-11-18 10:38:32,760 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:33 INFO - 2015-11-18 10:38:33,897 DEBUG : BROWSER_OUTPUT: __start_report781__end_report
10:38:33 INFO - 2015-11-18 10:38:33,897 DEBUG : BROWSER_OUTPUT:
10:38:33 INFO - 2015-11-18 10:38:33,897 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871913905__endTimestamp
10:38:33 INFO - 2015-11-18 10:38:33,897 DEBUG : BROWSER_OUTPUT:
10:38:34 INFO - 2015-11-18 10:38:34,720 INFO : Browser exited with error code: 0
10:38:34 INFO - 2015-11-18 10:38:34,720 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test...
10:38:34 INFO - 2015-11-18 10:38:34,720 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\tmpmsb_vf\profile\sessionstore.js
10:38:34 INFO - 2015-11-18 10:38:34,720 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:34 INFO - 2015-11-18 10:38:34,720 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:35 INFO - 2015-11-18 10:38:35,872 DEBUG : BROWSER_OUTPUT: __start_report807__end_report
10:38:35 INFO - 2015-11-18 10:38:35,872 DEBUG : BROWSER_OUTPUT:
10:38:35 INFO - 2015-11-18 10:38:35,872 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871915892__endTimestamp
10:38:35 INFO - 2015-11-18 10:38:35,872 DEBUG : BROWSER_OUTPUT:
10:38:36 INFO - 2015-11-18 10:38:36,729 INFO : Browser exited with error code: 0
10:38:36 INFO - 2015-11-18 10:38:36,729 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test...
10:38:36 INFO - 2015-11-18 10:38:36,729 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\tmpmsb_vf\profile\sessionstore.js
10:38:36 INFO - 2015-11-18 10:38:36,729 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:36 INFO - 2015-11-18 10:38:36,729 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:37 INFO - 2015-11-18 10:38:37,880 DEBUG : BROWSER_OUTPUT: __start_report784__end_report
10:38:37 INFO - 2015-11-18 10:38:37,880 DEBUG : BROWSER_OUTPUT:
10:38:37 INFO - 2015-11-18 10:38:37,880 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871917894__endTimestamp
10:38:37 INFO - 2015-11-18 10:38:37,880 DEBUG : BROWSER_OUTPUT:
10:38:38 INFO - 2015-11-18 10:38:38,736 INFO : Browser exited with error code: 0
10:38:38 INFO - 2015-11-18 10:38:38,752 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test...
10:38:38 INFO - 2015-11-18 10:38:38,752 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\tmpmsb_vf\profile\sessionstore.js
10:38:38 INFO - 2015-11-18 10:38:38,752 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:38 INFO - 2015-11-18 10:38:38,752 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:39 INFO - 2015-11-18 10:38:39,871 DEBUG : BROWSER_OUTPUT: __start_report788__end_report
10:38:39 INFO - 2015-11-18 10:38:39,871 DEBUG : BROWSER_OUTPUT:
10:38:39 INFO - 2015-11-18 10:38:39,871 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871919884__endTimestamp
10:38:39 INFO - 2015-11-18 10:38:39,871 DEBUG : BROWSER_OUTPUT:
10:38:40 INFO - 2015-11-18 10:38:40,713 INFO : Browser exited with error code: 0
10:38:40 INFO - 2015-11-18 10:38:40,713 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test...
10:38:40 INFO - 2015-11-18 10:38:40,713 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\tmpmsb_vf\profile\sessionstore.js
10:38:40 INFO - 2015-11-18 10:38:40,713 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:40 INFO - 2015-11-18 10:38:40,713 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:41 INFO - 2015-11-18 10:38:41,880 DEBUG : BROWSER_OUTPUT: __start_report801__end_report
10:38:41 INFO - 2015-11-18 10:38:41,880 DEBUG : BROWSER_OUTPUT:
10:38:41 INFO - 2015-11-18 10:38:41,880 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871921889__endTimestamp
10:38:41 INFO - 2015-11-18 10:38:41,880 DEBUG : BROWSER_OUTPUT:
10:38:42 INFO - 2015-11-18 10:38:42,766 INFO : Browser exited with error code: 0
10:38:42 INFO - 2015-11-18 10:38:42,766 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test...
10:38:42 INFO - 2015-11-18 10:38:42,766 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\tmpmsb_vf\profile\sessionstore.js
10:38:42 INFO - 2015-11-18 10:38:42,766 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:42 INFO - 2015-11-18 10:38:42,766 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:43 INFO - 2015-11-18 10:38:43,948 DEBUG : BROWSER_OUTPUT: __start_report815__end_report
10:38:43 INFO - 2015-11-18 10:38:43,948 DEBUG : BROWSER_OUTPUT:
10:38:43 INFO - 2015-11-18 10:38:43,948 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871923965__endTimestamp
10:38:43 INFO - 2015-11-18 10:38:43,948 DEBUG : BROWSER_OUTPUT:
10:38:44 INFO - 2015-11-18 10:38:44,835 INFO : Browser exited with error code: 0
10:38:44 INFO - 2015-11-18 10:38:44,835 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test...
10:38:44 INFO - 2015-11-18 10:38:44,835 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\tmpmsb_vf\profile\sessionstore.js
10:38:44 INFO - 2015-11-18 10:38:44,835 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\tmpmsb_vf\profile\sessionCheckpoints.json
10:38:44 INFO - 2015-11-18 10:38:44,835 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpmsb_vf\profile http://localhost:1631/startup_test/sessionrestore/index.html
10:38:45 INFO - 2015-11-18 10:38:45,987 DEBUG : BROWSER_OUTPUT: __start_report800__end_report
10:38:45 INFO - 2015-11-18 10:38:45,987 DEBUG : BROWSER_OUTPUT:
10:38:45 INFO - 2015-11-18 10:38:45,987 DEBUG : BROWSER_OUTPUT: __startTimestamp1447871925997__endTimestamp
10:38:45 INFO - 2015-11-18 10:38:45,987 DEBUG : BROWSER_OUTPUT:
10:38:46 INFO - 2015-11-18 10:38:46,844 INFO : Browser exited with error code: 0
10:38:46 INFO - 2015-11-18 10:38:46,891 INFO : Completed test sessionrestore_no_auto_restore (00:00:22)
10:38:47 INFO - 2015-11-18 10:38:47,372 INFO : Completed test suite (00:04:23)
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Working with test: a11yr
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Generating results file: a11yr
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Working with test: ts_paint
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Generating results file: ts_paint
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Working with test: tpaint
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Generating results file: tpaint
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Working with test: sessionrestore
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Generating results file: sessionrestore
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Working with test: sessionrestore_no_auto_restore
10:38:47 INFO - 2015-11-18 10:38:47,372 DEBUG : Generating results file: sessionrestore_no_auto_restore
10:38:47 INFO - 2015-11-18 10:38:47,372 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
10:38:47 INFO - 2015-11-18 10:38:47,420 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,420 DEBUG : process_Request line: a11yr_paint 694.67 graph.html#tests=[[223,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,420 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
10:38:47 INFO - 2015-11-18 10:38:47,466 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,466 DEBUG : process_Request line: ts_paint 1052.74 graph.html#tests=[[83,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,466 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
10:38:47 INFO - 2015-11-18 10:38:47,497 DEBUG : process_Request line: tpaint graph.html#tests=[[82,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,497 DEBUG : process_Request line: tpaint 248.33 graph.html#tests=[[82,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,497 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
10:38:47 INFO - 2015-11-18 10:38:47,543 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,543 DEBUG : process_Request line: sessionrestore 2110.01 graph.html#tests=[[313,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,543 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
10:38:47 INFO - 2015-11-18 10:38:47,591 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,591 DEBUG : process_Request line: sessionrestore_no_auto_restore 811.10 graph.html#tests=[[315,132,37]]
10:38:47 INFO - 2015-11-18 10:38:47,591 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1296.0, 1451.0, 1510.0, 1375.0, 1367.0, 1364.0, 1472.0, 1461.0, 1458.0, 1366.0, 1467.0, 1419.0, 1492.0, 1492.0, 1489.0, 1471.0, 1380.0, 1478.0, 1389.0, 1367.0, 1393.0, 1374.0, 1512.0, 1473.0, 1383.0], "unit": "ms", "name": "dhtml.html", "value": 1454.5}, {"lowerIsBetter": true, "replicates": [339.0, 328.0, 336.0, 314.0, 328.0, 339.0, 319.0, 340.0, 329.0, 330.0, 341.0, 328.0, 338.0, 333.0, 332.0, 325.0, 332.0, 339.0, 329.0, 329.0, 352.0, 327.0, 332.0, 331.0, 336.0], "unit": "ms", "name": "tablemutation.html", "value": 331.5}], "name": "a11yr", "value": 694.6678445925179}, {"subtests": [{"replicates": [1431.0, 1032.0, 1052.0, 1041.0, 1041.0, 1030.0, 1056.0, 1015.0, 1026.0, 1019.0, 1064.0, 1026.0, 1030.0, 1031.0, 1035.0, 1033.0, 1022.0, 1029.0, 1056.0, 1045.0], "name": "ts_paint", "value": 1032.0}], "name": "ts_paint", "value": 1032.0}, {"subtests": [{"replicates": [233.85500000000002, 236.19000000000233, 237.59499999999753, 238.1800000000003, 238.2449999999999, 239.78499999999985, 239.84000000000015, 240.61499999999796, 241.4350000000004, 246.64499999999998, 247.29999999999927, 247.72000000000116, 248.33499999999913, 248.875, 255.91499999999996, 257.6749999999993, 270.0, 273.53499999999985, 280.5750000000007, 311.09000000000015], "name": "tpaint", "value": 248.33499999999913}], "name": "tpaint", "value": 248.33499999999913}, {"subtests": [{"replicates": [2281.0, 2096.0, 2072.0, 2134.0, 2091.0, 2074.0, 2076.0, 2092.0, 2097.0, 2095.0], "name": "sessionrestore", "value": 2092.0}], "name": "sessionrestore", "value": 2092.0}, {"subtests": [{"replicates": [991.0, 781.0, 783.0, 781.0, 807.0, 784.0, 788.0, 801.0, 815.0, 800.0], "name": "sessionrestore_no_auto_restore", "value": 788.0}], "name": "sessionrestore_no_auto_restore", "value": 788.0}]}
10:38:47 INFO - RETURN: a11yr_paint: 694.67
10:38:47 INFO - RETURN: ts_paint: 1052.74
10:38:47 INFO - RETURN: tpaint: 248.33
10:38:47 INFO - RETURN: sessionrestore: 2110.01
10:38:47 INFO - RETURN: sessionrestore_no_auto_restore: 811.10
10:38:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,132,37]]", "result": "2110.01"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,132,37]]", "result": "694.67"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,132,37]]", "result": "248.33"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,132,37]]", "result": "1052.74"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,132,37]]", "result": "811.10"}}}
10:38:47 INFO - Return code: 0
10:38:47 INFO - # TBPL SUCCESS #
10:38:47 INFO - Running post-action listener: _resource_record_post_action
10:38:47 INFO - Running post-run listener: _resource_record_post_run
10:38:48 INFO - Total resource usage - Wall time: 267s; CPU: 11.0%; Read bytes: 47648768; Write bytes: 275789824; Read time: 252421; Write time: 342433
10:38:48 INFO - install - Wall time: 3s; CPU: 10.0%; Read bytes: 0; Write bytes: 60660736; Read time: 0; Write time: 65044
10:38:48 INFO - run-tests - Wall time: 265s; CPU: 11.0%; Read bytes: 47648768; Write bytes: 184312320; Read time: 252421; Write time: 248418
10:38:48 INFO - Running post-run listener: _upload_blobber_files
10:38:48 INFO - Blob upload gear active.
10:38:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
10:38:48 INFO - Copying logs to upload dir...
10:38:48 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=369.537000
========= master_lag: 2.67 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 6 mins, 12 secs) (at 2015-11-18 10:38:47.952580) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 10:38:47.953370) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.109000
build_url: 'https://queue.taskcluster.net/v1/task/SkxLiZ9YRKOqRI8-J5HSOg/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-18 10:38:48.076259) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:38:48.076579) =========
'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-142\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-142
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-142
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-142
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-142
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-142
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.109000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 10:38:48.205044) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-18 10:38:48.205372) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-18 10:38:48.671418) =========
========= Total master_lag: 2.93 =========