builder: fx-team_xp-ix_test-other
slave: t-xp32-ix-119
starttime: 1447900297.26
results: success (0)
buildid: 20151118163241
builduid: fc82e22211fb45a5a86b7ecc34c7daeb
revision: 42a976b770f1b1e54133ee0383940013ded058eb
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.262860) =========
master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.263485) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.263961) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test
program finished with exit code 0
elapsedTime=0.218000
basedir: 'C:\\slave\\test'
========= master_lag: 0.05 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.536539) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.536881) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.584749) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.585187) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.326000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.927991) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.928595) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.929426) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:37.930154) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
--18:31:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.02 GB/s
18:31:40 (2.02 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.575000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:38.523964) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-11-18 18:31:38.524265) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=2.283000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-11-18 18:31:40.839296) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-18 18:31:40.839651) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 42a976b770f1b1e54133ee0383940013ded058eb --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 42a976b770f1b1e54133ee0383940013ded058eb --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\Documents and Settings\All Users
APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
2015-11-18 18:31:43,240 truncating revision to first 12 chars
2015-11-18 18:31:43,240 Setting DEBUG logging.
2015-11-18 18:31:43,240 attempt 1/10
2015-11-18 18:31:43,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/42a976b770f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2015-11-18 18:31:44,187 unpacking tar archive at: fx-team-42a976b770f1/testing/mozharness/
program finished with exit code 0
elapsedTime=1.770000
========= master_lag: 0.65 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-11-18 18:31:43.263988) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:43.264316) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:43.350050) =========
========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:43.350410) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-18 18:31:43.350967) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 7 mins, 33 secs) (at 2015-11-18 18:31:43.351266) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
18:31:45 INFO - MultiFileLogger online at 20151118 18:31:45 in C:\slave\test
18:31:45 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
18:31:45 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
18:31:45 INFO - {'append_to_log': False,
18:31:45 INFO - 'base_work_dir': 'C:\\slave\\test',
18:31:45 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO',
18:31:45 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
18:31:45 INFO - 'branch': 'Fx-Team-Non-PGO',
18:31:45 INFO - 'buildbot_json_path': 'buildprops.json',
18:31:45 INFO - 'config_files': ('talos/windows_config.py',),
18:31:45 INFO - 'default_actions': ('clobber',
18:31:45 INFO - 'read-buildbot-config',
18:31:45 INFO - 'download-and-extract',
18:31:45 INFO - 'populate-webroot',
18:31:45 INFO - 'create-virtualenv',
18:31:45 INFO - 'install',
18:31:45 INFO - 'run-tests'),
18:31:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
18:31:45 INFO - 'download_minidump_stackwalk': True,
18:31:45 INFO - 'download_symbols': 'ondemand',
18:31:45 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
18:31:45 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
18:31:45 INFO - 'hg': 'c:/mozilla-build/hg/hg',
18:31:45 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
18:31:45 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
18:31:45 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
18:31:45 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
18:31:45 INFO - 'C:/mozilla-build/tooltool.py'),
18:31:45 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
18:31:45 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
18:31:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
18:31:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
18:31:45 INFO - 'installer_path': 'installer.exe',
18:31:45 INFO - 'log_level': 'info',
18:31:45 INFO - 'log_name': 'talos',
18:31:45 INFO - 'log_to_console': True,
18:31:45 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
18:31:45 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
18:31:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
18:31:45 INFO - 'opt_config_files': (),
18:31:45 INFO - 'pip_index': False,
18:31:45 INFO - 'sps_profile': False,
18:31:45 INFO - 'sps_profile_interval': 0,
18:31:45 INFO - 'suite': 'other',
18:31:45 INFO - 'system_bits': '32',
18:31:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
18:31:45 INFO - 'title': 't-xp32-ix-119',
18:31:45 INFO - 'use_talos_json': True,
18:31:45 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
18:31:45 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
18:31:45 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
18:31:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
18:31:45 INFO - 'work_dir': 'build'}
18:31:45 INFO - #####
18:31:45 INFO - ##### Running clobber step.
18:31:45 INFO - #####
18:31:45 INFO - Running pre-action listener: _resource_record_pre_action
18:31:45 INFO - Running main action method: clobber
18:31:45 INFO - rmtree: C:\slave\test\build
18:31:45 INFO - Using _rmtree_windows ...
18:31:45 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
18:32:50 INFO - Running post-action listener: _resource_record_post_action
18:32:50 INFO - #####
18:32:50 INFO - ##### Running read-buildbot-config step.
18:32:50 INFO - #####
18:32:50 INFO - Running pre-action listener: _resource_record_pre_action
18:32:50 INFO - Running main action method: read_buildbot_config
18:32:50 INFO - Using buildbot properties:
18:32:50 INFO - {
18:32:50 INFO - "properties": {
18:32:50 INFO - "buildnumber": 201,
18:32:50 INFO - "product": "firefox",
18:32:50 INFO - "script_repo_revision": "production",
18:32:50 INFO - "builddir": "fx-team_xp-ix_test-other",
18:32:50 INFO - "repository": "",
18:32:50 INFO - "buildername": "Windows XP 32-bit fx-team talos other",
18:32:50 INFO - "buildid": "20151118163241",
18:32:50 INFO - "slavename": "t-xp32-ix-119",
18:32:50 INFO - "pgo_build": "False",
18:32:50 INFO - "basedir": "C:\\slave\\test",
18:32:50 INFO - "project": "",
18:32:50 INFO - "platform": "xp-ix",
18:32:50 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/",
18:32:50 INFO - "slavebuilddir": "test",
18:32:50 INFO - "scheduler": "tests-fx-team-win32-talos",
18:32:50 INFO - "branch": "fx-team",
18:32:50 INFO - "repo_path": "integration/fx-team",
18:32:50 INFO - "stage_platform": "win32",
18:32:50 INFO - "builduid": "fc82e22211fb45a5a86b7ecc34c7daeb",
18:32:50 INFO - "revision": "42a976b770f1b1e54133ee0383940013ded058eb"
18:32:50 INFO - },
18:32:50 INFO - "sourcestamp": {
18:32:50 INFO - "repository": "",
18:32:50 INFO - "hasPatch": false,
18:32:50 INFO - "project": "",
18:32:50 INFO - "branch": "fx-team-win32-talos",
18:32:50 INFO - "changes": [
18:32:50 INFO - {
18:32:50 INFO - "category": null,
18:32:50 INFO - "files": [
18:32:50 INFO - {
18:32:50 INFO - "url": null,
18:32:50 INFO - "name": "https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip"
18:32:50 INFO - }
18:32:50 INFO - ],
18:32:50 INFO - "repository": "",
18:32:50 INFO - "rev": "42a976b770f1b1e54133ee0383940013ded058eb",
18:32:50 INFO - "who": "kwierso@gmail.com",
18:32:50 INFO - "when": 1447900239,
18:32:50 INFO - "number": 6680587,
18:32:50 INFO - "comments": "Backed out 8 changesets (bug 1171708) for browser_354894_perwindowpb.js failures CLOSED TREE\n\nBacked out changeset a1ac4564029c (bug 1171708)\nBacked out changeset 9f55b32f4440 (bug 1171708)\nBacked out changeset 7031b88f0200 (bug 1171708)\nBacked out changeset c4b23403e71f (bug 1171708)\nBacked out changeset 32196b22b454 (bug 1171708)\nBacked out changeset 4778fe17087a (bug 1171708)\nBacked out changeset 451741a8b932 (bug 1171708)\nBacked out changeset 82c9750d88e2 (bug 1171708)",
18:32:50 INFO - "project": "",
18:32:50 INFO - "at": "Wed 18 Nov 2015 18:30:39",
18:32:50 INFO - "branch": "fx-team-win32-talos",
18:32:50 INFO - "revlink": "",
18:32:50 INFO - "properties": [
18:32:50 INFO - [
18:32:50 INFO - "buildid",
18:32:50 INFO - "20151118163241",
18:32:50 INFO - "Change"
18:32:50 INFO - ],
18:32:50 INFO - [
18:32:50 INFO - "builduid",
18:32:50 INFO - "fc82e22211fb45a5a86b7ecc34c7daeb",
18:32:50 INFO - "Change"
18:32:50 INFO - ],
18:32:50 INFO - [
18:32:50 INFO - "pgo_build",
18:32:50 INFO - "False",
18:32:50 INFO - "Change"
18:32:50 INFO - ]
18:32:50 INFO - ],
18:32:50 INFO - "revision": "42a976b770f1b1e54133ee0383940013ded058eb"
18:32:50 INFO - }
18:32:50 INFO - ],
18:32:50 INFO - "revision": "42a976b770f1b1e54133ee0383940013ded058eb"
18:32:50 INFO - }
18:32:50 INFO - }
18:32:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip.
18:32:50 INFO - Running post-action listener: _resource_record_post_action
18:32:50 INFO - #####
18:32:50 INFO - ##### Running download-and-extract step.
18:32:50 INFO - #####
18:32:50 INFO - Running pre-action listener: _resource_record_pre_action
18:32:50 INFO - Running main action method: download_and_extract
18:32:50 INFO - mkdir: C:\slave\test\build\tests
18: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')]}
18:32:50 INFO - https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net
18:32:50 INFO - trying https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/test_packages.json
18:32:50 INFO - Downloading https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json
18:32:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1
18:32:51 INFO - Downloaded 1221 bytes.
18:32:51 INFO - Reading from file C:\slave\test\build\test_packages.json
18:32:51 INFO - Using the following test package requirements:
18:32:51 INFO - {u'common': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
18:32:51 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.cppunittest.tests.zip'],
18:32:51 INFO - u'jittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'jsshell-win32.zip'],
18:32:51 INFO - u'mochitest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.mochitest.tests.zip'],
18:32:51 INFO - u'mozbase': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
18:32:51 INFO - u'reftest': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.reftest.tests.zip'],
18:32:51 INFO - u'talos': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.talos.tests.zip'],
18:32:51 INFO - u'web-platform': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.web-platform.tests.zip'],
18:32:51 INFO - u'webapprt': [u'firefox-45.0a1.en-US.win32.common.tests.zip'],
18:32:51 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.win32.common.tests.zip',
18:32:51 INFO - u'firefox-45.0a1.en-US.win32.xpcshell.tests.zip']}
18:32:51 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip'] for test suite category: common
18:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:32:51 INFO - https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
18:32:51 INFO - trying https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
18:32:51 INFO - Downloading https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:32:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:32:53 INFO - Downloaded 18033107 bytes.
18:32:53 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
18:32:53 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
18:32:53 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
18:33:29 INFO - Return code: 0
18:33:29 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
18:33:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:33:29 INFO - https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
18:33:29 INFO - trying https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip
18:33:29 INFO - Downloading https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:33:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:33:31 INFO - Downloaded 18033107 bytes.
18:33:31 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
18:33:31 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip
18:33:31 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760
18:33:57 INFO - Return code: 0
18:33:57 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')]}
18:33:57 INFO - https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net
18:33:57 INFO - trying https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip
18:33:57 INFO - Downloading https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:34:00 INFO - Downloaded 11130997 bytes.
18:34:00 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
18:34:00 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip
18:34:00 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760
18:34:01 INFO - Return code: 0
18:34:01 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')]}
18:34:01 INFO - https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip matches https://queue.taskcluster.net
18:34:01 INFO - trying https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
18:34:01 INFO - Downloading https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.zip
18:34:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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
18:34:06 INFO - Downloaded 51671083 bytes.
18:34:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
18:34:06 INFO - mkdir: C:\slave\test\properties
18:34:06 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
18:34:06 INFO - Writing to file C:\slave\test\properties\build_url
18:34:06 INFO - Contents:
18:34:06 INFO - build_url:https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
18:34:06 INFO - Running post-action listener: _resource_record_post_action
18:34:06 INFO - Running post-action listener: set_extra_try_arguments
18:34:06 INFO - #####
18:34:06 INFO - ##### Running populate-webroot step.
18:34:06 INFO - #####
18:34:06 INFO - Running pre-action listener: _resource_record_pre_action
18:34:06 INFO - Running main action method: populate_webroot
18:34:06 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
18:34:06 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
18:34:06 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs',
18:34:06 INFO - u'../fennec_ids.txt'],
18:34:06 INFO - u'tests': [u'tcheck2']},
18:34:06 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
18:34:06 INFO - u'--tppagecycles',
18:34:06 INFO - u'7'],
18:34:06 INFO - u'tests': [u'tsvgm']}},
18:34:06 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
18:34:06 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'tresize', u'tcanvasmark']},
18:34:06 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
18:34:06 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'dromaeo_css',
18:34:06 INFO - u'kraken',
18:34:06 INFO - u'v8_7']},
18:34:06 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:34:06 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
18:34:06 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'tests': [u'damp', u'tps']},
18:34:06 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'damp', u'tps']},
18:34:06 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
18:34:06 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
18:34:06 INFO - u'other': {u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'other_l64': {u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'other_nol64': {u'tests': [u'a11yr',
18:34:06 INFO - u'ts_paint',
18:34:06 INFO - u'tpaint',
18:34:06 INFO - u'sessionrestore',
18:34:06 INFO - u'sessionrestore_no_auto_restore']},
18:34:06 INFO - u'svgr': {u'tests': [u'tsvgx',
18:34:06 INFO - u'tsvgr_opacity',
18:34:06 INFO - u'tart',
18:34:06 INFO - u'tscrollx',
18:34:06 INFO - u'cart']},
18:34:06 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'tsvgx',
18:34:06 INFO - u'tsvgr_opacity',
18:34:06 INFO - u'tart',
18:34:06 INFO - u'tscrollx',
18:34:06 INFO - u'cart']},
18:34:06 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'tests': [u'tp5o']},
18:34:06 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'talos_options': [u'--e10s'],
18:34:06 INFO - u'tests': [u'tp5o']},
18:34:06 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'talos_options': [u'--xperf_path',
18:34:06 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:34:06 INFO - u'tests': [u'tp5n']},
18:34:06 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
18:34:06 INFO - u'talos_options': [u'--e10s',
18:34:06 INFO - u'--xperf_path',
18:34:06 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
18:34:06 INFO - u'tests': [u'tp5n']}},
18:34:06 INFO - u'talos.zip': {u'path': u'',
18:34:06 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
18:34:06 INFO - Running post-action listener: _resource_record_post_action
18:34:06 INFO - #####
18:34:06 INFO - ##### Running create-virtualenv step.
18:34:06 INFO - #####
18:34:06 INFO - Running pre-action listener: _resource_record_pre_action
18:34:06 INFO - Running main action method: create_virtualenv
18:34:06 INFO - Creating virtualenv C:\slave\test\build/venv
18:34:06 INFO - mkdir: C:\slave\test\build\venv\Scripts
18:34:06 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
18:34:06 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'!
18:34:06 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
18:34:06 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
18:34:07 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
18:34:08 INFO - Installing distribute......................................................................................................................................................................................done.
18:34:09 INFO - Return code: 0
18:34:09 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
18:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:09 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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:09 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
18:34:09 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
18:34:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:09 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:09 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:09 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:09 INFO - 'DCLOCATION': 'SCL3',
18:34:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:09 INFO - 'HOMEDRIVE': 'C:',
18:34:09 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:09 INFO - 'KTS_VERSION': '1.19c',
18:34:09 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:09 INFO - 'MOZ_NO_REMOTE': '1',
18:34:09 INFO - 'NO_EM_RESTART': '1',
18:34:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:09 INFO - 'OS': 'Windows_NT',
18:34:09 INFO - 'OURDRIVE': 'C:',
18:34:09 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;',
18:34:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:09 INFO - 'PROCESSOR_LEVEL': '6',
18:34:09 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:09 INFO - 'PROMPT': '$P$G',
18:34:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:09 INFO - 'PWD': 'C:\\slave\\test',
18:34:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:09 INFO - 'SESSIONNAME': 'Console',
18:34:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:09 INFO - 'SYSTEMDRIVE': 'C:',
18:34:09 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:09 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:09 INFO - 'TEST1': 'testie',
18:34:09 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:09 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:09 INFO - 'USERNAME': 'cltbld',
18:34:09 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:09 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:10 INFO - Ignoring indexes: http://pypi.python.org/simple/
18:34:10 INFO - Downloading/unpacking pip>=1.5
18:34:10 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache
18:34:15 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
18:34:15 INFO - Running setup.py egg_info for package pip
18:34:15 INFO - warning: no files found matching 'pip\cacert.pem'
18:34:15 INFO - warning: no files found matching '*.html' under directory 'docs'
18:34:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:34:15 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:34:15 INFO - Installing collected packages: pip
18:34:15 INFO - Found existing installation: pip 0.8.2
18:34:15 INFO - Uninstalling pip:
18:34:15 INFO - Successfully uninstalled pip
18:34:15 INFO - Running setup.py install for pip
18:34:15 INFO - warning: no files found matching 'pip\cacert.pem'
18:34:15 INFO - warning: no files found matching '*.html' under directory 'docs'
18:34:15 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
18:34:15 INFO - no previously-included directories found matching 'docs\_build\_sources'
18:34:15 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
18:34:15 INFO - Successfully installed pip
18:34:15 INFO - Cleaning up...
18:34:15 INFO - Return code: 0
18:34:15 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv
18:34:15 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')]}
18:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34:15 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')]}
18:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:15 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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:15 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
18:34:15 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
18:34:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:15 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:15 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:15 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:15 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:15 INFO - 'DCLOCATION': 'SCL3',
18:34:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:15 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:15 INFO - 'HOMEDRIVE': 'C:',
18:34:15 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:15 INFO - 'KTS_VERSION': '1.19c',
18:34:15 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:15 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:15 INFO - 'MOZ_NO_REMOTE': '1',
18:34:15 INFO - 'NO_EM_RESTART': '1',
18:34:15 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:15 INFO - 'OS': 'Windows_NT',
18:34:15 INFO - 'OURDRIVE': 'C:',
18:34:15 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;',
18:34:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:15 INFO - 'PROCESSOR_LEVEL': '6',
18:34:15 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:15 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:15 INFO - 'PROMPT': '$P$G',
18:34:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:15 INFO - 'PWD': 'C:\\slave\\test',
18:34:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:15 INFO - 'SESSIONNAME': 'Console',
18:34:15 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:15 INFO - 'SYSTEMDRIVE': 'C:',
18:34:15 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:15 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:15 INFO - 'TEST1': 'testie',
18:34:15 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:15 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:15 INFO - 'USERNAME': 'cltbld',
18:34:15 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:15 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:15 INFO - Downloading/unpacking psutil>=0.7.1
18:34:16 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
18:34:16 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
18:34:16 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
18:34:16 INFO - Installing collected packages: psutil
18:34:17 INFO - Successfully installed psutil
18:34:17 INFO - Cleaning up...
18:34:17 INFO - Return code: 0
18:34:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv
18:34:17 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')]}
18:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34:17 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')]}
18:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:17 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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:17 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
18:34:17 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
18:34:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:17 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:17 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:17 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:17 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:17 INFO - 'DCLOCATION': 'SCL3',
18:34:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:17 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:17 INFO - 'HOMEDRIVE': 'C:',
18:34:17 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:17 INFO - 'KTS_VERSION': '1.19c',
18:34:17 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:17 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:17 INFO - 'MOZ_NO_REMOTE': '1',
18:34:17 INFO - 'NO_EM_RESTART': '1',
18:34:17 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:17 INFO - 'OS': 'Windows_NT',
18:34:17 INFO - 'OURDRIVE': 'C:',
18:34:17 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;',
18:34:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:17 INFO - 'PROCESSOR_LEVEL': '6',
18:34:17 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:17 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:17 INFO - 'PROMPT': '$P$G',
18:34:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:17 INFO - 'PWD': 'C:\\slave\\test',
18:34:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:17 INFO - 'SESSIONNAME': 'Console',
18:34:17 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:17 INFO - 'SYSTEMDRIVE': 'C:',
18:34:17 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:17 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:17 INFO - 'TEST1': 'testie',
18:34:17 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:17 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:17 INFO - 'USERNAME': 'cltbld',
18:34:17 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:17 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
18: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
18: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
18:34:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz
18:34:18 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
18:34:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
18:34:18 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)
18:34:18 INFO - Installing collected packages: mozsystemmonitor
18:34:18 INFO - Running setup.py install for mozsystemmonitor
18:34:19 INFO - Successfully installed mozsystemmonitor
18:34:19 INFO - Cleaning up...
18:34:19 INFO - Return code: 0
18:34:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
18: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')]}
18:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18: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')]}
18:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18: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', '--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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:19 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
18:34:19 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
18:34:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:19 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:19 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:19 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:19 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:19 INFO - 'DCLOCATION': 'SCL3',
18:34:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:19 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:19 INFO - 'HOMEDRIVE': 'C:',
18:34:19 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:19 INFO - 'KTS_VERSION': '1.19c',
18:34:19 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:19 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:19 INFO - 'MOZ_NO_REMOTE': '1',
18:34:19 INFO - 'NO_EM_RESTART': '1',
18:34:19 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:19 INFO - 'OS': 'Windows_NT',
18:34:19 INFO - 'OURDRIVE': 'C:',
18: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;',
18:34:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:19 INFO - 'PROCESSOR_LEVEL': '6',
18:34:19 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:19 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:19 INFO - 'PROMPT': '$P$G',
18:34:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:19 INFO - 'PWD': 'C:\\slave\\test',
18:34:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:19 INFO - 'SESSIONNAME': 'Console',
18:34:19 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:19 INFO - 'SYSTEMDRIVE': 'C:',
18:34:19 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:19 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:19 INFO - 'TEST1': 'testie',
18:34:19 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:19 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:19 INFO - 'USERNAME': 'cltbld',
18:34:19 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:19 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:19 INFO - Downloading/unpacking blobuploader==1.2.4
18:34:19 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
18:34:19 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
18:34:20 INFO - Downloading blobuploader-1.2.4.tar.gz
18:34:20 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
18:34:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
18:34:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
18:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
18:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
18:34:20 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
18:34:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
18:34:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
18:34:21 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
18:34:21 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
18:34:21 INFO - Downloading docopt-0.6.1.tar.gz
18:34:21 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
18:34:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
18:34:21 INFO - Installing collected packages: blobuploader, requests, docopt
18:34:21 INFO - Running setup.py install for blobuploader
18:34:21 INFO - Running setup.py install for requests
18:34:22 INFO - Running setup.py install for docopt
18:34:23 INFO - Successfully installed blobuploader requests docopt
18:34:23 INFO - Cleaning up...
18:34:23 INFO - Return code: 0
18:34:23 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:34: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')]}
18:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34: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')]}
18:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:23 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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:23 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
18:34:23 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
18:34:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:23 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:23 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:23 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:23 INFO - 'DCLOCATION': 'SCL3',
18:34:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:23 INFO - 'HOMEDRIVE': 'C:',
18:34:23 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:23 INFO - 'KTS_VERSION': '1.19c',
18:34:23 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:23 INFO - 'MOZ_NO_REMOTE': '1',
18:34:23 INFO - 'NO_EM_RESTART': '1',
18:34:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:23 INFO - 'OS': 'Windows_NT',
18:34:23 INFO - 'OURDRIVE': 'C:',
18: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;',
18:34:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:23 INFO - 'PROCESSOR_LEVEL': '6',
18:34:23 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:23 INFO - 'PROMPT': '$P$G',
18:34:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:23 INFO - 'PWD': 'C:\\slave\\test',
18:34:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:23 INFO - 'SESSIONNAME': 'Console',
18:34:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:23 INFO - 'SYSTEMDRIVE': 'C:',
18:34:23 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:23 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:23 INFO - 'TEST1': 'testie',
18:34:23 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:23 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:23 INFO - 'USERNAME': 'cltbld',
18:34:23 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:23 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
18:34:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ffpisc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
18:34:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
18:34:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-z_x1nb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
18:34:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
18:34:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-mso6kq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
18:34:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
18:34:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-kfdxic-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
18:34:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
18:34:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rw634x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
18:34:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
18:34:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-hhtplu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
18:34:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
18:34:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-n4hwi7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
18:34:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
18:34:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-63n5cm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
18:34:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
18:34:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-1qyxqu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
18:34:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
18:34:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-deeoq6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
18:34:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
18:34:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xnwxez-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
18:34:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
18:34:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-pgqiyz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
18:34:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
18:34:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-yndz05-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
18:34:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
18:34:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-_drxbx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
18:34:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
18:34:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xs1ea3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
18:34:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
18:34:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-w4tvpy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
18:34:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
18:34:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-nhjvj2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
18:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
18:34:27 INFO - Running setup.py install for manifestparser
18:34:27 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
18:34:27 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
18:34:27 INFO - Running setup.py install for mozcrash
18:34:28 INFO - Running setup.py install for mozdebug
18:34:28 INFO - Running setup.py install for mozdevice
18:34:28 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
18:34:28 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
18:34:28 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
18:34:28 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
18:34:28 INFO - Running setup.py install for mozfile
18:34:29 INFO - Running setup.py install for mozhttpd
18:34:29 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
18:34:29 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
18:34:29 INFO - Running setup.py install for mozinfo
18:34:30 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Running setup.py install for mozInstall
18:34:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
18:34:30 INFO - Running setup.py install for mozleak
18:34:30 INFO - Running setup.py install for mozlog
18:34:31 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
18:34:31 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
18:34:31 INFO - Running setup.py install for moznetwork
18:34:31 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
18:34:31 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
18:34:31 INFO - Running setup.py install for mozprocess
18:34:32 INFO - Running setup.py install for mozprofile
18:34:32 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Running setup.py install for mozrunner
18:34:32 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
18:34:32 INFO - Running setup.py install for mozscreenshot
18:34:33 INFO - Running setup.py install for moztest
18:34:33 INFO - Running setup.py install for mozversion
18:34:33 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
18:34:33 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
18:34:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
18:34:33 INFO - Cleaning up...
18:34:33 INFO - Return code: 0
18:34:33 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:34:33 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')]}
18:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34:33 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')]}
18:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:33 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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:33 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
18:34:33 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
18:34:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:33 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:33 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:33 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:33 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:33 INFO - 'DCLOCATION': 'SCL3',
18:34:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:33 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:33 INFO - 'HOMEDRIVE': 'C:',
18:34:33 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:33 INFO - 'KTS_VERSION': '1.19c',
18:34:33 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:33 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:33 INFO - 'MOZ_NO_REMOTE': '1',
18:34:33 INFO - 'NO_EM_RESTART': '1',
18:34:33 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:33 INFO - 'OS': 'Windows_NT',
18:34:33 INFO - 'OURDRIVE': 'C:',
18:34:33 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;',
18:34:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:33 INFO - 'PROCESSOR_LEVEL': '6',
18:34:33 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:33 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:33 INFO - 'PROMPT': '$P$G',
18:34:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:33 INFO - 'PWD': 'C:\\slave\\test',
18:34:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:33 INFO - 'SESSIONNAME': 'Console',
18:34:33 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:33 INFO - 'SYSTEMDRIVE': 'C:',
18:34:33 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:33 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:33 INFO - 'TEST1': 'testie',
18:34:33 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:33 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:33 INFO - 'USERNAME': 'cltbld',
18:34:33 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:33 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
18:34:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-podkcg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
18:34:34 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))
18:34:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
18:34:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-95nufe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
18:34:34 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))
18:34:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
18:34:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wanboy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
18:34:35 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))
18:34:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
18:34:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ngwnix-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
18:34:35 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))
18:34:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
18:34:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-3bf31c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
18:34:35 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))
18:34:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
18:34:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-wy260u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
18:34:35 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))
18:34:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
18:34:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lf2vkm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
18:34:35 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))
18:34:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
18:34:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-fmgvmt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
18:34:36 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))
18:34:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
18:34:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-l8pj9i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
18:34:36 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))
18:34:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
18:34:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-7iru7a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
18:34:36 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))
18:34:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
18:34:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-va1nrs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
18:34:36 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))
18:34:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
18:34:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-xwj4hn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
18:34:36 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))
18:34:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
18:34:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vvnh2k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
18:34:37 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))
18:34:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
18:34:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-hyndbz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
18:34:37 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))
18:34:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
18:34:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-2m7tnj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
18:34:37 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))
18:34:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
18:34:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rni0fb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
18:34:37 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))
18:34:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
18:34:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-e4wwi6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
18:34:37 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))
18:34:37 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))
18:34:37 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))
18:34:37 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))
18:34:37 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))
18:34:37 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))
18:34:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
18:34:37 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
18:34:37 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
18:34:38 INFO - Downloading blessings-1.5.1.tar.gz
18:34:38 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
18:34:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
18:34:39 INFO - Installing collected packages: blessings
18:34:39 INFO - Running setup.py install for blessings
18:34:39 INFO - Successfully installed blessings
18:34:39 INFO - Cleaning up...
18:34:39 INFO - Return code: 0
18:34:39 INFO - Done creating virtualenv C:\slave\test\build/venv.
18:34:39 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
18:34:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
18:34:40 INFO - Reading from file tmpfile_stdout
18:34:40 INFO - Using _rmtree_windows ...
18:34:40 INFO - Using _rmtree_windows ...
18:34:40 INFO - Current package versions:
18:34:40 INFO - blessings == 1.5.1
18:34:40 INFO - blobuploader == 1.2.4
18:34:40 INFO - docopt == 0.6.1
18:34:40 INFO - manifestparser == 1.1
18:34:40 INFO - mozInstall == 1.12
18:34:40 INFO - mozcrash == 0.16
18:34:40 INFO - mozdebug == 0.1
18:34:40 INFO - mozdevice == 0.47
18:34:40 INFO - mozfile == 1.2
18:34:40 INFO - mozhttpd == 0.7
18:34:40 INFO - mozinfo == 0.9
18:34:40 INFO - mozleak == 0.1
18:34:40 INFO - mozlog == 3.0
18:34:40 INFO - moznetwork == 0.27
18:34:40 INFO - mozprocess == 0.22
18:34:40 INFO - mozprofile == 0.27
18:34:40 INFO - mozrunner == 6.11
18:34:40 INFO - mozscreenshot == 0.1
18:34:40 INFO - mozsystemmonitor == 0.0
18:34:40 INFO - moztest == 0.7
18:34:40 INFO - mozversion == 1.4
18:34:40 INFO - psutil == 3.1.1
18:34:40 INFO - requests == 1.2.3
18:34:40 INFO - Installing None into virtualenv C:\slave\test\build/venv
18:34: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')]}
18:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
18:34: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')]}
18:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
18:34:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-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 0x00C96B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00D6DE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00D08EA8>, '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 0x00C8B660>, '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-119', '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-119', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-119', '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-119\\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-119'}}, attempt #1
18:34:40 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
18:34:40 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
18:34:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:40 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:40 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:40 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:40 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:40 INFO - 'DCLOCATION': 'SCL3',
18:34:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:40 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:40 INFO - 'HOMEDRIVE': 'C:',
18:34:40 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:40 INFO - 'KTS_VERSION': '1.19c',
18:34:40 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:40 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:40 INFO - 'MOZ_NO_REMOTE': '1',
18:34:40 INFO - 'NO_EM_RESTART': '1',
18:34:40 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:40 INFO - 'OS': 'Windows_NT',
18:34:40 INFO - 'OURDRIVE': 'C:',
18:34:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
18:34:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:40 INFO - 'PROCESSOR_LEVEL': '6',
18:34:40 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:40 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:40 INFO - 'PROMPT': '$P$G',
18:34:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:40 INFO - 'PWD': 'C:\\slave\\test',
18:34:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:40 INFO - 'SESSIONNAME': 'Console',
18:34:40 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:40 INFO - 'SYSTEMDRIVE': 'C:',
18:34:40 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:40 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:40 INFO - 'TEST1': 'testie',
18:34:40 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:40 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:40 INFO - 'USERNAME': 'cltbld',
18:34:40 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:40 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:40 INFO - Ignoring indexes: https://pypi.python.org/simple/
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 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))
18:34:40 INFO - Cleaning up...
18:34:40 INFO - Return code: 0
18:34:40 INFO - Running post-action listener: _resource_record_post_action
18:34:40 INFO - Running post-action listener: _start_resource_monitoring
18:34:40 INFO - Starting resource monitoring.
18:34:40 INFO - #####
18:34:40 INFO - ##### Running install step.
18:34:40 INFO - #####
18:34:40 INFO - Running pre-action listener: _resource_record_pre_action
18:34:40 INFO - Running main action method: install
18:34:40 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
18:34:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
18:34:41 INFO - Reading from file tmpfile_stdout
18:34:41 INFO - Using _rmtree_windows ...
18:34:41 INFO - Using _rmtree_windows ...
18:34:41 INFO - Detecting whether we're running mozinstall >=1.0...
18:34:41 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
18:34:41 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
18:34:41 INFO - Reading from file tmpfile_stdout
18:34:41 INFO - Output received:
18:34:41 INFO - Usage: mozinstall-script.py [options] installer
18:34:41 INFO - Options:
18:34:41 INFO - -h, --help show this help message and exit
18:34:41 INFO - -d DEST, --destination=DEST
18:34:41 INFO - Directory to install application into. [default:
18:34:41 INFO - "C:\slave\test"]
18:34:41 INFO - --app=APP Application being installed. [default: firefox]
18:34:41 INFO - Using _rmtree_windows ...
18:34:41 INFO - Using _rmtree_windows ...
18:34:41 INFO - mkdir: C:\slave\test\build\application
18:34:41 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']
18:34:41 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
18:34:43 INFO - Reading from file tmpfile_stdout
18:34:43 INFO - Output received:
18:34:43 INFO - C:\slave\test\build\application\firefox\firefox.exe
18:34:43 INFO - Using _rmtree_windows ...
18:34:43 INFO - Using _rmtree_windows ...
18:34:43 INFO - Running post-action listener: _resource_record_post_action
18:34:43 INFO - #####
18:34:43 INFO - ##### Running run-tests step.
18:34:43 INFO - #####
18:34:43 INFO - Running pre-action listener: _resource_record_pre_action
18:34:43 INFO - Running main action method: run_tests
18:34:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
18:34:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
18:34:43 INFO - Python 2.7.3
18:34:43 INFO - Return code: 0
18:34:43 INFO - grabbing minidump binary from tooltool
18:34:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
18:34:43 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 0x00C8B660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
18:34:43 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
18:34:43 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
18:34:43 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
18:34:44 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmphny_en
18:34:44 INFO - INFO - File integrity verified, renaming tmphny_en to win32-minidump_stackwalk.exe
18:34:44 INFO - Return code: 0
18:34:44 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
18:34:44 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
18:34:44 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
18:34:44 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
18:34:44 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
18:34:44 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/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 't-xp32-ix-119', '--webServer', 'localhost'] in C:\slave\test\build
18:34:44 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/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip --title t-xp32-ix-119 --webServer localhost
18:34:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users',
18:34:44 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119\\Application Data',
18:34:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
18:34:44 INFO - 'COMPUTERNAME': 'T-XP32-IX-119',
18:34:44 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe',
18:34:44 INFO - 'CYGWINBASE': 'C:\\cygwin',
18:34:44 INFO - 'DCLOCATION': 'SCL3',
18:34:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
18:34:44 INFO - 'FP_NO_HOST_CHECK': 'NO',
18:34:44 INFO - 'HOMEDRIVE': 'C:',
18:34:44 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
18:34:44 INFO - 'KTS_VERSION': '1.19c',
18:34:44 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-119',
18:34:44 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
18:34:44 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
18:34:44 INFO - 'MONDIR': 'C:\\Monitor_config\\',
18:34:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
18:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
18:34:44 INFO - 'MOZ_NO_REMOTE': '1',
18:34:44 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
18:34:44 INFO - 'NO_EM_RESTART': '1',
18:34:44 INFO - 'NUMBER_OF_PROCESSORS': '8',
18:34:44 INFO - 'OS': 'Windows_NT',
18:34:44 INFO - 'OURDRIVE': 'C:',
18:34:44 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;',
18:34:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1',
18:34:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
18:34:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
18:34:44 INFO - 'PROCESSOR_LEVEL': '6',
18:34:44 INFO - 'PROCESSOR_REVISION': '1e05',
18:34:44 INFO - 'PROGRAMFILES': 'C:\\Program Files',
18:34:44 INFO - 'PROMPT': '$P$G',
18:34:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
18:34:44 INFO - 'PWD': 'C:\\slave\\test',
18:34:44 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
18:34:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
18:34:44 INFO - 'SESSIONNAME': 'Console',
18:34:44 INFO - 'SLAVEDIR': 'C:\\slave\\',
18:34:44 INFO - 'SYSTEMDRIVE': 'C:',
18:34:44 INFO - 'SYSTEMROOT': 'C:\\WINDOWS',
18:34:44 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:44 INFO - 'TEST1': 'testie',
18:34:44 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp',
18:34:44 INFO - 'USERDOMAIN': 'T-XP32-IX-119',
18:34:44 INFO - 'USERNAME': 'cltbld',
18:34:44 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-119',
18:34:44 INFO - 'WINDIR': 'C:\\WINDOWS',
18:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
18:34:44 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/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 't-xp32-ix-119', '--webServer', 'localhost'] with output_timeout 3600
18:34:45 INFO - mozversion INFO | application_buildid: 20151118163241
18:34:45 INFO - mozversion INFO | application_changeset: 42a976b770f1b1e54133ee0383940013ded058eb
18:34:45 INFO - mozversion INFO | application_display_name: Nightly
18:34:45 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
18:34:45 INFO - mozversion INFO | application_name: Firefox
18:34:45 INFO - mozversion INFO | application_remotingname: firefox
18:34:45 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team
18:34:45 INFO - mozversion INFO | application_vendor: Mozilla
18:34:45 INFO - mozversion INFO | application_version: 45.0a1
18:34:45 INFO - mozversion INFO | platform_buildid: 20151118163241
18:34:45 INFO - mozversion INFO | platform_changeset: 42a976b770f1b1e54133ee0383940013ded058eb
18:34:45 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team
18:34:45 INFO - mozversion INFO | platform_version: 45.0a1
18:34:45 INFO - 2015-11-18 18:34:45,516 DEBUG : using testdate: 1447900485
18:34:45 INFO - 2015-11-18 18:34:45,516 DEBUG : actual date: 1447900485
18:34:45 INFO - 2015-11-18 18:34:45,530 INFO : starting webserver on 'localhost:1741'
18:34:45 INFO - 2015-11-18 18:34:45,530 INFO : Starting test suite t-xp32-ix-119
18:34:45 INFO - 2015-11-18 18:34:45,530 INFO : Starting test a11yr
18:34:45 INFO - 2015-11-18 18:34:45,530 DEBUG : operating with platform_type : win_
18:34:45 INFO - 2015-11-18 18:34:45,530 INFO : Initialising browser for a11yr test...
18:34:45 INFO - 2015-11-18 18:34:45,594 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp_7hivz\profile http://localhost:1741/getInfo.html
18:34:49 INFO - 2015-11-18 18:34:49,148 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:34:49 INFO - 2015-11-18 18:34:49,148 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:34:49 INFO - 2015-11-18 18:34:49,148 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
18:34:49 INFO - 2015-11-18 18:34:49,148 DEBUG : BROWSER_OUTPUT: __metrics
18:34:49 INFO - 2015-11-18 18:34:49,178 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:34:50 INFO - 2015-11-18 18:34:50,084 INFO : Browser initialized.
18:34:50 INFO - 2015-11-18 18:34:50,084 INFO : Running cycle 1/1 for a11yr test...
18:34:50 INFO - 2015-11-18 18:34:50,084 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp_7hivz\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\a11y\a11y.manifest.develop -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25
18:35:01 INFO - 2015-11-18 18:35:01,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 142790656
18:35:01 INFO - 2015-11-18 18:35:01,713 DEBUG : BROWSER_OUTPUT:
18:35:03 INFO - 2015-11-18 18:35:03,117 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)
18:35:03 INFO - 2015-11-18 18:35:03,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 172617728
18:35:03 INFO - 2015-11-18 18:35:03,724 DEBUG : BROWSER_OUTPUT:
18:35:05 INFO - 2015-11-18 18:35:05,299 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)
18:35:05 INFO - 2015-11-18 18:35:05,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 176472064
18:35:05 INFO - 2015-11-18 18:35:05,984 DEBUG : BROWSER_OUTPUT:
18:35:07 INFO - 2015-11-18 18:35:07,388 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)
18:35:08 INFO - 2015-11-18 18:35:08,075 DEBUG : BROWSER_OUTPUT: RSS: Main: 174931968
18:35:08 INFO - 2015-11-18 18:35:08,075 DEBUG : BROWSER_OUTPUT:
18:35:09 INFO - 2015-11-18 18:35:09,555 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)
18:35:10 INFO - 2015-11-18 18:35:10,226 DEBUG : BROWSER_OUTPUT: RSS: Main: 175648768
18:35:10 INFO - 2015-11-18 18:35:10,226 DEBUG : BROWSER_OUTPUT:
18:35:11 INFO - 2015-11-18 18:35:11,690 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)
18:35:12 INFO - 2015-11-18 18:35:12,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 174977024
18:35:12 INFO - 2015-11-18 18:35:12,361 DEBUG : BROWSER_OUTPUT:
18:35:13 INFO - 2015-11-18 18:35:13,921 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)
18:35:14 INFO - 2015-11-18 18:35:14,591 DEBUG : BROWSER_OUTPUT: RSS: Main: 175583232
18:35:14 INFO - 2015-11-18 18:35:14,591 DEBUG : BROWSER_OUTPUT:
18:35:16 INFO - 2015-11-18 18:35:16,072 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)
18:35:16 INFO - 2015-11-18 18:35:16,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 174796800
18:35:16 INFO - 2015-11-18 18:35:16,743 DEBUG : BROWSER_OUTPUT:
18:35:18 INFO - 2015-11-18 18:35:18,316 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)
18:35:18 INFO - 2015-11-18 18:35:18,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 176173056
18:35:18 INFO - 2015-11-18 18:35:18,971 DEBUG : BROWSER_OUTPUT:
18:35:20 INFO - 2015-11-18 18:35:20,515 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)
18:35:21 INFO - 2015-11-18 18:35:21,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 176422912
18:35:21 INFO - 2015-11-18 18:35:21,186 DEBUG : BROWSER_OUTPUT:
18:35:22 INFO - 2015-11-18 18:35:22,667 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)
18:35:23 INFO - 2015-11-18 18:35:23,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 176525312
18:35:23 INFO - 2015-11-18 18:35:23,336 DEBUG : BROWSER_OUTPUT:
18:35:24 INFO - 2015-11-18 18:35:24,818 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)
18:35:25 INFO - 2015-11-18 18:35:25,489 DEBUG : BROWSER_OUTPUT: RSS: Main: 176738304
18:35:25 INFO - 2015-11-18 18:35:25,489 DEBUG : BROWSER_OUTPUT:
18:35:26 INFO - 2015-11-18 18:35:26,953 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)
18:35:27 INFO - 2015-11-18 18:35:27,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 176107520
18:35:27 INFO - 2015-11-18 18:35:27,625 DEBUG : BROWSER_OUTPUT:
18:35:29 INFO - 2015-11-18 18:35:29,089 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)
18:35:29 INFO - 2015-11-18 18:35:29,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 175108096
18:35:29 INFO - 2015-11-18 18:35:29,759 DEBUG : BROWSER_OUTPUT:
18:35:31 INFO - 2015-11-18 18:35:31,226 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)
18:35:31 INFO - 2015-11-18 18:35:31,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 175955968
18:35:31 INFO - 2015-11-18 18:35:31,895 DEBUG : BROWSER_OUTPUT:
18:35:33 INFO - 2015-11-18 18:35:33,470 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)
18:35:34 INFO - 2015-11-18 18:35:34,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 176697344
18:35:34 INFO - 2015-11-18 18:35:34,141 DEBUG : BROWSER_OUTPUT:
18:35:35 INFO - 2015-11-18 18:35:35,607 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)
18:35:36 INFO - 2015-11-18 18:35:36,276 DEBUG : BROWSER_OUTPUT: RSS: Main: 176369664
18:35:36 INFO - 2015-11-18 18:35:36,276 DEBUG : BROWSER_OUTPUT:
18:35:37 INFO - 2015-11-18 18:35:37,835 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)
18:35:38 INFO - 2015-11-18 18:35:38,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 176623616
18:35:38 INFO - 2015-11-18 18:35:38,490 DEBUG : BROWSER_OUTPUT:
18:35:40 INFO - 2015-11-18 18:35:40,081 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)
18:35:40 INFO - 2015-11-18 18:35:40,750 DEBUG : BROWSER_OUTPUT: RSS: Main: 176353280
18:35:40 INFO - 2015-11-18 18:35:40,750 DEBUG : BROWSER_OUTPUT:
18:35:42 INFO - 2015-11-18 18:35:42,309 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)
18:35:42 INFO - 2015-11-18 18:35:42,996 DEBUG : BROWSER_OUTPUT: RSS: Main: 175153152
18:35:42 INFO - 2015-11-18 18:35:42,996 DEBUG : BROWSER_OUTPUT:
18:35:44 INFO - 2015-11-18 18:35:44,446 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)
18:35:45 INFO - 2015-11-18 18:35:45,131 DEBUG : BROWSER_OUTPUT: RSS: Main: 177319936
18:35:45 INFO - 2015-11-18 18:35:45,131 DEBUG : BROWSER_OUTPUT:
18:35:46 INFO - 2015-11-18 18:35:46,690 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)
18:35:47 INFO - 2015-11-18 18:35:47,361 DEBUG : BROWSER_OUTPUT: RSS: Main: 176439296
18:35:47 INFO - 2015-11-18 18:35:47,361 DEBUG : BROWSER_OUTPUT:
18:35:48 INFO - 2015-11-18 18:35:48,936 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)
18:35:49 INFO - 2015-11-18 18:35:49,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 177434624
18:35:49 INFO - 2015-11-18 18:35:49,607 DEBUG : BROWSER_OUTPUT:
18:35:51 INFO - 2015-11-18 18:35:51,072 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)
18:35:51 INFO - 2015-11-18 18:35:51,289 DEBUG : BROWSER_OUTPUT: 1447900551302 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]
18:35:51 INFO - 2015-11-18 18:35:51,757 DEBUG : BROWSER_OUTPUT: RSS: Main: 182120448
18:35:51 INFO - 2015-11-18 18:35:51,757 DEBUG : BROWSER_OUTPUT:
18:35:53 INFO - 2015-11-18 18:35:53,255 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)
18:35:53 INFO - 2015-11-18 18:35:53,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 182071296
18:35:53 INFO - 2015-11-18 18:35:53,956 DEBUG : BROWSER_OUTPUT:
18:35:55 INFO - 2015-11-18 18:35:55,546 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)
18:35:56 INFO - 2015-11-18 18:35:56,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 179961856
18:35:56 INFO - 2015-11-18 18:35:56,217 DEBUG : BROWSER_OUTPUT:
18:35:57 INFO - 2015-11-18 18:35:57,137 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)
18:35:57 INFO - 2015-11-18 18:35:57,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 178073600
18:35:57 INFO - 2015-11-18 18:35:57,713 DEBUG : BROWSER_OUTPUT:
18:35:58 INFO - 2015-11-18 18:35:58,571 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)
18:35:59 INFO - 2015-11-18 18:35:59,148 DEBUG : BROWSER_OUTPUT: RSS: Main: 178962432
18:35:59 INFO - 2015-11-18 18:35:59,148 DEBUG : BROWSER_OUTPUT:
18:36:00 INFO - 2015-11-18 18:36:00,020 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)
18:36:00 INFO - 2015-11-18 18:36:00,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 178221056
18:36:00 INFO - 2015-11-18 18:36:00,598 DEBUG : BROWSER_OUTPUT:
18:36:01 INFO - 2015-11-18 18:36:01,470 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)
18:36:02 INFO - 2015-11-18 18:36:02,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 179068928
18:36:02 INFO - 2015-11-18 18:36:02,046 DEBUG : BROWSER_OUTPUT:
18:36:02 INFO - 2015-11-18 18:36:02,921 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)
18:36:03 INFO - 2015-11-18 18:36:03,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 173195264
18:36:03 INFO - 2015-11-18 18:36:03,496 DEBUG : BROWSER_OUTPUT:
18:36:04 INFO - 2015-11-18 18:36:04,369 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)
18:36:04 INFO - 2015-11-18 18:36:04,947 DEBUG : BROWSER_OUTPUT: RSS: Main: 174706688
18:36:04 INFO - 2015-11-18 18:36:04,947 DEBUG : BROWSER_OUTPUT:
18:36:05 INFO - 2015-11-18 18:36:05,805 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)
18:36:06 INFO - 2015-11-18 18:36:06,381 DEBUG : BROWSER_OUTPUT: RSS: Main: 174723072
18:36:06 INFO - 2015-11-18 18:36:06,381 DEBUG : BROWSER_OUTPUT:
18:36:07 INFO - 2015-11-18 18:36:07,239 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)
18:36:07 INFO - 2015-11-18 18:36:07,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 174678016
18:36:07 INFO - 2015-11-18 18:36:07,831 DEBUG : BROWSER_OUTPUT:
18:36:08 INFO - 2015-11-18 18:36:08,688 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)
18:36:09 INFO - 2015-11-18 18:36:09,280 DEBUG : BROWSER_OUTPUT: RSS: Main: 175030272
18:36:09 INFO - 2015-11-18 18:36:09,280 DEBUG : BROWSER_OUTPUT:
18:36:10 INFO - 2015-11-18 18:36:10,138 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)
18:36:10 INFO - 2015-11-18 18:36:10,716 DEBUG : BROWSER_OUTPUT: RSS: Main: 174227456
18:36:10 INFO - 2015-11-18 18:36:10,716 DEBUG : BROWSER_OUTPUT:
18:36:11 INFO - 2015-11-18 18:36:11,588 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)
18:36:12 INFO - 2015-11-18 18:36:12,180 DEBUG : BROWSER_OUTPUT: RSS: Main: 175124480
18:36:12 INFO - 2015-11-18 18:36:12,180 DEBUG : BROWSER_OUTPUT:
18:36:13 INFO - 2015-11-18 18:36:13,023 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)
18:36:13 INFO - 2015-11-18 18:36:13,615 DEBUG : BROWSER_OUTPUT: RSS: Main: 174329856
18:36:13 INFO - 2015-11-18 18:36:13,615 DEBUG : BROWSER_OUTPUT:
18:36:14 INFO - 2015-11-18 18:36:14,473 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)
18:36:15 INFO - 2015-11-18 18:36:15,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 175001600
18:36:15 INFO - 2015-11-18 18:36:15,049 DEBUG : BROWSER_OUTPUT:
18:36:15 INFO - 2015-11-18 18:36:15,923 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)
18:36:16 INFO - 2015-11-18 18:36:16,515 DEBUG : BROWSER_OUTPUT: RSS: Main: 174387200
18:36:16 INFO - 2015-11-18 18:36:16,515 DEBUG : BROWSER_OUTPUT:
18:36:17 INFO - 2015-11-18 18:36:17,372 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)
18:36:17 INFO - 2015-11-18 18:36:17,964 DEBUG : BROWSER_OUTPUT: RSS: Main: 175124480
18:36:17 INFO - 2015-11-18 18:36:17,964 DEBUG : BROWSER_OUTPUT:
18:36:18 INFO - 2015-11-18 18:36:18,822 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)
18:36:19 INFO - 2015-11-18 18:36:19,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 174428160
18:36:19 INFO - 2015-11-18 18:36:19,414 DEBUG : BROWSER_OUTPUT:
18:36:20 INFO - 2015-11-18 18:36:20,272 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)
18:36:20 INFO - 2015-11-18 18:36:20,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 174923776
18:36:20 INFO - 2015-11-18 18:36:20,865 DEBUG : BROWSER_OUTPUT:
18:36:21 INFO - 2015-11-18 18:36:21,721 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)
18:36:22 INFO - 2015-11-18 18:36:22,299 DEBUG : BROWSER_OUTPUT: RSS: Main: 175222784
18:36:22 INFO - 2015-11-18 18:36:22,299 DEBUG : BROWSER_OUTPUT:
18:36:23 INFO - 2015-11-18 18:36:23,155 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)
18:36:23 INFO - 2015-11-18 18:36:23,749 DEBUG : BROWSER_OUTPUT: RSS: Main: 175206400
18:36:23 INFO - 2015-11-18 18:36:23,749 DEBUG : BROWSER_OUTPUT:
18:36:24 INFO - 2015-11-18 18:36:24,605 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)
18:36:25 INFO - 2015-11-18 18:36:25,198 DEBUG : BROWSER_OUTPUT: RSS: Main: 174661632
18:36:25 INFO - 2015-11-18 18:36:25,198 DEBUG : BROWSER_OUTPUT:
18:36:26 INFO - 2015-11-18 18:36:26,055 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)
18:36:26 INFO - 2015-11-18 18:36:26,648 DEBUG : BROWSER_OUTPUT: RSS: Main: 173326336
18:36:26 INFO - 2015-11-18 18:36:26,648 DEBUG : BROWSER_OUTPUT:
18:36:27 INFO - 2015-11-18 18:36:27,522 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)
18:36:28 INFO - 2015-11-18 18:36:28,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 173912064
18:36:28 INFO - 2015-11-18 18:36:28,098 DEBUG : BROWSER_OUTPUT:
18:36:28 INFO - 2015-11-18 18:36:28,956 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)
18:36:29 INFO - 2015-11-18 18:36:29,548 DEBUG : BROWSER_OUTPUT: RSS: Main: 173150208
18:36:29 INFO - 2015-11-18 18:36:29,548 DEBUG : BROWSER_OUTPUT:
18:36:30 INFO - 2015-11-18 18:36:30,421 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)
18:36:31 INFO - 2015-11-18 18:36:31,013 DEBUG : BROWSER_OUTPUT: RSS: Main: 174333952
18:36:31 INFO - 2015-11-18 18:36:31,013 DEBUG : BROWSER_OUTPUT:
18:36:31 INFO - 2015-11-18 18:36:31,934 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)
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 174297088
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT:
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: __start_tp_report
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;1308;1493;1375;1377;1372;1501;1400;1495;1490;1404;1392;1340;1385;1351;1490;1362;1485;1495;1482;1394;1495;1507;1393;1412;1489
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;322;334;332;344;329;341;335;333;343;329;345;332;334;343;331;325;333;332;337;330;343;334;333;342;332
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: __end_tp_report
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: __start_cc_report
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,6225
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: __end_cc_report
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900592203__endTimestamp
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT:
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: Number of tests: 2
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT:
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:1427.48 Median:1404.00 stddev:62.90 (4.5%) stddev-sans-first:59.00
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: Values: 1308.0 1493.0 1375.0 1377.0 1372.0 1501.0 1400.0 1495.0 1490.0 1404.0 1392.0 1340.0 1385.0 1351.0 1490.0 1362.0 1485.0 1495.0 1482.0 1394.0 1495.0 1507.0 1393.0 1412.0 1489.0
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT:
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:334.72 Median:333.00 stddev:6.10 (1.8%) stddev-sans-first:5.61
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: Values: 322.0 334.0 332.0 344.0 329.0 341.0 335.0 333.0 343.0 329.0 345.0 332.0 334.0 343.0 331.0 325.0 333.0 332.0 337.0 330.0 343.0 334.0 333.0 342.0 332.0
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
18:36:32 INFO - 2015-11-18 18:36:32,183 DEBUG : BROWSER_OUTPUT:
18:36:32 INFO - 2015-11-18 18:36:32,776 INFO : Browser exited with error code: 0
18:36:32 INFO - 2015-11-18 18:36:32,838 INFO : Completed test a11yr (00:01:47)
18:36:32 INFO - 2015-11-18 18:36:32,838 INFO : Starting test ts_paint
18:36:32 INFO - 2015-11-18 18:36:32,838 DEBUG : operating with platform_type : win_
18:36:32 INFO - 2015-11-18 18:36:32,838 INFO : Initialising browser for ts_paint test...
18:36:32 INFO - 2015-11-18 18:36:32,854 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/getInfo.html
18:36:34 INFO - 2015-11-18 18:36:34,786 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:36:34 INFO - 2015-11-18 18:36:34,786 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:36:34 INFO - 2015-11-18 18:36:34,786 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
18:36:34 INFO - 2015-11-18 18:36:34,786 DEBUG : BROWSER_OUTPUT: __metrics
18:36:34 INFO - 2015-11-18 18:36:34,802 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:36:35 INFO - 2015-11-18 18:36:35,596 INFO : Browser initialized.
18:36:35 INFO - 2015-11-18 18:36:35,596 INFO : Running cycle 1/20 for ts_paint test...
18:36:35 INFO - 2015-11-18 18:36:35,596 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:37 INFO - 2015-11-18 18:36:37,032 DEBUG : BROWSER_OUTPUT: __start_report1442__end_report
18:36:37 INFO - 2015-11-18 18:36:37,032 DEBUG : BROWSER_OUTPUT:
18:36:37 INFO - 2015-11-18 18:36:37,046 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900597055__endTimestamp
18:36:37 INFO - 2015-11-18 18:36:37,920 INFO : Browser exited with error code: 0
18:36:37 INFO - 2015-11-18 18:36:37,920 INFO : Running cycle 2/20 for ts_paint test...
18:36:37 INFO - 2015-11-18 18:36:37,920 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:38 INFO - 2015-11-18 18:36:38,980 DEBUG : BROWSER_OUTPUT: __start_report1053__end_report
18:36:38 INFO - 2015-11-18 18:36:38,980 DEBUG : BROWSER_OUTPUT:
18:36:38 INFO - 2015-11-18 18:36:38,980 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900598989__endTimestamp
18:36:39 INFO - 2015-11-18 18:36:39,729 INFO : Browser exited with error code: 0
18:36:39 INFO - 2015-11-18 18:36:39,729 INFO : Running cycle 3/20 for ts_paint test...
18:36:39 INFO - 2015-11-18 18:36:39,729 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:40 INFO - 2015-11-18 18:36:40,757 DEBUG : BROWSER_OUTPUT: __start_report1042__end_report
18:36:40 INFO - 2015-11-18 18:36:40,757 DEBUG : BROWSER_OUTPUT:
18:36:40 INFO - 2015-11-18 18:36:40,757 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900600776__endTimestamp
18:36:41 INFO - 2015-11-18 18:36:41,599 INFO : Browser exited with error code: 0
18:36:41 INFO - 2015-11-18 18:36:41,599 INFO : Running cycle 4/20 for ts_paint test...
18:36:41 INFO - 2015-11-18 18:36:41,599 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:42 INFO - 2015-11-18 18:36:42,612 DEBUG : BROWSER_OUTPUT: __start_report1034__end_report
18:36:42 INFO - 2015-11-18 18:36:42,612 DEBUG : BROWSER_OUTPUT:
18:36:42 INFO - 2015-11-18 18:36:42,628 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900602631__endTimestamp
18:36:43 INFO - 2015-11-18 18:36:43,454 INFO : Browser exited with error code: 0
18:36:43 INFO - 2015-11-18 18:36:43,454 INFO : Running cycle 5/20 for ts_paint test...
18:36:43 INFO - 2015-11-18 18:36:43,454 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:44 INFO - 2015-11-18 18:36:44,483 DEBUG : BROWSER_OUTPUT: __start_report1039__end_report
18:36:44 INFO - 2015-11-18 18:36:44,483 DEBUG : BROWSER_OUTPUT:
18:36:44 INFO - 2015-11-18 18:36:44,483 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900604499__endTimestamp
18:36:45 INFO - 2015-11-18 18:36:45,325 INFO : Browser exited with error code: 0
18:36:45 INFO - 2015-11-18 18:36:45,325 INFO : Running cycle 6/20 for ts_paint test...
18:36:45 INFO - 2015-11-18 18:36:45,325 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:46 INFO - 2015-11-18 18:36:46,369 DEBUG : BROWSER_OUTPUT: __start_report1044__end_report
18:36:46 INFO - 2015-11-18 18:36:46,369 DEBUG : BROWSER_OUTPUT:
18:36:46 INFO - 2015-11-18 18:36:46,369 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900606377__endTimestamp
18:36:47 INFO - 2015-11-18 18:36:47,227 INFO : Browser exited with error code: 0
18:36:47 INFO - 2015-11-18 18:36:47,227 INFO : Running cycle 7/20 for ts_paint test...
18:36:47 INFO - 2015-11-18 18:36:47,227 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:48 INFO - 2015-11-18 18:36:48,256 DEBUG : BROWSER_OUTPUT: __start_report1051__end_report
18:36:48 INFO - 2015-11-18 18:36:48,256 DEBUG : BROWSER_OUTPUT:
18:36:48 INFO - 2015-11-18 18:36:48,256 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900608273__endTimestamp
18:36:49 INFO - 2015-11-18 18:36:49,144 INFO : Browser exited with error code: 0
18:36:49 INFO - 2015-11-18 18:36:49,144 INFO : Running cycle 8/20 for ts_paint test...
18:36:49 INFO - 2015-11-18 18:36:49,144 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:50 INFO - 2015-11-18 18:36:50,174 DEBUG : BROWSER_OUTPUT: __start_report1039__end_report
18:36:50 INFO - 2015-11-18 18:36:50,174 DEBUG : BROWSER_OUTPUT:
18:36:50 INFO - 2015-11-18 18:36:50,174 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900610188__endTimestamp
18:36:51 INFO - 2015-11-18 18:36:51,062 INFO : Browser exited with error code: 0
18:36:51 INFO - 2015-11-18 18:36:51,062 INFO : Running cycle 9/20 for ts_paint test...
18:36:51 INFO - 2015-11-18 18:36:51,062 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:52 INFO - 2015-11-18 18:36:52,092 DEBUG : BROWSER_OUTPUT: __start_report1037__end_report
18:36:52 INFO - 2015-11-18 18:36:52,092 DEBUG : BROWSER_OUTPUT:
18:36:52 INFO - 2015-11-18 18:36:52,092 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900612106__endTimestamp
18:36:53 INFO - 2015-11-18 18:36:53,026 INFO : Browser exited with error code: 0
18:36:53 INFO - 2015-11-18 18:36:53,026 INFO : Running cycle 10/20 for ts_paint test...
18:36:53 INFO - 2015-11-18 18:36:53,026 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:54 INFO - 2015-11-18 18:36:54,072 DEBUG : BROWSER_OUTPUT: __start_report1065__end_report
18:36:54 INFO - 2015-11-18 18:36:54,072 DEBUG : BROWSER_OUTPUT:
18:36:54 INFO - 2015-11-18 18:36:54,086 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900614092__endTimestamp
18:36:54 INFO - 2015-11-18 18:36:54,990 INFO : Browser exited with error code: 0
18:36:54 INFO - 2015-11-18 18:36:54,990 INFO : Running cycle 11/20 for ts_paint test...
18:36:54 INFO - 2015-11-18 18:36:54,990 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:56 INFO - 2015-11-18 18:36:56,005 DEBUG : BROWSER_OUTPUT: __start_report1032__end_report
18:36:56 INFO - 2015-11-18 18:36:56,005 DEBUG : BROWSER_OUTPUT:
18:36:56 INFO - 2015-11-18 18:36:56,005 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900616021__endTimestamp
18:36:56 INFO - 2015-11-18 18:36:56,908 INFO : Browser exited with error code: 0
18:36:56 INFO - 2015-11-18 18:36:56,908 INFO : Running cycle 12/20 for ts_paint test...
18:36:56 INFO - 2015-11-18 18:36:56,908 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:57 INFO - 2015-11-18 18:36:57,921 DEBUG : BROWSER_OUTPUT: __start_report1034__end_report
18:36:57 INFO - 2015-11-18 18:36:57,921 DEBUG : BROWSER_OUTPUT:
18:36:57 INFO - 2015-11-18 18:36:57,921 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900617940__endTimestamp
18:36:58 INFO - 2015-11-18 18:36:58,811 INFO : Browser exited with error code: 0
18:36:58 INFO - 2015-11-18 18:36:58,811 INFO : Running cycle 13/20 for ts_paint test...
18:36:58 INFO - 2015-11-18 18:36:58,811 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:36:59 INFO - 2015-11-18 18:36:59,855 DEBUG : BROWSER_OUTPUT: __start_report1045__end_report
18:36:59 INFO - 2015-11-18 18:36:59,855 DEBUG : BROWSER_OUTPUT:
18:36:59 INFO - 2015-11-18 18:36:59,855 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900619862__endTimestamp
18:37:00 INFO - 2015-11-18 18:37:00,775 INFO : Browser exited with error code: 0
18:37:00 INFO - 2015-11-18 18:37:00,775 INFO : Running cycle 14/20 for ts_paint test...
18:37:00 INFO - 2015-11-18 18:37:00,775 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:01 INFO - 2015-11-18 18:37:01,819 DEBUG : BROWSER_OUTPUT: __start_report1056__end_report
18:37:01 INFO - 2015-11-18 18:37:01,819 DEBUG : BROWSER_OUTPUT:
18:37:01 INFO - 2015-11-18 18:37:01,819 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900621830__endTimestamp
18:37:02 INFO - 2015-11-18 18:37:02,740 INFO : Browser exited with error code: 0
18:37:02 INFO - 2015-11-18 18:37:02,740 INFO : Running cycle 15/20 for ts_paint test...
18:37:02 INFO - 2015-11-18 18:37:02,740 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:03 INFO - 2015-11-18 18:37:03,772 DEBUG : BROWSER_OUTPUT: __start_report1027__end_report
18:37:03 INFO - 2015-11-18 18:37:03,772 DEBUG : BROWSER_OUTPUT:
18:37:03 INFO - 2015-11-18 18:37:03,788 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900623786__endTimestamp
18:37:04 INFO - 2015-11-18 18:37:04,709 INFO : Browser exited with error code: 0
18:37:04 INFO - 2015-11-18 18:37:04,709 INFO : Running cycle 16/20 for ts_paint test...
18:37:04 INFO - 2015-11-18 18:37:04,709 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:05 INFO - 2015-11-18 18:37:05,740 DEBUG : BROWSER_OUTPUT: __start_report1042__end_report
18:37:05 INFO - 2015-11-18 18:37:05,740 DEBUG : BROWSER_OUTPUT:
18:37:05 INFO - 2015-11-18 18:37:05,755 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900625757__endTimestamp
18:37:06 INFO - 2015-11-18 18:37:06,677 INFO : Browser exited with error code: 0
18:37:06 INFO - 2015-11-18 18:37:06,677 INFO : Running cycle 17/20 for ts_paint test...
18:37:06 INFO - 2015-11-18 18:37:06,677 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:07 INFO - 2015-11-18 18:37:07,707 DEBUG : BROWSER_OUTPUT: __start_report1036__end_report
18:37:07 INFO - 2015-11-18 18:37:07,707 DEBUG : BROWSER_OUTPUT:
18:37:07 INFO - 2015-11-18 18:37:07,723 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900627727__endTimestamp
18:37:08 INFO - 2015-11-18 18:37:08,644 INFO : Browser exited with error code: 0
18:37:08 INFO - 2015-11-18 18:37:08,644 INFO : Running cycle 18/20 for ts_paint test...
18:37:08 INFO - 2015-11-18 18:37:08,644 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:09 INFO - 2015-11-18 18:37:09,706 DEBUG : BROWSER_OUTPUT: __start_report1075__end_report
18:37:09 INFO - 2015-11-18 18:37:09,706 DEBUG : BROWSER_OUTPUT:
18:37:09 INFO - 2015-11-18 18:37:09,706 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900629709__endTimestamp
18:37:10 INFO - 2015-11-18 18:37:10,674 INFO : Browser exited with error code: 0
18:37:10 INFO - 2015-11-18 18:37:10,674 INFO : Running cycle 19/20 for ts_paint test...
18:37:10 INFO - 2015-11-18 18:37:10,674 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:11 INFO - 2015-11-18 18:37:11,690 DEBUG : BROWSER_OUTPUT: __start_report1035__end_report
18:37:11 INFO - 2015-11-18 18:37:11,690 DEBUG : BROWSER_OUTPUT:
18:37:11 INFO - 2015-11-18 18:37:11,704 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900631712__endTimestamp
18:37:12 INFO - 2015-11-18 18:37:12,641 INFO : Browser exited with error code: 0
18:37:12 INFO - 2015-11-18 18:37:12,641 INFO : Running cycle 20/20 for ts_paint test...
18:37:12 INFO - 2015-11-18 18:37:12,641 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpxjbtat\profile http://localhost:1741/startup_test/tspaint_test.html
18:37:13 INFO - 2015-11-18 18:37:13,657 DEBUG : BROWSER_OUTPUT: __start_report1036__end_report
18:37:13 INFO - 2015-11-18 18:37:13,657 DEBUG : BROWSER_OUTPUT:
18:37:13 INFO - 2015-11-18 18:37:13,657 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900633674__endTimestamp
18:37:14 INFO - 2015-11-18 18:37:14,609 INFO : Browser exited with error code: 0
18:37:14 INFO - 2015-11-18 18:37:14,671 INFO : Completed test ts_paint (00:00:41)
18:37:14 INFO - 2015-11-18 18:37:14,671 INFO : Starting test tpaint
18:37:14 INFO - 2015-11-18 18:37:14,671 DEBUG : operating with platform_type : win_
18:37:14 INFO - 2015-11-18 18:37:14,687 INFO : Initialising browser for tpaint test...
18:37:14 INFO - 2015-11-18 18:37:14,703 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmphwwyyi\profile http://localhost:1741/getInfo.html
18:37:16 INFO - 2015-11-18 18:37:16,530 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:37:16 INFO - 2015-11-18 18:37:16,530 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:37:16 INFO - 2015-11-18 18:37:16,530 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661
18:37:16 INFO - 2015-11-18 18:37:16,530 DEBUG : BROWSER_OUTPUT: __metrics
18:37:16 INFO - 2015-11-18 18:37:16,546 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:37:17 INFO - 2015-11-18 18:37:17,358 INFO : Browser initialized.
18:37:17 INFO - 2015-11-18 18:37:17,358 INFO : Running cycle 1/1 for tpaint test...
18:37:17 INFO - 2015-11-18 18:37:17,358 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmphwwyyi\profile file://C:\slave\test\build\tests\talos\talos/startup_test/tpaint.html?auto=1
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: __start_report236.76000000000022|236.92999999999984|238.8650000000016|239.09000000000015|239.56499999999983|239.94500000000016|242.375|245.16500000000087|246.875|248.5699999999997|250.30000000000018|252.4349999999995|253.76000000000022|257.72499999999854|259.2399999999998|279.85000000000036|282.7649999999994|289.8100000000013|291.28499999999985|301.15999999999985__end_report__startTimestamp1447900663921__endTimestamp
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: openingTimes=236.92999999999984,238.8650000000016,239.09000000000015,239.56499999999983,239.94500000000016,242.375,245.16500000000087,246.875,248.5699999999997,250.30000000000018,252.4349999999995,253.76000000000022,257.72499999999854,259.2399999999998,279.85000000000036,282.7649999999994,289.8100000000013,291.28499999999985,301.15999999999985
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: avgOpenTime:256.62
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: minOpenTime:236.76
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: maxOpenTime:301.16
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: medOpenTime:249.43499999999995
18:37:43 INFO - 2015-11-18 18:37:43,921 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:249.43499999999995
18:37:44 INFO - 2015-11-18 18:37:44,796 INFO : Browser exited with error code: 0
18:37:44 INFO - 2015-11-18 18:37:44,844 INFO : Completed test tpaint (00:00:30)
18:37:44 INFO - 2015-11-18 18:37:44,844 INFO : Starting test sessionrestore
18:37:44 INFO - 2015-11-18 18:37:44,844 DEBUG : operating with platform_type : win_
18:37:44 INFO - 2015-11-18 18:37:44,844 INFO : Initialising browser for sessionrestore test...
18:37:44 INFO - 2015-11-18 18:37:44,875 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/getInfo.html
18:37:49 INFO - 2015-11-18 18:37:49,716 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:37:49 INFO - 2015-11-18 18:37:49,716 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:37:49 INFO - 2015-11-18 18:37:49,716 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
18:37:49 INFO - 2015-11-18 18:37:49,716 DEBUG : BROWSER_OUTPUT: __metrics
18:37:49 INFO - 2015-11-18 18:37:49,809 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:37:51 INFO - 2015-11-18 18:37:51,309 INFO : Browser initialized.
18:37:51 INFO - 2015-11-18 18:37:51,309 INFO : Running cycle 1/10 for sessionrestore test...
18:37:51 INFO - 2015-11-18 18:37:51,309 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\tmpdohteb\profile\sessionstore.js
18:37:51 INFO - 2015-11-18 18:37:51,309 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\tmpdohteb\profile\sessionCheckpoints.json
18:37:51 INFO - 2015-11-18 18:37:51,323 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:37:55 INFO - 2015-11-18 18:37:55,775 DEBUG : BROWSER_OUTPUT: __start_report2280__end_report
18:37:55 INFO - 2015-11-18 18:37:55,775 DEBUG : BROWSER_OUTPUT:
18:37:55 INFO - 2015-11-18 18:37:55,775 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900675784__endTimestamp
18:37:55 INFO - 2015-11-18 18:37:55,775 DEBUG : BROWSER_OUTPUT:
18:37:57 INFO - 2015-11-18 18:37:57,321 INFO : Browser exited with error code: 0
18:37:57 INFO - 2015-11-18 18:37:57,321 INFO : Running cycle 2/10 for sessionrestore test...
18:37:57 INFO - 2015-11-18 18:37:57,321 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\tmpdohteb\profile\sessionstore.js
18:37:57 INFO - 2015-11-18 18:37:57,336 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\tmpdohteb\profile\sessionCheckpoints.json
18:37:57 INFO - 2015-11-18 18:37:57,336 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:01 INFO - 2015-11-18 18:38:01,381 DEBUG : BROWSER_OUTPUT: __start_report2092__end_report
18:38:01 INFO - 2015-11-18 18:38:01,381 DEBUG : BROWSER_OUTPUT:
18:38:01 INFO - 2015-11-18 18:38:01,381 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900681393__endTimestamp
18:38:01 INFO - 2015-11-18 18:38:01,381 DEBUG : BROWSER_OUTPUT:
18:38:02 INFO - 2015-11-18 18:38:02,943 INFO : Browser exited with error code: 0
18:38:02 INFO - 2015-11-18 18:38:02,943 INFO : Running cycle 3/10 for sessionrestore test...
18:38:02 INFO - 2015-11-18 18:38:02,943 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\tmpdohteb\profile\sessionstore.js
18:38:02 INFO - 2015-11-18 18:38:02,943 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:02 INFO - 2015-11-18 18:38:02,943 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:07 INFO - 2015-11-18 18:38:07,065 DEBUG : BROWSER_OUTPUT: __start_report2132__end_report
18:38:07 INFO - 2015-11-18 18:38:07,065 DEBUG : BROWSER_OUTPUT:
18:38:07 INFO - 2015-11-18 18:38:07,065 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900687077__endTimestamp
18:38:07 INFO - 2015-11-18 18:38:07,065 DEBUG : BROWSER_OUTPUT:
18:38:08 INFO - 2015-11-18 18:38:08,706 INFO : Browser exited with error code: 0
18:38:08 INFO - 2015-11-18 18:38:08,706 INFO : Running cycle 4/10 for sessionrestore test...
18:38:08 INFO - 2015-11-18 18:38:08,706 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\tmpdohteb\profile\sessionstore.js
18:38:08 INFO - 2015-11-18 18:38:08,706 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:08 INFO - 2015-11-18 18:38:08,706 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:12 INFO - 2015-11-18 18:38:12,719 DEBUG : BROWSER_OUTPUT: __start_report2071__end_report
18:38:12 INFO - 2015-11-18 18:38:12,719 DEBUG : BROWSER_OUTPUT:
18:38:12 INFO - 2015-11-18 18:38:12,719 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900692734__endTimestamp
18:38:12 INFO - 2015-11-18 18:38:12,719 DEBUG : BROWSER_OUTPUT:
18:38:14 INFO - 2015-11-18 18:38:14,374 INFO : Browser exited with error code: 0
18:38:14 INFO - 2015-11-18 18:38:14,374 INFO : Running cycle 5/10 for sessionrestore test...
18:38:14 INFO - 2015-11-18 18:38:14,374 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\tmpdohteb\profile\sessionstore.js
18:38:14 INFO - 2015-11-18 18:38:14,374 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:14 INFO - 2015-11-18 18:38:14,374 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:18 INFO - 2015-11-18 18:38:18,403 DEBUG : BROWSER_OUTPUT: __start_report2090__end_report
18:38:18 INFO - 2015-11-18 18:38:18,403 DEBUG : BROWSER_OUTPUT:
18:38:18 INFO - 2015-11-18 18:38:18,403 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900698412__endTimestamp
18:38:18 INFO - 2015-11-18 18:38:18,403 DEBUG : BROWSER_OUTPUT:
18:38:20 INFO - 2015-11-18 18:38:20,028 INFO : Browser exited with error code: 0
18:38:20 INFO - 2015-11-18 18:38:20,028 INFO : Running cycle 6/10 for sessionrestore test...
18:38:20 INFO - 2015-11-18 18:38:20,028 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\tmpdohteb\profile\sessionstore.js
18:38:20 INFO - 2015-11-18 18:38:20,028 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:20 INFO - 2015-11-18 18:38:20,028 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:24 INFO - 2015-11-18 18:38:24,056 DEBUG : BROWSER_OUTPUT: __start_report2083__end_report
18:38:24 INFO - 2015-11-18 18:38:24,056 DEBUG : BROWSER_OUTPUT:
18:38:24 INFO - 2015-11-18 18:38:24,056 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900704071__endTimestamp
18:38:24 INFO - 2015-11-18 18:38:24,056 DEBUG : BROWSER_OUTPUT:
18:38:25 INFO - 2015-11-18 18:38:25,805 INFO : Browser exited with error code: 0
18:38:25 INFO - 2015-11-18 18:38:25,805 INFO : Running cycle 7/10 for sessionrestore test...
18:38:25 INFO - 2015-11-18 18:38:25,805 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\tmpdohteb\profile\sessionstore.js
18:38:25 INFO - 2015-11-18 18:38:25,805 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:25 INFO - 2015-11-18 18:38:25,805 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:29 INFO - 2015-11-18 18:38:29,849 DEBUG : BROWSER_OUTPUT: __start_report2104__end_report
18:38:29 INFO - 2015-11-18 18:38:29,849 DEBUG : BROWSER_OUTPUT:
18:38:29 INFO - 2015-11-18 18:38:29,849 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900709857__endTimestamp
18:38:29 INFO - 2015-11-18 18:38:29,849 DEBUG : BROWSER_OUTPUT:
18:38:31 INFO - 2015-11-18 18:38:31,568 INFO : Browser exited with error code: 0
18:38:31 INFO - 2015-11-18 18:38:31,568 INFO : Running cycle 8/10 for sessionrestore test...
18:38:31 INFO - 2015-11-18 18:38:31,568 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\tmpdohteb\profile\sessionstore.js
18:38:31 INFO - 2015-11-18 18:38:31,568 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:31 INFO - 2015-11-18 18:38:31,568 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:35 INFO - 2015-11-18 18:38:35,596 DEBUG : BROWSER_OUTPUT: __start_report2071__end_report
18:38:35 INFO - 2015-11-18 18:38:35,596 DEBUG : BROWSER_OUTPUT:
18:38:35 INFO - 2015-11-18 18:38:35,596 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900715598__endTimestamp
18:38:35 INFO - 2015-11-18 18:38:35,596 DEBUG : BROWSER_OUTPUT:
18:38:37 INFO - 2015-11-18 18:38:37,331 INFO : Browser exited with error code: 0
18:38:37 INFO - 2015-11-18 18:38:37,331 INFO : Running cycle 9/10 for sessionrestore test...
18:38:37 INFO - 2015-11-18 18:38:37,331 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\tmpdohteb\profile\sessionstore.js
18:38:37 INFO - 2015-11-18 18:38:37,331 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:37 INFO - 2015-11-18 18:38:37,331 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:41 INFO - 2015-11-18 18:38:41,375 DEBUG : BROWSER_OUTPUT: __start_report2086__end_report
18:38:41 INFO - 2015-11-18 18:38:41,375 DEBUG : BROWSER_OUTPUT:
18:38:41 INFO - 2015-11-18 18:38:41,375 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900721382__endTimestamp
18:38:41 INFO - 2015-11-18 18:38:41,375 DEBUG : BROWSER_OUTPUT:
18:38:43 INFO - 2015-11-18 18:38:43,092 INFO : Browser exited with error code: 0
18:38:43 INFO - 2015-11-18 18:38:43,092 INFO : Running cycle 10/10 for sessionrestore test...
18:38:43 INFO - 2015-11-18 18:38:43,092 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\tmpdohteb\profile\sessionstore.js
18:38:43 INFO - 2015-11-18 18:38:43,092 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\tmpdohteb\profile\sessionCheckpoints.json
18:38:43 INFO - 2015-11-18 18:38:43,092 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpdohteb\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:47 INFO - 2015-11-18 18:38:47,121 DEBUG : BROWSER_OUTPUT: __start_report2077__end_report
18:38:47 INFO - 2015-11-18 18:38:47,121 DEBUG : BROWSER_OUTPUT:
18:38:47 INFO - 2015-11-18 18:38:47,121 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900727137__endTimestamp
18:38:47 INFO - 2015-11-18 18:38:47,121 DEBUG : BROWSER_OUTPUT:
18:38:48 INFO - 2015-11-18 18:38:48,855 INFO : Browser exited with error code: 0
18:38:48 INFO - 2015-11-18 18:38:48,917 INFO : Completed test sessionrestore (00:01:04)
18:38:48 INFO - 2015-11-18 18:38:48,917 INFO : Starting test sessionrestore_no_auto_restore
18:38:48 INFO - 2015-11-18 18:38:48,917 DEBUG : operating with platform_type : win_
18:38:48 INFO - 2015-11-18 18:38:48,917 INFO : Initialising browser for sessionrestore_no_auto_restore test...
18:38:48 INFO - 2015-11-18 18:38:48,948 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/getInfo.html
18:38:50 INFO - 2015-11-18 18:38:50,822 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
18:38:50 INFO - 2015-11-18 18:38:50,822 DEBUG : BROWSER_OUTPUT: colorDepth:24
18:38:50 INFO - 2015-11-18 18:38:50,822 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1192/770
18:38:50 INFO - 2015-11-18 18:38:50,822 DEBUG : BROWSER_OUTPUT: __metrics
18:38:50 INFO - 2015-11-18 18:38:50,838 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: console.error:
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: Stack:
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9
18:38:51 INFO - 2015-11-18 18:38:51,104 DEBUG : BROWSER_OUTPUT:
18:38:51 INFO - 2015-11-18 18:38:51,667 INFO : Browser initialized.
18:38:51 INFO - 2015-11-18 18:38:51,667 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test...
18:38:51 INFO - 2015-11-18 18:38:51,667 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\tmpnjkny4\profile\sessionstore.js
18:38:51 INFO - 2015-11-18 18:38:51,667 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\tmpnjkny4\profile\sessionCheckpoints.json
18:38:51 INFO - 2015-11-18 18:38:51,667 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:53 INFO - 2015-11-18 18:38:53,165 DEBUG : BROWSER_OUTPUT: __start_report984__end_report
18:38:53 INFO - 2015-11-18 18:38:53,165 DEBUG : BROWSER_OUTPUT:
18:38:53 INFO - 2015-11-18 18:38:53,165 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900733170__endTimestamp
18:38:53 INFO - 2015-11-18 18:38:53,165 DEBUG : BROWSER_OUTPUT:
18:38:53 INFO - 2015-11-18 18:38:53,993 INFO : Browser exited with error code: 0
18:38:53 INFO - 2015-11-18 18:38:53,993 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test...
18:38:53 INFO - 2015-11-18 18:38:53,993 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\tmpnjkny4\profile\sessionstore.js
18:38:53 INFO - 2015-11-18 18:38:53,993 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\tmpnjkny4\profile\sessionCheckpoints.json
18:38:53 INFO - 2015-11-18 18:38:53,993 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:55 INFO - 2015-11-18 18:38:55,132 DEBUG : BROWSER_OUTPUT: __start_report775__end_report
18:38:55 INFO - 2015-11-18 18:38:55,132 DEBUG : BROWSER_OUTPUT:
18:38:55 INFO - 2015-11-18 18:38:55,132 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900735140__endTimestamp
18:38:55 INFO - 2015-11-18 18:38:55,132 DEBUG : BROWSER_OUTPUT:
18:38:55 INFO - 2015-11-18 18:38:55,914 INFO : Browser exited with error code: 0
18:38:55 INFO - 2015-11-18 18:38:55,914 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test...
18:38:55 INFO - 2015-11-18 18:38:55,914 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\tmpnjkny4\profile\sessionstore.js
18:38:55 INFO - 2015-11-18 18:38:55,914 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\tmpnjkny4\profile\sessionCheckpoints.json
18:38:55 INFO - 2015-11-18 18:38:55,914 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:57 INFO - 2015-11-18 18:38:57,053 DEBUG : BROWSER_OUTPUT: __start_report778__end_report
18:38:57 INFO - 2015-11-18 18:38:57,053 DEBUG : BROWSER_OUTPUT:
18:38:57 INFO - 2015-11-18 18:38:57,053 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900737057__endTimestamp
18:38:57 INFO - 2015-11-18 18:38:57,053 DEBUG : BROWSER_OUTPUT:
18:38:57 INFO - 2015-11-18 18:38:57,881 INFO : Browser exited with error code: 0
18:38:57 INFO - 2015-11-18 18:38:57,881 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test...
18:38:57 INFO - 2015-11-18 18:38:57,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\tmpnjkny4\profile\sessionstore.js
18:38:57 INFO - 2015-11-18 18:38:57,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\tmpnjkny4\profile\sessionCheckpoints.json
18:38:57 INFO - 2015-11-18 18:38:57,881 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:38:59 INFO - 2015-11-18 18:38:59,038 DEBUG : BROWSER_OUTPUT: __start_report792__end_report
18:38:59 INFO - 2015-11-18 18:38:59,038 DEBUG : BROWSER_OUTPUT:
18:38:59 INFO - 2015-11-18 18:38:59,038 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900739039__endTimestamp
18:38:59 INFO - 2015-11-18 18:38:59,038 DEBUG : BROWSER_OUTPUT:
18:38:59 INFO - 2015-11-18 18:38:59,895 INFO : Browser exited with error code: 0
18:38:59 INFO - 2015-11-18 18:38:59,895 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test...
18:38:59 INFO - 2015-11-18 18:38:59,895 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\tmpnjkny4\profile\sessionstore.js
18:38:59 INFO - 2015-11-18 18:38:59,895 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\tmpnjkny4\profile\sessionCheckpoints.json
18:38:59 INFO - 2015-11-18 18:38:59,895 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:01 INFO - 2015-11-18 18:39:01,036 DEBUG : BROWSER_OUTPUT: __start_report779__end_report
18:39:01 INFO - 2015-11-18 18:39:01,036 DEBUG : BROWSER_OUTPUT:
18:39:01 INFO - 2015-11-18 18:39:01,036 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900741037__endTimestamp
18:39:01 INFO - 2015-11-18 18:39:01,036 DEBUG : BROWSER_OUTPUT:
18:39:01 INFO - 2015-11-18 18:39:01,927 INFO : Browser exited with error code: 0
18:39:01 INFO - 2015-11-18 18:39:01,927 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test...
18:39:01 INFO - 2015-11-18 18:39:01,927 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionstore.js on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile\sessionstore.js
18:39:01 INFO - 2015-11-18 18:39:01,927 DEBUG : Reinstalling C:\slave\test\build\tests\talos\talos/startup_test/sessionrestore/profile\sessionCheckpoints.json on top of c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile\sessionCheckpoints.json
18:39:01 INFO - 2015-11-18 18:39:01,927 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:03 INFO - 2015-11-18 18:39:03,066 DEBUG : BROWSER_OUTPUT: __start_report787__end_report
18:39:03 INFO - 2015-11-18 18:39:03,066 DEBUG : BROWSER_OUTPUT:
18:39:03 INFO - 2015-11-18 18:39:03,066 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900743076__endTimestamp
18:39:03 INFO - 2015-11-18 18:39:03,066 DEBUG : BROWSER_OUTPUT:
18:39:04 INFO - 2015-11-18 18:39:03,987 INFO : Browser exited with error code: 0
18:39:04 INFO - 2015-11-18 18:39:03,987 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test...
18:39:04 INFO - 2015-11-18 18:39:03,987 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\tmpnjkny4\profile\sessionstore.js
18:39:04 INFO - 2015-11-18 18:39:03,987 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\tmpnjkny4\profile\sessionCheckpoints.json
18:39:04 INFO - 2015-11-18 18:39:03,987 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:05 INFO - 2015-11-18 18:39:05,144 DEBUG : BROWSER_OUTPUT: __start_report799__end_report
18:39:05 INFO - 2015-11-18 18:39:05,144 DEBUG : BROWSER_OUTPUT:
18:39:05 INFO - 2015-11-18 18:39:05,144 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900745148__endTimestamp
18:39:05 INFO - 2015-11-18 18:39:05,144 DEBUG : BROWSER_OUTPUT:
18:39:06 INFO - 2015-11-18 18:39:06,111 INFO : Browser exited with error code: 0
18:39:06 INFO - 2015-11-18 18:39:06,111 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test...
18:39:06 INFO - 2015-11-18 18:39:06,111 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\tmpnjkny4\profile\sessionstore.js
18:39:06 INFO - 2015-11-18 18:39:06,111 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\tmpnjkny4\profile\sessionCheckpoints.json
18:39:06 INFO - 2015-11-18 18:39:06,111 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:07 INFO - 2015-11-18 18:39:07,282 DEBUG : BROWSER_OUTPUT: __start_report805__end_report
18:39:07 INFO - 2015-11-18 18:39:07,282 DEBUG : BROWSER_OUTPUT:
18:39:07 INFO - 2015-11-18 18:39:07,282 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900747285__endTimestamp
18:39:07 INFO - 2015-11-18 18:39:07,282 DEBUG : BROWSER_OUTPUT:
18:39:08 INFO - 2015-11-18 18:39:08,236 INFO : Browser exited with error code: 0
18:39:08 INFO - 2015-11-18 18:39:08,236 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test...
18:39:08 INFO - 2015-11-18 18:39:08,236 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\tmpnjkny4\profile\sessionstore.js
18:39:08 INFO - 2015-11-18 18:39:08,236 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\tmpnjkny4\profile\sessionCheckpoints.json
18:39:08 INFO - 2015-11-18 18:39:08,236 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:09 INFO - 2015-11-18 18:39:09,375 DEBUG : BROWSER_OUTPUT: __start_report781__end_report
18:39:09 INFO - 2015-11-18 18:39:09,375 DEBUG : BROWSER_OUTPUT:
18:39:09 INFO - 2015-11-18 18:39:09,375 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900749380__endTimestamp
18:39:09 INFO - 2015-11-18 18:39:09,375 DEBUG : BROWSER_OUTPUT:
18:39:10 INFO - 2015-11-18 18:39:10,312 INFO : Browser exited with error code: 0
18:39:10 INFO - 2015-11-18 18:39:10,312 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test...
18:39:10 INFO - 2015-11-18 18:39:10,312 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\tmpnjkny4\profile\sessionstore.js
18:39:10 INFO - 2015-11-18 18:39:10,312 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\tmpnjkny4\profile\sessionCheckpoints.json
18:39:10 INFO - 2015-11-18 18:39:10,312 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmpnjkny4\profile http://localhost:1741/startup_test/sessionrestore/index.html
18:39:11 INFO - 2015-11-18 18:39:11,467 DEBUG : BROWSER_OUTPUT: __start_report798__end_report
18:39:11 INFO - 2015-11-18 18:39:11,467 DEBUG : BROWSER_OUTPUT:
18:39:11 INFO - 2015-11-18 18:39:11,467 DEBUG : BROWSER_OUTPUT: __startTimestamp1447900751473__endTimestamp
18:39:11 INFO - 2015-11-18 18:39:11,467 DEBUG : BROWSER_OUTPUT:
18:39:12 INFO - 2015-11-18 18:39:12,374 INFO : Browser exited with error code: 0
18:39:12 INFO - 2015-11-18 18:39:12,437 INFO : Completed test sessionrestore_no_auto_restore (00:00:23)
18:39:12 INFO - 2015-11-18 18:39:12,858 INFO : Completed test suite (00:04:27)
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Working with test: a11yr
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Generating results file: a11yr
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Working with test: ts_paint
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Generating results file: ts_paint
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Working with test: tpaint
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Generating results file: tpaint
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Working with test: sessionrestore
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Generating results file: sessionrestore
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Working with test: sessionrestore_no_auto_restore
18:39:12 INFO - 2015-11-18 18:39:12,858 DEBUG : Generating results file: sessionrestore_no_auto_restore
18:39:12 INFO - 2015-11-18 18:39:12,858 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:39:12 INFO - 2015-11-18 18:39:12,904 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,132,37]]
18:39:12 INFO - 2015-11-18 18:39:12,904 DEBUG : process_Request line: a11yr_paint 685.52 graph.html#tests=[[223,132,37]]
18:39:12 INFO - 2015-11-18 18:39:12,904 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:39:12 INFO - 2015-11-18 18:39:12,983 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,132,37]]
18:39:12 INFO - 2015-11-18 18:39:12,983 DEBUG : process_Request line: ts_paint 1060.22 graph.html#tests=[[83,132,37]]
18:39:12 INFO - 2015-11-18 18:39:12,983 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:39:13 INFO - 2015-11-18 18:39:13,013 DEBUG : process_Request line: tpaint graph.html#tests=[[82,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,013 DEBUG : process_Request line: tpaint 253.76 graph.html#tests=[[82,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,013 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:39:13 INFO - 2015-11-18 18:39:13,061 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,061 DEBUG : process_Request line: sessionrestore 2107.79 graph.html#tests=[[313,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,061 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0
18:39:13 INFO - 2015-11-18 18:39:13,108 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,108 DEBUG : process_Request line: sessionrestore_no_auto_restore 805.85 graph.html#tests=[[315,132,37]]
18:39:13 INFO - 2015-11-18 18:39:13,108 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1308.0, 1493.0, 1375.0, 1377.0, 1372.0, 1501.0, 1400.0, 1495.0, 1490.0, 1404.0, 1392.0, 1340.0, 1385.0, 1351.0, 1490.0, 1362.0, 1485.0, 1495.0, 1482.0, 1394.0, 1495.0, 1507.0, 1393.0, 1412.0, 1489.0], "unit": "ms", "name": "dhtml.html", "value": 1408.0}, {"lowerIsBetter": true, "replicates": [322.0, 334.0, 332.0, 344.0, 329.0, 341.0, 335.0, 333.0, 343.0, 329.0, 345.0, 332.0, 334.0, 343.0, 331.0, 325.0, 333.0, 332.0, 337.0, 330.0, 343.0, 334.0, 333.0, 342.0, 332.0], "unit": "ms", "name": "tablemutation.html", "value": 333.5}], "name": "a11yr", "value": 685.5205750740469}, {"subtests": [{"replicates": [1442.0, 1053.0, 1042.0, 1034.0, 1039.0, 1044.0, 1051.0, 1039.0, 1037.0, 1065.0, 1032.0, 1034.0, 1045.0, 1056.0, 1027.0, 1042.0, 1036.0, 1075.0, 1035.0, 1036.0], "name": "ts_paint", "value": 1039.0}], "name": "ts_paint", "value": 1039.0}, {"subtests": [{"replicates": [236.76000000000022, 236.92999999999984, 238.8650000000016, 239.09000000000015, 239.56499999999983, 239.94500000000016, 242.375, 245.16500000000087, 246.875, 248.5699999999997, 250.30000000000018, 252.4349999999995, 253.76000000000022, 257.72499999999854, 259.2399999999998, 279.85000000000036, 282.7649999999994, 289.8100000000013, 291.28499999999985, 301.15999999999985], "name": "tpaint", "value": 253.76000000000022}], "name": "tpaint", "value": 253.76000000000022}, {"subtests": [{"replicates": [2280.0, 2092.0, 2132.0, 2071.0, 2090.0, 2083.0, 2104.0, 2071.0, 2086.0, 2077.0], "name": "sessionrestore", "value": 2086.0}], "name": "sessionrestore", "value": 2086.0}, {"subtests": [{"replicates": [984.0, 775.0, 778.0, 792.0, 779.0, 787.0, 799.0, 805.0, 781.0, 798.0], "name": "sessionrestore_no_auto_restore", "value": 787.0}], "name": "sessionrestore_no_auto_restore", "value": 787.0}]}
18:39:13 INFO - RETURN: a11yr_paint: 685.52
18:39:13 INFO - RETURN: ts_paint: 1060.22
18:39:13 INFO - RETURN: tpaint: 253.76
18:39:13 INFO - RETURN: sessionrestore: 2107.79
18:39:13 INFO - RETURN: sessionrestore_no_auto_restore: 805.85
18:39:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,132,37]]", "result": "2107.79"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,132,37]]", "result": "685.52"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,132,37]]", "result": "253.76"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,132,37]]", "result": "1060.22"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,132,37]]", "result": "805.85"}}}
18:39:13 INFO - Return code: 0
18:39:13 INFO - # TBPL SUCCESS #
18:39:13 INFO - Running post-action listener: _resource_record_post_action
18:39:13 INFO - Running post-run listener: _resource_record_post_run
18:39:14 INFO - Total resource usage - Wall time: 272s; CPU: 11.0%; Read bytes: 47385088; Write bytes: 285090816; Read time: 421833; Write time: 391692
18:39:14 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 0; Write bytes: 57902592; Read time: 0; Write time: 61431
18:39:14 INFO - run-tests - Wall time: 270s; CPU: 11.0%; Read bytes: 47385088; Write bytes: 193362944; Read time: 421833; Write time: 298083
18:39:14 INFO - Running post-run listener: _upload_blobber_files
18:39:14 INFO - Blob upload gear active.
18:39:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
18:39:14 INFO - Copying logs to upload dir...
18:39:14 INFO - mkdir: C:\slave\test\build\upload\logs
program finished with exit code 0
elapsedTime=448.849000
========= master_lag: 4.51 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 7 mins, 33 secs) (at 2015-11-18 18:39:16.708055) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-18 18:39:16.708789) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/artifacts/public/build/firefox-45.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.110000
build_url: 'https://queue.taskcluster.net/v1/task/2y_eSff3TF2IC0K6Pbe5VA/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 18:39:16.828785) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:39:16.829108) =========
'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-119\Application Data
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-XP32-IX-119
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-119
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOGONSERVER=\\T-XP32-IX-119
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-119
USERNAME=cltbld
USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-119
WINDIR=C:\WINDOWS
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.110000
========= master_lag: 0.01 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-18 18:39:16.948101) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-18 18:39:16.948406) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-18 18:39:17.483100) =========
========= Total master_lag: 5.30 =========