builder: mozilla-beta_win7-ix_test-dromaeojs-pgo
slave: t-w732-ix-199
starttime: 1455915130.08
results: success (0)
buildid: 20160219090437
builduid: b4fad0d5b31046ab88b309d166c875fe
revision: b935e0100697
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.089438) =========
master: http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.090009) =========
========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.090308) =========
'cd'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['cd']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
C:\slave\test-pgo
program finished with exit code 0
elapsedTime=0.499000
basedir: 'C:\\slave\\test-pgo'
========= master_lag: 0.04 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.624359) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.624692) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.791501) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:10.791869) =========
'rm' '-rf' 'properties'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'properties']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.502000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:11.307623) =========
========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:11.307944) =========
script_repo_url: https://hg.mozilla.org/build/mozharness
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:11.308311) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:11.308601) =========
'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-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--12:52:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]
0K .......... . 100% 2.27 GB/s
12:52:10 (2.27 GB/s) - `archiver_client.py' saved [12141/12141]
program finished with exit code 0
elapsedTime=0.593000
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:11.919697) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-02-19 12:52:11.920025) =========
'rm' '-rf' 'scripts'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=12.303000
========= master_lag: 0.01 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-02-19 12:52:24.229916) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 12:52:24.230296) =========
'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b935e0100697 --destination scripts --debug'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b935e0100697 --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-02-19 12:52:23,744 Setting DEBUG logging.
2016-02-19 12:52:23,744 attempt 1/10
2016-02-19 12:52:23,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b935e0100697?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-02-19 12:52:24,582 unpacking tar archive at: mozilla-beta-b935e0100697/testing/mozharness/
program finished with exit code 0
elapsedTime=1.715000
========= master_lag: 0.09 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 12:52:26.030738) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:26.031098) =========
'echo' u'b935e0100697'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['echo', u'b935e0100697']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
b935e0100697
program finished with exit code 0
elapsedTime=0.101000
script_repo_revision: 'b935e0100697'
========= master_lag: 0.02 =========
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:26.153329) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:26.153658) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 12:52:26.173172) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-02-19 12:52:26.173568) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Beta' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Beta'
in dir C:\\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test-pgo/buildprops.json
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
12:52:25 INFO - MultiFileLogger online at 20160219 12:52:25 in C:\slave\test-pgo
12:52:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta
12:52:25 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json.
12:52:25 INFO - {'append_to_log': False,
12:52:25 INFO - 'base_work_dir': 'C:\\slave\\test-pgo',
12:52:25 INFO - 'blob_upload_branch': 'Mozilla-Beta',
12:52:25 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt',
12:52:25 INFO - 'branch': 'Mozilla-Beta',
12:52:25 INFO - 'buildbot_json_path': 'buildprops.json',
12:52:25 INFO - 'config_files': ('talos/windows_config.py',),
12:52:25 INFO - 'default_actions': ('clobber',
12:52:25 INFO - 'read-buildbot-config',
12:52:25 INFO - 'download-and-extract',
12:52:25 INFO - 'populate-webroot',
12:52:25 INFO - 'create-virtualenv',
12:52:25 INFO - 'install',
12:52:25 INFO - 'run-tests'),
12:52:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:52:25 INFO - 'download_minidump_stackwalk': True,
12:52:25 INFO - 'download_symbols': 'ondemand',
12:52:25 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
12:52:25 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'),
12:52:25 INFO - 'hg': 'c:/mozilla-build/hg/hg',
12:52:25 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python',
12:52:25 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'),
12:52:25 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
12:52:25 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
12:52:25 INFO - 'C:/mozilla-build/tooltool.py'),
12:52:25 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
12:52:25 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
12:52:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:52:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:52:25 INFO - 'installer_path': 'installer.exe',
12:52:25 INFO - 'log_level': 'info',
12:52:25 INFO - 'log_name': 'talos',
12:52:25 INFO - 'log_to_console': True,
12:52:25 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
12:52:25 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
12:52:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
12:52:25 INFO - 'opt_config_files': (),
12:52:25 INFO - 'pip_index': False,
12:52:25 INFO - 'sps_profile': False,
12:52:25 INFO - 'sps_profile_interval': 0,
12:52:25 INFO - 'suite': 'dromaeojs',
12:52:25 INFO - 'system_bits': '32',
12:52:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:52:25 INFO - 'title': 'T-W732-IX-199',
12:52:25 INFO - 'use_talos_json': True,
12:52:25 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
12:52:25 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv',
12:52:25 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
12:52:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:52:25 INFO - 'work_dir': 'build'}
12:52:25 INFO - #####
12:52:25 INFO - ##### Running clobber step.
12:52:25 INFO - #####
12:52:25 INFO - Running pre-action listener: _resource_record_pre_action
12:52:25 INFO - Running main action method: clobber
12:52:25 INFO - rmtree: C:\slave\test-pgo\build
12:52:25 INFO - Using _rmtree_windows ...
12:52:25 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1
12:53:48 INFO - Running post-action listener: _resource_record_post_action
12:53:48 INFO - #####
12:53:48 INFO - ##### Running read-buildbot-config step.
12:53:48 INFO - #####
12:53:48 INFO - Running pre-action listener: _resource_record_pre_action
12:53:48 INFO - Running main action method: read_buildbot_config
12:53:48 INFO - Using buildbot properties:
12:53:48 INFO - {
12:53:48 INFO - "properties": {
12:53:48 INFO - "buildnumber": 6,
12:53:48 INFO - "product": "firefox",
12:53:48 INFO - "script_repo_revision": "production",
12:53:48 INFO - "builddir": "mozilla-beta_win7-ix_test-dromaeojs",
12:53:48 INFO - "repository": "",
12:53:48 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos dromaeojs",
12:53:48 INFO - "buildid": "20160219090437",
12:53:48 INFO - "slavename": "t-w732-ix-199",
12:53:48 INFO - "pgo_build": "True",
12:53:48 INFO - "basedir": "C:\\slave\\test-pgo",
12:53:48 INFO - "project": "",
12:53:48 INFO - "platform": "win7-ix",
12:53:48 INFO - "master": "http://buildbot-master111.bb.releng.scl3.mozilla.com:8201/",
12:53:48 INFO - "slavebuilddir": "test",
12:53:48 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos",
12:53:48 INFO - "branch": "mozilla-beta",
12:53:48 INFO - "repo_path": "releases/mozilla-beta",
12:53:48 INFO - "stage_platform": "win32-pgo",
12:53:48 INFO - "builduid": "b4fad0d5b31046ab88b309d166c875fe",
12:53:48 INFO - "revision": "b935e0100697"
12:53:48 INFO - },
12:53:48 INFO - "sourcestamp": {
12:53:48 INFO - "repository": "",
12:53:48 INFO - "hasPatch": false,
12:53:48 INFO - "project": "",
12:53:48 INFO - "branch": "mozilla-beta-win32-pgo-talos",
12:53:48 INFO - "changes": [
12:53:48 INFO - {
12:53:48 INFO - "category": null,
12:53:48 INFO - "files": [
12:53:48 INFO - {
12:53:48 INFO - "url": null,
12:53:48 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip"
12:53:48 INFO - }
12:53:48 INFO - ],
12:53:48 INFO - "repository": "",
12:53:48 INFO - "rev": "b935e0100697",
12:53:48 INFO - "who": "sendchange",
12:53:48 INFO - "when": 1455914893,
12:53:48 INFO - "number": 7629191,
12:53:48 INFO - "comments": "Bug 1249551 - _Blank Hello panel in Firefox Beta 7_. r=mdeboer a=sylvestre",
12:53:48 INFO - "project": "",
12:53:48 INFO - "at": "Fri 19 Feb 2016 12:48:13",
12:53:48 INFO - "branch": "mozilla-beta-win32-pgo-talos",
12:53:48 INFO - "revlink": "",
12:53:48 INFO - "properties": [
12:53:48 INFO - [
12:53:48 INFO - "buildid",
12:53:48 INFO - "20160219090437",
12:53:48 INFO - "Change"
12:53:48 INFO - ],
12:53:48 INFO - [
12:53:48 INFO - "builduid",
12:53:48 INFO - "b4fad0d5b31046ab88b309d166c875fe",
12:53:48 INFO - "Change"
12:53:48 INFO - ],
12:53:48 INFO - [
12:53:48 INFO - "pgo_build",
12:53:48 INFO - "True",
12:53:48 INFO - "Change"
12:53:48 INFO - ]
12:53:48 INFO - ],
12:53:48 INFO - "revision": "b935e0100697"
12:53:48 INFO - }
12:53:48 INFO - ],
12:53:48 INFO - "revision": "b935e0100697"
12:53:48 INFO - }
12:53:48 INFO - }
12:53:48 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip.
12:53:48 INFO - Running post-action listener: _resource_record_post_action
12:53:48 INFO - #####
12:53:48 INFO - ##### Running download-and-extract step.
12:53:48 INFO - #####
12:53:48 INFO - Running pre-action listener: _resource_record_pre_action
12:53:48 INFO - Running main action method: download_and_extract
12:53:48 INFO - mkdir: C:\slave\test-pgo\build\tests
12:53:48 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')]}
12:53:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/test_packages.json
12:53:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/test_packages.json to C:\slave\test-pgo\build\test_packages.json
12:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1
12:53:48 INFO - Downloaded 1189 bytes.
12:53:48 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json
12:53:48 INFO - Using the following test package requirements:
12:53:48 INFO - {u'common': [u'firefox-45.0.en-US.win32.common.tests.zip'],
12:53:48 INFO - u'cppunittest': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.cppunittest.tests.zip'],
12:53:48 INFO - u'jittest': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'jsshell-win32.zip'],
12:53:48 INFO - u'mochitest': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.mochitest.tests.zip'],
12:53:48 INFO - u'mozbase': [u'firefox-45.0.en-US.win32.common.tests.zip'],
12:53:48 INFO - u'reftest': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.reftest.tests.zip'],
12:53:48 INFO - u'talos': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.talos.tests.zip'],
12:53:48 INFO - u'web-platform': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.web-platform.tests.zip'],
12:53:48 INFO - u'webapprt': [u'firefox-45.0.en-US.win32.common.tests.zip'],
12:53:48 INFO - u'xpcshell': [u'firefox-45.0.en-US.win32.common.tests.zip',
12:53:48 INFO - u'firefox-45.0.en-US.win32.xpcshell.tests.zip']}
12:53:48 INFO - Downloading packages: [u'firefox-45.0.en-US.win32.common.tests.zip'] for test suite category: common
12:53:48 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')]}
12:53:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip
12:53:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-45.0.en-US.win32.common.tests.zip
12:53:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'}, attempt #1
12:53:49 INFO - Downloaded 17779168 bytes.
12:53:49 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests
12:53:49 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-45.0.en-US.win32.common.tests.zip
12:53:49 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'] with output_timeout 1760
12:54:18 INFO - Return code: 0
12:54:18 INFO - Downloading packages: [u'firefox-45.0.en-US.win32.common.tests.zip', u'firefox-45.0.en-US.win32.talos.tests.zip'] for test suite category: talos
12:54:18 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')]}
12:54:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip
12:54:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-45.0.en-US.win32.common.tests.zip
12:54:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'}, attempt #1
12:54:19 INFO - Downloaded 17779168 bytes.
12:54:19 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests
12:54:19 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-45.0.en-US.win32.common.tests.zip
12:54:19 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.common.tests.zip'] with output_timeout 1760
12:54:41 INFO - Return code: 0
12:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:54:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.talos.tests.zip
12:54:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-45.0.en-US.win32.talos.tests.zip
12:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.talos.tests.zip'}, attempt #1
12:54:42 INFO - Downloaded 11132038 bytes.
12:54:42 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests
12:54:42 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-45.0.en-US.win32.talos.tests.zip
12:54:42 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.talos.tests.zip'] with output_timeout 1760
12:54:46 INFO - Return code: 0
12:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:54:46 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip
12:54:46 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-45.0.en-US.win32.zip
12:54:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.zip'}, attempt #1
12:54:50 INFO - Downloaded 54435320 bytes.
12:54:50 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip
12:54:50 INFO - mkdir: C:\slave\test-pgo\properties
12:54:50 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url
12:54:50 INFO - Writing to file C:\slave\test-pgo\properties\build_url
12:54:50 INFO - Contents:
12:54:50 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip
12:54:50 INFO - Running post-action listener: _resource_record_post_action
12:54:50 INFO - Running post-action listener: set_extra_try_arguments
12:54:50 INFO - #####
12:54:50 INFO - ##### Running populate-webroot step.
12:54:50 INFO - #####
12:54:50 INFO - Running pre-action listener: _resource_record_pre_action
12:54:50 INFO - Running main action method: populate_webroot
12:54:50 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
12:54:50 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
12:54:50 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
12:54:50 INFO - u'--tppagecycles',
12:54:50 INFO - u'7'],
12:54:50 INFO - u'tests': [u'tsvgm']}},
12:54:50 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']},
12:54:50 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'tresize', u'tcanvasmark']},
12:54:50 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']},
12:54:50 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'dromaeo_css',
12:54:50 INFO - u'kraken',
12:54:50 INFO - u'v8_7']},
12:54:50 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:54:50 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
12:54:50 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'tests': [u'damp', u'tps']},
12:54:50 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'damp', u'tps']},
12:54:50 INFO - u'g3': {u'tests': [u'dromaeo_dom']},
12:54:50 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
12:54:50 INFO - u'other': {u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'other-e10s': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'other_l64': {u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'other_nol64': {u'tests': [u'a11yr',
12:54:50 INFO - u'ts_paint',
12:54:50 INFO - u'tpaint',
12:54:50 INFO - u'sessionrestore',
12:54:50 INFO - u'sessionrestore_no_auto_restore']},
12:54:50 INFO - u'svgr': {u'tests': [u'tsvgx',
12:54:50 INFO - u'tsvgr_opacity',
12:54:50 INFO - u'tart',
12:54:50 INFO - u'tscrollx',
12:54:50 INFO - u'cart']},
12:54:50 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'tsvgx',
12:54:50 INFO - u'tsvgr_opacity',
12:54:50 INFO - u'tart',
12:54:50 INFO - u'tscrollx',
12:54:50 INFO - u'cart']},
12:54:50 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'tests': [u'tp5o']},
12:54:50 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'talos_options': [u'--e10s'],
12:54:50 INFO - u'tests': [u'tp5o']},
12:54:50 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'talos_options': [u'--xperf_path',
12:54:50 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:54:50 INFO - u'tests': [u'tp5n']},
12:54:50 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
12:54:50 INFO - u'talos_options': [u'--e10s',
12:54:50 INFO - u'--xperf_path',
12:54:50 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:54:50 INFO - u'tests': [u'tp5n']}},
12:54:50 INFO - u'talos.zip': {u'path': u'',
12:54:50 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
12:54:50 INFO - Running post-action listener: _resource_record_post_action
12:54:50 INFO - #####
12:54:50 INFO - ##### Running create-virtualenv step.
12:54:50 INFO - #####
12:54:50 INFO - Running pre-action listener: _resource_record_pre_action
12:54:50 INFO - Running main action method: create_virtualenv
12:54:50 INFO - Creating virtualenv C:\slave\test-pgo\build/venv
12:54:50 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts
12:54:50 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll
12:54:50 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'!
12:54:50 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build
12:54:50 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv
12:54:55 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe
12:54:59 INFO - Installing distribute......................................................................................................................................................................................done.
12:55:00 INFO - Return code: 0
12:55:00 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv
12:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:00 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:00 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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-pgo\build
12:55:00 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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
12:55:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:00 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:00 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:00 INFO - 'DCLOCATION': 'SCL3',
12:55:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:00 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:00 INFO - 'HOMEDRIVE': 'C:',
12:55:00 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:00 INFO - 'KTS_VERSION': '1.19c',
12:55:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:00 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:00 INFO - 'MOZ_NO_REMOTE': '1',
12:55:00 INFO - 'NO_EM_RESTART': '1',
12:55:00 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:00 INFO - 'OS': 'Windows_NT',
12:55:00 INFO - 'OURDRIVE': 'C:',
12:55:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:55:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:00 INFO - 'PROCESSOR_LEVEL': '6',
12:55:00 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:00 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:00 INFO - 'PROMPT': '$P$G',
12:55:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:00 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:00 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:00 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:00 INFO - 'SYSTEMDRIVE': 'C:',
12:55:00 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:00 INFO - 'TEST1': 'testie',
12:55:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:00 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:00 INFO - 'USERNAME': 'cltbld',
12:55:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:00 INFO - 'WINDIR': 'C:\\windows',
12:55:00 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:01 INFO - Ignoring indexes: http://pypi.python.org/simple/
12:55:01 INFO - Downloading/unpacking pip>=1.5
12:55:06 INFO - Running setup.py egg_info for package pip
12:55:06 INFO - warning: no files found matching 'pip\cacert.pem'
12:55:06 INFO - warning: no files found matching '*.html' under directory 'docs'
12:55:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:55:06 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:55:06 INFO - Installing collected packages: pip
12:55:06 INFO - Found existing installation: pip 0.8.2
12:55:06 INFO - Uninstalling pip:
12:55:06 INFO - Successfully uninstalled pip
12:55:06 INFO - Running setup.py install for pip
12:55:06 INFO - warning: no files found matching 'pip\cacert.pem'
12:55:06 INFO - warning: no files found matching '*.html' under directory 'docs'
12:55:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
12:55:06 INFO - no previously-included directories found matching 'docs\_build\_sources'
12:55:06 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:06 INFO - Successfully installed pip
12:55:06 INFO - Cleaning up...
12:55:06 INFO - Return code: 0
12:55:06 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv
12:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:06 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:06 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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-pgo\build
12:55:06 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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
12:55:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:06 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:06 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:06 INFO - 'DCLOCATION': 'SCL3',
12:55:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:06 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:06 INFO - 'HOMEDRIVE': 'C:',
12:55:06 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:06 INFO - 'KTS_VERSION': '1.19c',
12:55:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:06 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:06 INFO - 'MOZ_NO_REMOTE': '1',
12:55:06 INFO - 'NO_EM_RESTART': '1',
12:55:06 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:06 INFO - 'OS': 'Windows_NT',
12:55:06 INFO - 'OURDRIVE': 'C:',
12:55:06 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:55:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:06 INFO - 'PROCESSOR_LEVEL': '6',
12:55:06 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:06 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:06 INFO - 'PROMPT': '$P$G',
12:55:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:06 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:06 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:06 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:06 INFO - 'SYSTEMDRIVE': 'C:',
12:55:06 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:06 INFO - 'TEST1': 'testie',
12:55:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:06 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:06 INFO - 'USERNAME': 'cltbld',
12:55:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:06 INFO - 'WINDIR': 'C:\\windows',
12:55:06 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:08 INFO - Downloading/unpacking psutil>=0.7.1
12:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
12:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
12:55:09 INFO - Installing collected packages: psutil
12:55:09 INFO - Successfully installed psutil
12:55:09 INFO - Cleaning up...
12:55:09 INFO - Return code: 0
12:55:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv
12:55: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')]}
12:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55: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')]}
12:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:09 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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-pgo\build
12:55:09 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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
12:55:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:09 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:09 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:09 INFO - 'DCLOCATION': 'SCL3',
12:55:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:09 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:09 INFO - 'HOMEDRIVE': 'C:',
12:55:09 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:09 INFO - 'KTS_VERSION': '1.19c',
12:55:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:09 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:09 INFO - 'MOZ_NO_REMOTE': '1',
12:55:09 INFO - 'NO_EM_RESTART': '1',
12:55:09 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:09 INFO - 'OS': 'Windows_NT',
12:55:09 INFO - 'OURDRIVE': 'C:',
12:55: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;',
12:55:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:09 INFO - 'PROCESSOR_LEVEL': '6',
12:55:09 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:09 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:09 INFO - 'PROMPT': '$P$G',
12:55:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:09 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:09 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:09 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:09 INFO - 'SYSTEMDRIVE': 'C:',
12:55:09 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:09 INFO - 'TEST1': 'testie',
12:55:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:09 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:09 INFO - 'USERNAME': 'cltbld',
12:55:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:09 INFO - 'WINDIR': 'C:\\windows',
12:55:09 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0
12:55:10 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
12:55:10 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
12:55:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz
12:55:11 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
12:55:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0)
12:55:11 INFO - Installing collected packages: mozsystemmonitor
12:55:11 INFO - Running setup.py install for mozsystemmonitor
12:55:11 INFO - Successfully installed mozsystemmonitor
12:55:11 INFO - Cleaning up...
12:55:11 INFO - Return code: 0
12:55:11 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv
12:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:11 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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-pgo\build
12:55:11 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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
12:55:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:11 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:11 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:11 INFO - 'DCLOCATION': 'SCL3',
12:55:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:11 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:11 INFO - 'HOMEDRIVE': 'C:',
12:55:11 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:11 INFO - 'KTS_VERSION': '1.19c',
12:55:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:11 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:11 INFO - 'MOZ_NO_REMOTE': '1',
12:55:11 INFO - 'NO_EM_RESTART': '1',
12:55:11 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:11 INFO - 'OS': 'Windows_NT',
12:55:11 INFO - 'OURDRIVE': 'C:',
12:55:11 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;',
12:55:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:11 INFO - 'PROCESSOR_LEVEL': '6',
12:55:11 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:11 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:11 INFO - 'PROMPT': '$P$G',
12:55:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:11 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:11 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:11 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:11 INFO - 'SYSTEMDRIVE': 'C:',
12:55:11 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:11 INFO - 'TEST1': 'testie',
12:55:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:11 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:11 INFO - 'USERNAME': 'cltbld',
12:55:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:11 INFO - 'WINDIR': 'C:\\windows',
12:55:11 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:11 INFO - Downloading/unpacking blobuploader==1.2.4
12:55:11 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
12:55:11 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
12:55:12 INFO - Downloading blobuploader-1.2.4.tar.gz
12:55:12 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
12:55:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
12:55:13 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
12:55:13 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
12:55:13 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests
12:55:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
12:55:14 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
12:55:14 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
12:55:14 INFO - Downloading docopt-0.6.1.tar.gz
12:55:14 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt
12:55:14 INFO - Installing collected packages: blobuploader, requests, docopt
12:55:14 INFO - Running setup.py install for blobuploader
12:55:14 INFO - Running setup.py install for requests
12:55:15 INFO - Running setup.py install for docopt
12:55:15 INFO - Successfully installed blobuploader requests docopt
12:55:15 INFO - Cleaning up...
12:55:16 INFO - Return code: 0
12:55:16 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
12:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:16 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\config
12:55:16 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test-pgo\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
12:55:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:16 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:16 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:16 INFO - 'DCLOCATION': 'SCL3',
12:55:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:16 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:16 INFO - 'HOMEDRIVE': 'C:',
12:55:16 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:16 INFO - 'KTS_VERSION': '1.19c',
12:55:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:16 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:16 INFO - 'MOZ_NO_REMOTE': '1',
12:55:16 INFO - 'NO_EM_RESTART': '1',
12:55:16 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:16 INFO - 'OS': 'Windows_NT',
12:55:16 INFO - 'OURDRIVE': 'C:',
12:55:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:55:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:16 INFO - 'PROCESSOR_LEVEL': '6',
12:55:16 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:16 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:16 INFO - 'PROMPT': '$P$G',
12:55:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:16 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:16 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:16 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:16 INFO - 'SYSTEMDRIVE': 'C:',
12:55:16 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:16 INFO - 'TEST1': 'testie',
12:55:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:16 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:16 INFO - 'USERNAME': 'cltbld',
12:55:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:16 INFO - 'WINDIR': 'C:\\windows',
12:55:16 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:16 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
12:55:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bihsih-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybsacp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dwzbgm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2zdlwm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4pyml4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ptxvvs-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
12:55:17 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
12:55:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4xw1am-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ek0uv3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cf5rql-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zvh7f0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-egc0rz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uv7fqp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1vwnei-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dizvvk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
12:55:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
12:55:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-apl7i9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
12:55:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
12:55:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y7jbqw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
12:55:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
12:55:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mcqjmd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
12:55:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:55:19 INFO - Running setup.py install for manifestparser
12:55:19 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:19 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:19 INFO - Running setup.py install for mozcrash
12:55:20 INFO - Running setup.py install for mozdebug
12:55:20 INFO - Running setup.py install for mozdevice
12:55:20 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:20 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:20 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:20 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:21 INFO - Running setup.py install for mozfile
12:55:22 INFO - Running setup.py install for mozhttpd
12:55:22 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:22 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:22 INFO - Running setup.py install for mozinfo
12:55:22 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:22 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:22 INFO - Running setup.py install for mozInstall
12:55:23 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Running setup.py install for mozleak
12:55:23 INFO - Running setup.py install for mozlog
12:55:23 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:23 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:24 INFO - Running setup.py install for moznetwork
12:55:24 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:24 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:24 INFO - Running setup.py install for mozprocess
12:55:25 INFO - Running setup.py install for mozprofile
12:55:25 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:25 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:25 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:25 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:25 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:25 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:26 INFO - Running setup.py install for mozrunner
12:55:26 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:26 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:26 INFO - Running setup.py install for mozscreenshot
12:55:27 INFO - Running setup.py install for moztest
12:55:27 INFO - Running setup.py install for mozversion
12:55:27 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:27 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
12:55:27 INFO - Cleaning up...
12:55:27 INFO - Return code: 0
12:55:27 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
12:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\config
12:55:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\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
12:55:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:27 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:27 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:27 INFO - 'DCLOCATION': 'SCL3',
12:55:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:27 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:27 INFO - 'HOMEDRIVE': 'C:',
12:55:27 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:27 INFO - 'KTS_VERSION': '1.19c',
12:55:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:27 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:27 INFO - 'MOZ_NO_REMOTE': '1',
12:55:27 INFO - 'NO_EM_RESTART': '1',
12:55:27 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:27 INFO - 'OS': 'Windows_NT',
12:55:27 INFO - 'OURDRIVE': 'C:',
12:55:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
12:55:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:27 INFO - 'PROCESSOR_LEVEL': '6',
12:55:27 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:27 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:27 INFO - 'PROMPT': '$P$G',
12:55:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:27 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:27 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:27 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:27 INFO - 'SYSTEMDRIVE': 'C:',
12:55:27 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:27 INFO - 'TEST1': 'testie',
12:55:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:27 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:27 INFO - 'USERNAME': 'cltbld',
12:55:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:27 INFO - 'WINDIR': 'C:\\windows',
12:55:27 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser
12:55:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-riuz1j-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser
12:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1))
12:55:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash
12:55:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v_7auh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash
12:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
12:55:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug
12:55:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_jzc77-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug
12:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3))
12:55:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice
12:55:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b4o53f-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice
12:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
12:55:28 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile
12:55:28 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lhr_ur-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wb6kjc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qc5ika-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-whu3lk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bi61ha-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vyzkim-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b5soi9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess
12:55:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-doch_c-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess
12:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12))
12:55:29 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile
12:55:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gekcmq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13))
12:55:30 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner
12:55:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9iedkp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14))
12:55:30 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot
12:55:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zgtihl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15))
12:55:30 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest
12:55:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jnaa6r-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16))
12:55:30 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion
12:55:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5bqvsa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17))
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2))
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3))
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
12:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4))
12:55:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10))
12:55:30 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
12:55:30 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
12:55:31 INFO - Downloading blessings-1.6.tar.gz
12:55:31 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings
12:55:32 INFO - Installing collected packages: blessings
12:55:32 INFO - Running setup.py install for blessings
12:55:32 INFO - Successfully installed blessings
12:55:32 INFO - Cleaning up...
12:55:35 INFO - Return code: 0
12:55:35 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv.
12:55:35 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
12:55:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
12:55:35 INFO - Reading from file tmpfile_stdout
12:55:35 INFO - Using _rmtree_windows ...
12:55:35 INFO - Using _rmtree_windows ...
12:55:35 INFO - Current package versions:
12:55:35 INFO - blessings == 1.6
12:55:35 INFO - blobuploader == 1.2.4
12:55:35 INFO - docopt == 0.6.1
12:55:35 INFO - manifestparser == 1.1
12:55:35 INFO - mozInstall == 1.12
12:55:35 INFO - mozcrash == 0.16
12:55:35 INFO - mozdebug == 0.1
12:55:35 INFO - mozdevice == 0.47
12:55:35 INFO - mozfile == 1.2
12:55:35 INFO - mozhttpd == 0.7
12:55:35 INFO - mozinfo == 0.9
12:55:35 INFO - mozleak == 0.1
12:55:35 INFO - mozlog == 3.1
12:55:35 INFO - moznetwork == 0.27
12:55:35 INFO - mozprocess == 0.22
12:55:35 INFO - mozprofile == 0.27
12:55:35 INFO - mozrunner == 6.11
12:55:35 INFO - mozscreenshot == 0.1
12:55:35 INFO - mozsystemmonitor == 0.0
12:55:35 INFO - moztest == 0.7
12:55:35 INFO - mozversion == 1.4
12:55:35 INFO - psutil == 3.1.1
12:55:35 INFO - requests == 1.2.3
12:55:35 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv
12:55:35 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')]}
12:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:35 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')]}
12:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:35 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\talos
12:55:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\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
12:55:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:35 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:35 INFO - 'DCLOCATION': 'SCL3',
12:55:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:35 INFO - 'HOMEDRIVE': 'C:',
12:55:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:35 INFO - 'KTS_VERSION': '1.19c',
12:55:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:35 INFO - 'MOZ_NO_REMOTE': '1',
12:55:35 INFO - 'NO_EM_RESTART': '1',
12:55:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:35 INFO - 'OS': 'Windows_NT',
12:55:35 INFO - 'OURDRIVE': 'C:',
12:55:35 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;',
12:55:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:35 INFO - 'PROCESSOR_LEVEL': '6',
12:55:35 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:35 INFO - 'PROMPT': '$P$G',
12:55:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:35 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:35 INFO - 'SYSTEMDRIVE': 'C:',
12:55:35 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:35 INFO - 'TEST1': 'testie',
12:55:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:35 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:35 INFO - 'USERNAME': 'cltbld',
12:55:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:35 INFO - 'WINDIR': 'C:\\windows',
12:55:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3))
12:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1))
12:55:35 INFO - Cleaning up...
12:55:35 INFO - Return code: 0
12:55:35 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv
12:55:35 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')]}
12:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:35 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')]}
12:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:35 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test-pgo\build
12:55:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
12:55:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:35 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:35 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:35 INFO - 'DCLOCATION': 'SCL3',
12:55:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:35 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:35 INFO - 'HOMEDRIVE': 'C:',
12:55:35 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:35 INFO - 'KTS_VERSION': '1.19c',
12:55:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:35 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:35 INFO - 'MOZ_NO_REMOTE': '1',
12:55:35 INFO - 'NO_EM_RESTART': '1',
12:55:35 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:35 INFO - 'OS': 'Windows_NT',
12:55:35 INFO - 'OURDRIVE': 'C:',
12:55:35 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;',
12:55:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:35 INFO - 'PROCESSOR_LEVEL': '6',
12:55:35 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:35 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:35 INFO - 'PROMPT': '$P$G',
12:55:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:35 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:35 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:35 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:35 INFO - 'SYSTEMDRIVE': 'C:',
12:55:35 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:35 INFO - 'TEST1': 'testie',
12:55:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:35 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:35 INFO - 'USERNAME': 'cltbld',
12:55:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:35 INFO - 'WINDIR': 'C:\\windows',
12:55:35 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:36 INFO - Downloading/unpacking jsonschema
12:55:36 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
12:55:36 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
12:55:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
12:55:37 INFO - Downloading/unpacking functools32 (from jsonschema)
12:55: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
12:55: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
12:55:37 INFO - Downloading functools32-3.2.3-2.tar.gz
12:55:37 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32
12:55:37 INFO - warning: no files found matching '*.txt'
12:55:37 INFO - no previously-included directories found matching 'build'
12:55:37 INFO - no previously-included directories found matching 'dist'
12:55:37 INFO - no previously-included directories found matching '.git*'
12:55:37 INFO - Installing collected packages: jsonschema, functools32
12:55:37 INFO - Running setup.py install for functools32
12:55:37 INFO - warning: no files found matching '*.txt'
12:55:37 INFO - no previously-included directories found matching 'build'
12:55:37 INFO - no previously-included directories found matching 'dist'
12:55:37 INFO - no previously-included directories found matching '.git*'
12:55:37 INFO - Successfully installed jsonschema functools32
12:55:37 INFO - Cleaning up...
12:55:38 INFO - Return code: 0
12:55:38 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv
12:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
12:55:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:55:38 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018AB6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01952EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0185AF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-199', 'USERDOMAIN': 'T-W732-IX-199', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-199', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
12:55:38 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in C:\slave\test-pgo\build
12:55:38 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8
12:55:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:38 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:38 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:38 INFO - 'DCLOCATION': 'SCL3',
12:55:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:38 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:38 INFO - 'HOMEDRIVE': 'C:',
12:55:38 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:38 INFO - 'KTS_VERSION': '1.19c',
12:55:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:38 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:38 INFO - 'MOZ_NO_REMOTE': '1',
12:55:38 INFO - 'NO_EM_RESTART': '1',
12:55:38 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:38 INFO - 'OS': 'Windows_NT',
12:55:38 INFO - 'OURDRIVE': 'C:',
12:55:38 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;',
12:55:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:38 INFO - 'PROCESSOR_LEVEL': '6',
12:55:38 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:38 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:38 INFO - 'PROMPT': '$P$G',
12:55:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:38 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:38 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:38 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:38 INFO - 'SYSTEMDRIVE': 'C:',
12:55:38 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:38 INFO - 'TEST1': 'testie',
12:55:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:38 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:38 INFO - 'USERNAME': 'cltbld',
12:55:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:38 INFO - 'WINDIR': 'C:\\windows',
12:55:38 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/
12:55:38 INFO - Downloading/unpacking flake8
12:55:38 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
12:55:38 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
12:55:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl
12:55:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8)
12:55:39 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
12:55:39 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
12:55:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl
12:55:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8)
12:55:39 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
12:55:39 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
12:55:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8)
12:55:39 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
12:55:39 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
12:55:40 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8
12:55:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
12:55:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
12:55:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
12:55:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
12:55:40 INFO - no previously-included directories found matching 'docs\_build'
12:55:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8
12:55:40 INFO - Running setup.py install for pep8
12:55:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs'
12:55:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs'
12:55:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite'
12:55:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite'
12:55:40 INFO - no previously-included directories found matching 'docs\_build'
12:55:40 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts
12:55:40 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts
12:55:40 INFO - Successfully installed flake8 mccabe pyflakes pep8
12:55:40 INFO - Cleaning up...
12:55:41 INFO - Return code: 0
12:55:41 INFO - Running post-action listener: _resource_record_post_action
12:55:41 INFO - Running post-action listener: _start_resource_monitoring
12:55:41 INFO - Starting resource monitoring.
12:55:41 INFO - #####
12:55:41 INFO - ##### Running install step.
12:55:41 INFO - #####
12:55:41 INFO - Running pre-action listener: _resource_record_pre_action
12:55:41 INFO - Running main action method: install
12:55:41 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze']
12:55:41 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze
12:55:41 INFO - Reading from file tmpfile_stdout
12:55:41 INFO - Using _rmtree_windows ...
12:55:41 INFO - Using _rmtree_windows ...
12:55:41 INFO - Detecting whether we're running mozinstall >=1.0...
12:55:41 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h']
12:55:41 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h
12:55:41 INFO - Reading from file tmpfile_stdout
12:55:41 INFO - Output received:
12:55:41 INFO - Usage: mozinstall-script.py [options] installer
12:55:41 INFO - Options:
12:55:41 INFO - -h, --help show this help message and exit
12:55:41 INFO - -d DEST, --destination=DEST
12:55:41 INFO - Directory to install application into. [default:
12:55:41 INFO - "C:\slave\test-pgo"]
12:55:41 INFO - --app=APP Application being installed. [default: firefox]
12:55:41 INFO - Using _rmtree_windows ...
12:55:41 INFO - Using _rmtree_windows ...
12:55:41 INFO - mkdir: C:\slave\test-pgo\build\application
12:55:41 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-45.0.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application']
12:55:41 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-45.0.en-US.win32.zip --destination C:\slave\test-pgo\build\application
12:55:43 INFO - Reading from file tmpfile_stdout
12:55:43 INFO - Output received:
12:55:43 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe
12:55:43 INFO - Using _rmtree_windows ...
12:55:43 INFO - Using _rmtree_windows ...
12:55:43 INFO - Running post-action listener: _resource_record_post_action
12:55:43 INFO - #####
12:55:43 INFO - ##### Running run-tests step.
12:55:43 INFO - #####
12:55:43 INFO - Running pre-action listener: _resource_record_pre_action
12:55:43 INFO - Running main action method: run_tests
12:55:43 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version']
12:55:43 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version
12:55:43 INFO - Python 2.7.3
12:55:43 INFO - Return code: 0
12:55:43 INFO - grabbing minidump binary from tooltool
12:55: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')]}
12:55: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-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8C98>, '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 0x012DA860>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A1D5C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1
12:55: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-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build
12:55: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-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o
12:55:43 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
12:55:44 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpj9ss9p
12:55:44 INFO - INFO - File integrity verified, renaming tmpj9ss9p to win32-minidump_stackwalk.exe
12:55:44 INFO - Return code: 0
12:55:44 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755
12:55:44 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir
12:55:44 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir
12:55:44 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe
12:55:44 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir
12:55:44 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos']
12:55:44 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos
12:55:45 INFO - Return code: 0
12:55:45 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-199', '--webServer', 'localhost'] in C:\slave\test-pgo\build
12:55:45 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-199 --webServer localhost
12:55:45 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
12:55:45 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
12:55:45 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
12:55:45 INFO - 'COMPUTERNAME': 'T-W732-IX-199',
12:55:45 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
12:55:45 INFO - 'CYGWINBASE': 'C:\\cygwin',
12:55:45 INFO - 'DCLOCATION': 'SCL3',
12:55:45 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
12:55:45 INFO - 'FP_NO_HOST_CHECK': 'NO',
12:55:45 INFO - 'HOMEDRIVE': 'C:',
12:55:45 INFO - 'HOMEPATH': '\\Users\\cltbld',
12:55:45 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
12:55:45 INFO - 'KTS_VERSION': '1.19c',
12:55:45 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
12:55:45 INFO - 'LOGONSERVER': '\\\\T-W732-IX-199',
12:55:45 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
12:55:45 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe',
12:55:45 INFO - 'MONDIR': 'C:\\Monitor_config\\',
12:55:45 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
12:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:45 INFO - 'MOZ_NO_REMOTE': '1',
12:55:45 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir',
12:55:45 INFO - 'NO_EM_RESTART': '1',
12:55:45 INFO - 'NUMBER_OF_PROCESSORS': '8',
12:55:45 INFO - 'OS': 'Windows_NT',
12:55:45 INFO - 'OURDRIVE': 'C:',
12:55:45 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;',
12:55:45 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
12:55:45 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
12:55:45 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
12:55:45 INFO - 'PROCESSOR_LEVEL': '6',
12:55:45 INFO - 'PROCESSOR_REVISION': '1e05',
12:55:45 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
12:55:45 INFO - 'PROGRAMFILES': 'C:\\Program Files',
12:55:45 INFO - 'PROMPT': '$P$G',
12:55:45 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json',
12:55:45 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
12:55:45 INFO - 'PUBLIC': 'C:\\Users\\Public',
12:55:45 INFO - 'PWD': 'C:\\slave\\test-pgo',
12:55:45 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos',
12:55:45 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
12:55:45 INFO - 'SLAVEDIR': 'C:\\slave\\',
12:55:45 INFO - 'SYSTEMDRIVE': 'C:',
12:55:45 INFO - 'SYSTEMROOT': 'C:\\windows',
12:55:45 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:45 INFO - 'TEST1': 'testie',
12:55:45 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
12:55:45 INFO - 'USERDOMAIN': 'T-W732-IX-199',
12:55:45 INFO - 'USERNAME': 'cltbld',
12:55:45 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
12:55:45 INFO - 'WINDIR': 'C:\\windows',
12:55:45 INFO - 'WINDOWS_TRACING_FLAGS': '3',
12:55:45 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
12:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
12:55:45 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-199', '--webServer', 'localhost'] with output_timeout 3600
12:55:46 INFO - mozversion INFO | application_buildid: 20160219090437
12:55:46 INFO - mozversion INFO | application_changeset: b935e0100697bde8bfc19d56a7ae6beb8722dff5
12:55:46 INFO - mozversion INFO | application_display_name: Nightly
12:55:46 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:55:46 INFO - mozversion INFO | application_name: Firefox
12:55:46 INFO - mozversion INFO | application_remotingname: firefox
12:55:46 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta
12:55:46 INFO - mozversion INFO | application_vendor: Mozilla
12:55:46 INFO - mozversion INFO | application_version: 45.0
12:55:46 INFO - mozversion INFO | platform_buildid: 20160219090437
12:55:46 INFO - mozversion INFO | platform_changeset: b935e0100697bde8bfc19d56a7ae6beb8722dff5
12:55:46 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta
12:55:46 INFO - mozversion INFO | platform_version: 45.0
12:55:46 INFO - 2016-02-19 12:55:46,579 DEBUG : using testdate: 1455915346
12:55:46 INFO - 2016-02-19 12:55:46,579 DEBUG : actual date: 1455915346
12:55:46 INFO - 2016-02-19 12:55:46,671 INFO : starting webserver on 'localhost:49570'
12:55:46 INFO - 2016-02-19 12:55:46,673 INFO : Starting test suite T-W732-IX-199
12:55:46 INFO - 2016-02-19 12:55:46,673 INFO : Starting test dromaeo_css
12:55:46 INFO - 2016-02-19 12:55:46,674 DEBUG : operating with platform_type : w7_
12:55:46 INFO - 2016-02-19 12:55:46,674 INFO : Initialising browser for dromaeo_css test...
12:55:46 INFO - 2016-02-19 12:55:46,756 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqwi5gq\profile http://localhost:49570/getInfo.html
12:55:55 INFO - 2016-02-19 12:55:55,134 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
12:55:55 INFO - 2016-02-19 12:55:55,134 DEBUG : BROWSER_OUTPUT: colorDepth:24
12:55:55 INFO - 2016-02-19 12:55:55,134 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
12:55:55 INFO - 2016-02-19 12:55:55,134 DEBUG : BROWSER_OUTPUT: __metrics
12:55:55 INFO - 2016-02-19 12:55:55,335 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
12:55:56 INFO - 2016-02-19 12:55:56,450 INFO : Browser initialized.
12:55:56 INFO - 2016-02-19 12:55:56,450 INFO : Running cycle 1/1 for dromaeo_css test...
12:55:56 INFO - 2016-02-19 12:55:56,450 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqwi5gq\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
12:55:57 INFO - 2016-02-19 12:55:57,015 DEBUG : BROWSER_OUTPUT: [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
12:56:07 INFO - 2016-02-19 12:56:07,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 107814912
12:56:07 INFO - 2016-02-19 12:56:07,894 DEBUG : BROWSER_OUTPUT:
12:56:59 INFO - 2016-02-19 12:56:59,132 DEBUG : BROWSER_OUTPUT: 1455915419108 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]
12:59:07 INFO - 2016-02-19 12:59:07,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49570/tests/dromaeo/cssquery-ext.html)
12:59:09 INFO - 2016-02-19 12:59:09,467 DEBUG : BROWSER_OUTPUT: RSS: Main: 273174528
12:59:09 INFO - 2016-02-19 12:59:09,467 DEBUG : BROWSER_OUTPUT:
13:02:11 INFO - 2016-02-19 13:02:11,815 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-ext.html (next: http://localhost:49570/tests/dromaeo/cssquery-jquery.html)
13:02:12 INFO - 2016-02-19 13:02:12,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 183808000
13:02:12 INFO - 2016-02-19 13:02:12,375 DEBUG : BROWSER_OUTPUT:
13:05:13 INFO - 2016-02-19 13:05:13,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49570/tests/dromaeo/cssquery-mootools.html)
13:05:14 INFO - 2016-02-19 13:05:14,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 138252288
13:05:14 INFO - 2016-02-19 13:05:14,539 DEBUG : BROWSER_OUTPUT:
13:08:16 INFO - 2016-02-19 13:08:16,227 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49570/tests/dromaeo/cssquery-prototype.html)
13:08:16 INFO - 2016-02-19 13:08:16,790 DEBUG : BROWSER_OUTPUT: RSS: Main: 126128128
13:08:16 INFO - 2016-02-19 13:08:16,790 DEBUG : BROWSER_OUTPUT:
13:11:19 INFO - 2016-02-19 13:11:19,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49570/tests/dromaeo/cssquery-yui.html)
13:11:19 INFO - 2016-02-19 13:11:19,759 DEBUG : BROWSER_OUTPUT: RSS: Main: 127684608
13:11:19 INFO - 2016-02-19 13:11:19,759 DEBUG : BROWSER_OUTPUT:
13:14:21 INFO - 2016-02-19 13:14:21,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/dromaeo/cssquery-yui.html (next: http://localhost:49570/tests/dromaeo/cssquery-dojo.html)
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT: RSS: Main: 171114496
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT: __start_tp_report
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
13:14:22 INFO - 2016-02-19 13:14:22,085 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
13:14:22 INFO - 2016-02-19 13:14:22,086 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2906.2734316420892;2960.659524154723;2962.851857407129;2979.9851000744993;3000.924976875579;5302.098643230651;5373.9193912091305;5385.892282154365;5444.673319600822;5446.673199608026;5345.652532585379;5410.215518749779;5445.401005889349;5487.012337779198;5489.258950041743;4588.541145885401;4653.720776753389;4706.5293470653005;4779.665423420362;4786.138495070886;4638.907221855561;4661.673682842203;4691.225947718623;4691.554302341272;4693.601043911254;4730.787114579852;4737.313089602006;4750.809967601292;4754.096721622898;4764.880877978044;4457.888552786174;4564.475085365176;4614.100250451163;4747.643926705511;4748.216544270192;4594;4612.492625811192;4716.52834716529;4763.857084287477;4780.521947805227;5256.526912577886;5263.31576895005;5329.8934021319355;5368.8389348319615;5436.157395603648;5277.234800953878;5359.839204823823;5362.249285100089;5435.0488664483955;5579;5588.21764952907;5617.297837770278;5667.489925906688;5687.544996400278;5707.001274776898;5176.7411629418375;5186.325777648919;5291.629585928987;5298.6555873868465;5304.230886521433;5523.944760552343;5650.208970744099;5657.2928383952;5662.235598194274;5667.319921609434;5438.700871452029;5467.9453205467435;5472.753726082336;5478.205660179252;5479.4520547945685;5319.441458646864;5401.7029063401105;5560.833175004756;5566.55467562594;5573.27547418833;5420.945790542123;5500;5519.420460851633;5524.751386187631;5545.3622833374675;5452.672839629635;5516.117421212587;5546.362168350611;5552.500274975271;5557.5831812614215;3542.468629705513;3558.3594952908725;3578.4274516077303;3629.1834337273904;3629.4555816627717;3293.045016945059;3303.3723592517345;3327.916802079919;3338.0987133473827;3341.298327351235;5254.65844720092;5338.492843179892;5370.087085195526;5371.086915224421;5484.890302193934;5603.411641777636;5696.174054762119;5719.7140142992685;5729.856753581111;5746.770129194869;5407.567394608422;5413.9729301353245;5451.4276001019325;5473.9452605474235;5511;4290.227759003347;4445.266531022345;4451.532589078165;4453.933191002137;4454.57681520255;5711.9714401428555;5838.153467747238;5874.236349274653;5936.851578710567;5996.640201587831;5682.289713785777;5690.743916523684;5747.339056008613;5751.683657398883;5764.481196692318;5707.143928410772;5817.563682723728;5822.301323841166;5823.592348535732;5894.734736936763;5469.234307196916;5482.588805839658;5498.532624726943;5509.779608815603;5512.944870551243;5590.105583106683;5649.774009039593;5672.688002159921;5675.4040825714355;5677.148427735708;5462;5480.890382192413;5506.201600767947;5530.668159910419;5551.7779288828;5403.378611459575;5486.835394938158;5499.1476321170285;5504.119340905436;5507.339119305709;5058.140116180185;5396.703181325065;5397.487238712316;5406.675599464044;5409.0263752524925;5466.426025267289;5510.1459273812625;5528.253685752372;5538.224648549126;5582.078956972054;5439.646422982493;5447.455254474679;5478.671279723229;5550.666959982414;5552.611317207757
13:14:22 INFO - 2016-02-19 13:14:22,086 DEBUG : BROWSER_OUTPUT: |1;ext.html;508.7049511283456;522.6106550619788;531.5906751801116;535.2506490912722;536.661903001109;30412.543811842814;39282.8035859821;39989.80005099971;40070.59752908649;40079.59920400795;42698.573014269845;42976.785116074454;43029.139417211634;43138.568614313845;43143.35284970728;7656.770296891074;7761.22387761225;7906.881396779053;8002.199780021981;8090.231428014329;10134.645287414942;10263.538140783645;10430.530626121843;10431.634892778755;10449.791004179911;9350.251979841596;10288.331258468175;10350.637727679532;10358.223133264997;10373.170146388271;7582.924170758277;7711.2288771123;9927.702168934944;10199.49002549877;10480.737981550446;9505.334626576143;10137.493125343703;10179.796404071878;10192.898071019268;10196.796064078677;36529.81735091334;39122.413163802565;39277.214455711015;39301;39305;3116;3136.8431578420987;3175.063356309894;3222.3394204188085;3286.3263031078573;29466;30864.845675771703;31149;31806.363872722417;31961.360772784647;3610.1696609779633;3742.4760533525327;3757.079515518715;3784.8486060557543;3787.2236191580937;302822;304185.9999999978;305283;305325;307471;49647;50888.745556271984;54942.725286373316;55929;57273.14090288649;52274.73862630662;53508.197377039374;53648.463515365125;53720.462795371546;53845.730771345894;13415.195088294733;13833.515826946008;13917.721645567235;13965.092269002482;13974.301284935713;10528.631497897537;10716.73208169802;10740.946295268473;10758;10775.137988960865;18544.629107418044;18824.15291311894;19086.522836929187;19097.13542968538;19122.52193695169;7565.167831538526;7760.068791745027;7761.107472640606;7765.301122898966;7774.572398518136;905.5834316214615;981.038582189444;1027.4041056187393;1027.6916924922493;1090.1823632275793;28367;28686.99595514147;28922.710772892002;28988.27529311784;29080.418391632047;1010.4594042187395;1031.231732359397;1035.49260862177;1082.983755243672;1088.2599832114238;14468.131912085308;14565.198914059829;14937.70124597502;14965.625859353604;15297;313669;317400;339460;405679;432267;48369.75815120902;49540.50459495431;49583.75208123936;49766.50233497618;50050.24924626134;1191.8510186226722;1242.3974372429363;1244.464880101547;1253.3983687829711;1254.4856608790353;25523.6171457432;30028.699713003465;30141.588672906553;31199.688003119678;31213.219669508442;44583.77708111569;45794.31308530308;46118.76940615275;46192.30711539262;46290.76854615705;257828;268448;269341;270416;271586;29274.560881586367;41114.383284250165;42007.369889451074;43075.353869692604;43216.35175472433;41909.58090419057;42510.787446062575;43367;43689.781551093314;43694.56305436905;36834.63165368312;36884.631153689195;37806.43290350592;37965.24069518649;38427.42358864563;35407;35969.82015090012;36514.8174259127;36937.261254775294;37266.8136659315;35492.82253588716;36449.088772780895;36976.445353319185;37104.07239819026;37288;33099.66900330966;33188.17029574183;33295.684223041026;33972.66027339695;34298.82850585731;22249.888750556143;26921.577058064868;27161.320966975985;28257.293567660974;28318.85840570784
13:14:22 INFO - 2016-02-19 13:14:22,096 DEBUG : BROWSER_OUTPUT: |2;jquery.html;290.0876742644385;292.926768307923;294.4435017816326;297.9061595597387;300.6437371714518;13461.326933653327;15608.531744047668;16772.83227167728;17134.571635709115;17167;16226.269817858196;16545.586360340996;17365.913170434163;17633.470995870113;17676;4548.135854187702;4612.331211974261;4634.281686338623;4637.744924029177;4639.65202609804;3881.1849511602595;3967.36522156455;4552.044070745131;5188.948110518884;5299.49654782795;5155.252488389181;5208.765605547759;5258.815941442051;5278.2082687596785;5279.076161671711;5251.081060814342;5251.606129540281;5264.552513036378;5668.971655141698;5936.257967754031;5096.490350964911;5105.438401775802;5105.897882042338;5106.336176297058;5108.744562771846;20382.286619968378;20568.794312056987;21311.467213319636;21316.78683213179;21352.46618834526;1911.8852868827776;1950.697641865513;1950.8146726061002;2077.1379877350882;2096.6959790830397;8884.8667269991;8920.330975176823;8953.149450802164;9023.909760902372;10332.896671033268;1719.2005717341372;1732.2897611979029;1769.6814573376787;1810.5292623917876;1818.5362732503127;70205;72051;72052;72538.63730681312;72987;9294.117058879396;9298.767530811783;9400.29497787655;9444;9449.110177964298;11209.327440353605;11492.965633093061;11500.642368314642;11508.136889733303;11535.942320288344;7610.429217808686;7671.347935425551;7684.6157692116285;7690.15408305086;7694.192109828499;7803.414743894231;7842.960785196037;7973.44185906981;7999.840003199904;8033;3724.273766615484;3761.7366784324836;3781.300459415017;3829.5978922213326;3832.2335532893535;1507.4422463688506;1532.2338830584708;1579.5893067802224;1586.904785712861;1587.09535564727;845.2350622686479;898.7618281155499;902.251131560803;983.311681822727;1002.0030070080317;16232.269547870374;16377;16874.83125168757;16984.74522882158;17158.914205428893;931.1386967055447;958.3531116496337;967.4969016111723;986.5018165826211;1012.1953047327393;9764.853527197098;10247.692569222936;10282.228832837567;10330.638427654996;10488.31825931312;411895;416519;430931;433064;436412;83674;84801.79184769363;85481.57259213664;86104.56947715346;86130;961.5096300886458;977.7506735782472;994.1053052252886;1000.2448151645461;1027.409239687198;16107.087338361913;16239.51281461558;16241.51275461738;16262.91868540697;16264.918675406547;39777.80111099426;40142.598574013886;40579;41644.791776040925;41737.37393939154;53873;53954.46045539495;54400.72799635977;55170.44829551653;55259.17111243415;8182.549959752271;8351.916480835114;8400.495970241805;8773.42972706772;8791.032986371367;10292.691219263434;10457.581696732046;10467.790644187226;10468.947655261674;10476.42379669095;12166;14956.326965286371;15144.242787860783;15262.313195905988;15309.311081001159;14876.330565124379;15164;15245.16151611672;15318.540443786704;15600.687986240438;15353.846461535242;15670.294836732603;15873.920630396775;16098.27557759927;16333.183340833148;8683.782905427415;8740.868886966828;8756.168164024408;8773.605187766438;9037.457752534869;12254.816177757162;15363;15402.15288159162;15523.22383880824;15692.215389230721
13:14:22 INFO - 2016-02-19 13:14:22,098 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1163.156711384246;1241.1932244041373;1255.5354518828033;1262.2552693910598;1509.3132624655782;6551.279359270482;6847.520673552854;7176.748813791518;7188.676509557069;7194.136703595569;7034.9296507034915;7107.466939979509;7221.855562888739;7239.20368759436;7242.493025488218;1980.1188471130354;1983.186893373717;2000.559876827103;2002.5794583137558;2003.549201429674;2020.5958808238338;2033.5627840014467;2045.9386218413472;2052.5279185787276;2054.4042227753935;1993.252530301137;2037.2971324892962;2049.1598444637702;2053.14794360341;2060.093558834108;2026.3009261804655;2058.6500294949897;2058.8558800883943;2059.258666879922;2063.298478517311;1922.404054743034;1963.1656545967905;1969.3501144622383;1969.9015049247623;1970.7240986261934;6207.658578778165;6351;6971;7037.507374483737;7047.154341479055;4086.979565102185;4221.620054195107;4225.767582782945;4229.894252643709;4234.322508398673;2822.6048353230562;2935.9853200734074;2945.6759756426777;3028.37918226763;3029.5152775556026;1008.0625018732576;1021.8467229915498;1023.2019025160387;1025.251566356564;1036.1400037968467;9373;9423.858642120374;9435.622575097057;9436.716898492987;9446.95276523613;3603.4234522476277;3815.6184381562175;3827.3684842001317;3844.9038774030873;3845.1540661054523;8214.712485062993;8271.296939760068;8272.958635206785;8274.503529788231;8281.420300579022;4173;4194.874153775391;4284.250256205151;4292.077203401283;4303.483581970184;3922.4116382542848;3944.8816535503406;3949.565547789741;3952.505936757905;3963.9009024774036;3004.4591973444553;3012.4426981008583;3106.099231222965;3137.9843100784806;3143.528470729409;932.6269492203173;946.129560804062;949.5062567464879;950.0736781637986;950.8716323296405;1133.0538999934981;1136.8863113688565;1151.7754037962518;1157.9710507237387;1164.8660404053473;6416.935830641727;6622.503312251601;6656.201255849329;6666.266710661824;6670;364.29508900278046;371.1815446939492;372.27406557213544;372.48968912589385;372.549215449304;5236.423993360727;5293.947060529423;5302.655327403706;5309.681419114865;5317.30874986257;7611.847763044819;8012.1186669466315;8020.759377218693;8166.877496837552;8183.222593853575;8234.258916697527;8263.338932885355;8266.917330826735;8273.420860539705;8277;6717.966410167918;6809.659517024129;6812.897806532906;6813.454923606099;6827.146606674166;6382.170317858649;6405.967970160119;6415.235810152246;6480.060391243338;6492.64290464029;7298.124225093023;7406.629668516661;7771.611419429119;7846.960765196138;7896.052473702963;7800.45396822217;8090.635921383434;8115.553644549606;8132.1867813218205;8132.3900707448365;5930;6203.7828675996125;6242;6420.7431702731365;6443.613383197023;6725.327467253235;6817.216020157547;6841.042254084532;6858.5199036067;6862.794116176523;5944.910826337522;6554.737810487527;6596.109525214227;6610.2398224204835;6636.867262654623;6100.23747031621;6356.809295721136;6530.738770449128;6531.085648009377;6554.770583029571;6222.844428889314;6667.133272674521;6685.130932978682;6706.262311145868;6721.193456785217;5862.208601838697;6000.69996500182;6024.969875150597;6034.456898879122;6046.54650901193;5902.439268269508;5948.583599148018;5978.461938425563;5978.701064946648;5990.940090599038
13:14:22 INFO - 2016-02-19 13:14:22,177 DEBUG : BROWSER_OUTPUT: |4;prototype.html;260.4816415333487;271.3866661345359;275.34467966240345;275.7132582114601;276.7606020792014;6205.22434695663;6231;6273.68631568422;6282.2147231596055;6318.9368106318925;6169.4755945744555;6181.805459062024;6182.350853160409;6188.81433556994;6190.133381326618;4251;4344.543822898606;4379.102284031782;4380.912381752363;4385.693001489897;4079.2657321682086;4376.606105450509;4376.868693939187;4384.079343337903;4384.276594361926;4313.6549076074;4318.395424640553;4329.978350108261;4340.52254252032;4344.087741574257;4065.1463192729266;4298.355246712987;4330.523642399334;4342.196693611676;4345.369921361401;3689.6679298863232;3693.3905905525558;3694.5935947045805;3700.1674623209833;3701.555813302394;5749.338826034976;5886.20536227608;5964.015937370329;6020.909686354708;6022.759089636409;5103.591712663015;5108.106081435771;5109;5112.821051263188;5113.130767769451;6274.466670333027;6275.246970363585;6286.151369565096;6424.871502569922;6425.357464253584;3924.7448915820382;3980.44273801668;3980.920381592381;3987.342088555385;3990.1221731219084;6312.431881130675;6607.80176594703;6749.19009718832;6790.5925644460995;6812.080369150151;3920.9803950980063;4014.437978682987;4014.578469260744;4017.2568074906235;4154.148399578079;4045.716799824043;4198.748075115503;4203.180379825904;4204.117135401539;4213.620774130343;5049.31834202378;5057.266696329085;5063.544281014652;5066.06277838601;5066.417362003343;6105.206323177958;6229.127922090911;6385.6487893165395;6403.103565500834;6475.870482590416;1467.662437639349;1467.9486217982533;1471.1834931612843;1471.3967273417848;1477.866991970728;925.0518218825757;925.5834874306454;944.1738478831023;944.5466176235445;949.8765160529088;6335.081413195061;6335.588186767845;6337.809865704036;6339.683015849189;6343.809685709436;6385.329540398284;6748.898766518506;6751.56114852533;6758.526903116833;6763.425108865801;6114;6259.123722678829;6259.843503912439;6260.593061450991;6312.936870631235;3365.7560569741813;3614.1506745915217;3622.2031153146268;3634.60019397866;3644.653757893049;6995.965020174866;7045.189803172599;7057.294270573005;7068.11648543932;7077.433805295564;6616;6794.150731158606;6797.898031529531;6844.452443804584;6845.041694162821;6965.19900211472;7006.789796306119;7060.6116663584;7061.258567850405;7064.540804847566;6370.490360771035;6728;6749.426298764463;6800.081988931431;6804.965975170093;6668.866622667422;6680.63256520896;6692.59844409337;6695.7321707133115;6697.665116744046;6929.133858267716;7045.471589630695;7129.536580122275;7143.821404464722;7174.174969878531;4169.8957526062095;4196.223698615766;4228.323468245066;4229.25987952101;4238.809253583535;6587.308332625005;6677.866442671217;6716.361945615159;6780.830479238059;6801.591904485746;6400.775972840928;6512.869742605027;6556.475481961336;6611.305812889577;6633.303503132294;6447.580907241015;6566;6566.967165164144;6573.474122070318;6581.967090164519;6394.072859435449;6399.392057754506;6403.135576697086;6408.711607977559;6417.358264173546;6332.208473940757;6424.743010279536;6562.442192413699;6575.342465753578;6591.538592298493;6327.936720632735;6443.0335449682925;6454.677266136769;6494.415502604788;6496.512761542997
13:14:22 INFO - 2016-02-19 13:14:22,177 DEBUG : BROWSER_OUTPUT: |5;yui.html;699.4159876503118;710.6659869861166;732.9450291228156;793.8967934168564;814.7800093974627;13595.184288942659;13956.023078384504;13983.790243146335;13984.720305593883;14043.578692639212;12773.55292564758;12777;12785.913197378233;12817.935910320435;12830.101892867502;540.4514417865869;544.5317027356471;609.2506217352659;629.7386584567399;638.667892695798;638.8307098618869;641.1856941684082;641.3906788550895;642.1235014205622;644.6196743921085;661.6823924516235;668.6690088406245;673.9831504212409;674.052955597384;674.0933444517117;571.0149991265056;573.438030729885;574.2792795042216;655.6951017776756;665.9167604049494;459.71497671443916;482.2814007129352;484.027105517908;484.52758560403674;484.883627929298;11493.080553555696;11551.653450396501;11604.361760103191;11610.767784644344;11626.302421854716;12877.484900603964;12948.093633445662;13273.203607783564;13330.266835324053;13660.385282662304;10666.839997400046;10977.945110274477;11065.225434219608;11182.16133789969;11199.888001120047;3723.0320116769835;3753.2202849298183;3787.223619158066;3800.7149463790324;3837.7889216093104;54778.45221547774;55007;55008.449915500736;55098.72450637721;55267.447325527035;9202.861957070649;9586.424814511012;9693.079157480028;9700.611975521042;10001;17070.658586828442;17289.221985010703;17402.303907843798;17414.738778918327;17419.216135273942;7311.378532824663;7353.374963128087;7616.04799400075;7625.580593067434;7669;4333.393324934512;4396.758178300161;4410.691251612357;4430.645548356124;4553.612942899825;2910.010596397235;2943.661478929908;2975.047984644893;2987.925301867427;2995.311078451968;541.5207541325955;542.9981683793395;547.9698616576047;558.9580781441408;560.6804121650702;627.9748810047638;659.8845202089615;681.8465845184794;742.6620887496284;748.6406524867984;9815.607375705034;9863.210943124534;9944.80110397788;10203.336783109075;10504.842427363596;557.670974125268;715.1168307140678;722.6928555364054;757.4622018367021;771.0516065239786;4421.955780442155;4751.691140075953;4954.826581069645;5042;5191.480851914778;66439;68954.65522672454;69526;69979;70583.29416705767;61878;62477.687611561654;62547;63147.6842615784;64080;55871.441285586625;57309.42690573208;57794.71102644544;58277.125843112386;59475;9890.159336456481;10087.344322619007;10156.898431015596;10239;10284.434356110485;43825.56174438215;43963.56036439595;43999.560004400824;44981.1003779916;45329.320060198464;50815.2377714342;52792.20811687751;53697.463025369245;54837.45162548483;55788;12051.156419050933;12072.637820865388;12364.134510584532;12432.440540175894;12787.232766034067;14201;14328.785068223777;14502.782458263346;14580.85419145795;14657.92671036638;13345.933270333586;13464.99102333924;13798.793018104536;13924.860751392356;13941.372638231507;12458.127931045103;12971.286579238233;13053.477860885838;13103.2138071716;13323.333833308103;11354.432278385882;11888.28670279786;12291.139620226499;13441.932790335986;13478.32608369558;11084.445777711244;11273.210875238654;11354.148438866952;11499;11617.941910290394;10774.676759697222;11343.40530917128;11401.771964560829;11468.197226194086;11519.712007199887
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: __end_tp_report
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: __start_cc_report
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1560
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: __end_cc_report
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: __startTimestamp1455916462054__endTimestamp
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: Number of tests: 6
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,178 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5158.32 Median:5445.40 stddev:701.53 (12.9%) stddev-sans-first:681.07
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT: Values: 2906.3 2960.7 2962.9 2980.0 3000.9 5302.1 5373.9 5385.9 5444.7 5446.7 5345.7 5410.2 5445.4 5487.0 5489.3 4588.5 4653.7 4706.5 4779.7 4786.1 4638.9 4661.7 4691.2 4691.6 4693.6 4730.8 4737.3 4750.8 4754.1 4764.9 4457.9 4564.5 4614.1 4747.6 4748.2 4594.0 4612.5 4716.5 4763.9 4780.5 5256.5 5263.3 5329.9 5368.8 5436.2 5277.2 5359.8 5362.2 5435.0 5579.0 5588.2 5617.3 5667.5 5687.5 5707.0 5176.7 5186.3 5291.6 5298.7 5304.2 5523.9 5650.2 5657.3 5662.2 5667.3 5438.7 5467.9 5472.8 5478.2 5479.5 5319.4 5401.7 5560.8 5566.6 5573.3 5420.9 5500.0 5519.4 5524.8 5545.4 5452.7 5516.1 5546.4 5552.5 5557.6 3542.5 3558.4 3578.4 3629.2 3629.5 3293.0 3303.4 3327.9 3338.1 3341.3 5254.7 5338.5 5370.1 5371.1 5484.9 5603.4 5696.2 5719.7 5729.9 5746.8 5407.6 5414.0 5451.4 5473.9 5511.0 4290.2 4445.3 4451.5 4453.9 4454.6 5712.0 5838.2 5874.2 5936.9 5996.6 5682.3 5690.7 5747.3 5751.7 5764.5 5707.1 5817.6 5822.3 5823.6 5894.7 5469.2 5482.6 5498.5 5509.8 5512.9 5590.1 5649.8 5672.7 5675.4 5677.1 5462.0 5480.9 5506.2 5530.7 5551.8 5403.4 5486.8 5499.1 5504.1 5507.3 5058.1 5396.7 5397.5 5406.7 5409.0 5466.4 5510.1 5528.3 5538.2 5582.1 5439.6 5447.5 5478.7 5550.7 5552.6
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47881.65 Median:186716.33 stddev:82530.25 (44.2%) stddev-sans-first:82685.12
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT: Values: 508.7 522.6 531.6 535.3 536.7 30412.5 39282.8 39989.8 40070.6 40079.6 42698.6 42976.8 43029.1 43138.6 43143.4 7656.8 7761.2 7906.9 8002.2 8090.2 10134.6 10263.5 10430.5 10431.6 10449.8 9350.3 10288.3 10350.6 10358.2 10373.2 7582.9 7711.2 9927.7 10199.5 10480.7 9505.3 10137.5 10179.8 10192.9 10196.8 36529.8 39122.4 39277.2 39301.0 39305.0 3116.0 3136.8 3175.1 3222.3 3286.3 29466.0 30864.8 31149.0 31806.4 31961.4 3610.2 3742.5 3757.1 3784.8 3787.2 302822.0 304186.0 305283.0 305325.0 307471.0 49647.0 50888.7 54942.7 55929.0 57273.1 52274.7 53508.2 53648.5 53720.5 53845.7 13415.2 13833.5 13917.7 13965.1 13974.3 10528.6 10716.7 10740.9 10758.0 10775.1 18544.6 18824.2 19086.5 19097.1 19122.5 7565.2 7760.1 7761.1 7765.3 7774.6 905.6 981.0 1027.4 1027.7 1090.2 28367.0 28687.0 28922.7 28988.3 29080.4 1010.5 1031.2 1035.5 1083.0 1088.3 14468.1 14565.2 14937.7 14965.6 15297.0 313669.0 317400.0 339460.0 405679.0 432267.0 48369.8 49540.5 49583.8 49766.5 50050.2 1191.9 1242.4 1244.5 1253.4 1254.5 25523.6 30028.7 30141.6 31199.7 31213.2 44583.8 45794.3 46118.8 46192.3 46290.8 257828.0 268448.0 269341.0 270416.0 271586.0 29274.6 41114.4 42007.4 43075.4 43216.4 41909.6 42510.8 43367.0 43689.8 43694.6 36834.6 36884.6 37806.4 37965.2 38427.4 35407.0 35969.8 36514.8 36937.3 37266.8 35492.8 36449.1 36976.4 37104.1 37288.0 33099.7 33188.2 33295.7 33972.7 34298.8 22249.9 26921.6 27161.3 28257.3 28318.9
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26298.90 Median:21872.58 stddev:70304.94 (321.4%) stddev-sans-first:70475.04
13:14:22 INFO - 2016-02-19 13:14:22,180 DEBUG : BROWSER_OUTPUT: Values: 290.1 292.9 294.4 297.9 300.6 13461.3 15608.5 16772.8 17134.6 17167.0 16226.3 16545.6 17365.9 17633.5 17676.0 4548.1 4612.3 4634.3 4637.7 4639.7 3881.2 3967.4 4552.0 5188.9 5299.5 5155.3 5208.8 5258.8 5278.2 5279.1 5251.1 5251.6 5264.6 5669.0 5936.3 5096.5 5105.4 5105.9 5106.3 5108.7 20382.3 20568.8 21311.5 21316.8 21352.5 1911.9 1950.7 1950.8 2077.1 2096.7 8884.9 8920.3 8953.1 9023.9 10332.9 1719.2 1732.3 1769.7 1810.5 1818.5 70205.0 72051.0 72052.0 72538.6 72987.0 9294.1 9298.8 9400.3 9444.0 9449.1 11209.3 11493.0 11500.6 11508.1 11535.9 7610.4 7671.3 7684.6 7690.2 7694.2 7803.4 7843.0 7973.4 7999.8 8033.0 3724.3 3761.7 3781.3 3829.6 3832.2 1507.4 1532.2 1579.6 1586.9 1587.1 845.2 898.8 902.3 983.3 1002.0 16232.3 16377.0 16874.8 16984.7 17158.9 931.1 958.4 967.5 986.5 1012.2 9764.9 10247.7 10282.2 10330.6 10488.3 411895.0 416519.0 430931.0 433064.0 436412.0 83674.0 84801.8 85481.6 86104.6 86130.0 961.5 977.8 994.1 1000.2 1027.4 16107.1 16239.5 16241.5 16262.9 16264.9 39777.8 40142.6 40579.0 41644.8 41737.4 53873.0 53954.5 54400.7 55170.4 55259.2 8182.5 8351.9 8400.5 8773.4 8791.0 10292.7 10457.6 10467.8 10468.9 10476.4 12166.0 14956.3 15144.2 15262.3 15309.3 14876.3 15164.0 15245.2 15318.5 15600.7 15353.8 15670.3 15873.9 16098.3 16333.2 8683.8 8740.9 8756.2 8773.6 9037.5 12254.8 15363.0 15402.2 15523.2 15692.2
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4923.44 Median:6040.50 stddev:2578.75 (42.7%) stddev-sans-first:2570.49
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: Values: 1163.2 1241.2 1255.5 1262.3 1509.3 6551.3 6847.5 7176.7 7188.7 7194.1 7034.9 7107.5 7221.9 7239.2 7242.5 1980.1 1983.2 2000.6 2002.6 2003.5 2020.6 2033.6 2045.9 2052.5 2054.4 1993.3 2037.3 2049.2 2053.1 2060.1 2026.3 2058.7 2058.9 2059.3 2063.3 1922.4 1963.2 1969.4 1969.9 1970.7 6207.7 6351.0 6971.0 7037.5 7047.2 4087.0 4221.6 4225.8 4229.9 4234.3 2822.6 2936.0 2945.7 3028.4 3029.5 1008.1 1021.8 1023.2 1025.3 1036.1 9373.0 9423.9 9435.6 9436.7 9447.0 3603.4 3815.6 3827.4 3844.9 3845.2 8214.7 8271.3 8273.0 8274.5 8281.4 4173.0 4194.9 4284.3 4292.1 4303.5 3922.4 3944.9 3949.6 3952.5 3963.9 3004.5 3012.4 3106.1 3138.0 3143.5 932.6 946.1 949.5 950.1 950.9 1133.1 1136.9 1151.8 1158.0 1164.9 6416.9 6622.5 6656.2 6666.3 6670.0 364.3 371.2 372.3 372.5 372.5 5236.4 5293.9 5302.7 5309.7 5317.3 7611.8 8012.1 8020.8 8166.9 8183.2 8234.3 8263.3 8266.9 8273.4 8277.0 6718.0 6809.7 6812.9 6813.5 6827.1 6382.2 6406.0 6415.2 6480.1 6492.6 7298.1 7406.6 7771.6 7847.0 7896.1 7800.5 8090.6 8115.6 8132.2 8132.4 5930.0 6203.8 6242.0 6420.7 6443.6 6725.3 6817.2 6841.0 6858.5 6862.8 5944.9 6554.7 6596.1 6610.2 6636.9 6100.2 6356.8 6530.7 6531.1 6554.8 6222.8 6667.1 6685.1 6706.3 6721.2 5862.2 6000.7 6025.0 6034.5 6046.5 5902.4 5948.6 5978.5 5978.7 5990.9
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5311.00 Median:6278.73 stddev:1743.22 (27.8%) stddev-sans-first:1706.39
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: Values: 260.5 271.4 275.3 275.7 276.8 6205.2 6231.0 6273.7 6282.2 6318.9 6169.5 6181.8 6182.4 6188.8 6190.1 4251.0 4344.5 4379.1 4380.9 4385.7 4079.3 4376.6 4376.9 4384.1 4384.3 4313.7 4318.4 4330.0 4340.5 4344.1 4065.1 4298.4 4330.5 4342.2 4345.4 3689.7 3693.4 3694.6 3700.2 3701.6 5749.3 5886.2 5964.0 6020.9 6022.8 5103.6 5108.1 5109.0 5112.8 5113.1 6274.5 6275.2 6286.2 6424.9 6425.4 3924.7 3980.4 3980.9 3987.3 3990.1 6312.4 6607.8 6749.2 6790.6 6812.1 3921.0 4014.4 4014.6 4017.3 4154.1 4045.7 4198.7 4203.2 4204.1 4213.6 5049.3 5057.3 5063.5 5066.1 5066.4 6105.2 6229.1 6385.6 6403.1 6475.9 1467.7 1467.9 1471.2 1471.4 1477.9 925.1 925.6 944.2 944.5 949.9 6335.1 6335.6 6337.8 6339.7 6343.8 6385.3 6748.9 6751.6 6758.5 6763.4 6114.0 6259.1 6259.8 6260.6 6312.9 3365.8 3614.2 3622.2 3634.6 3644.7 6996.0 7045.2 7057.3 7068.1 7077.4 6616.0 6794.2 6797.9 6844.5 6845.0 6965.2 7006.8 7060.6 7061.3 7064.5 6370.5 6728.0 6749.4 6800.1 6805.0 6668.9 6680.6 6692.6 6695.7 6697.7 6929.1 7045.5 7129.5 7143.8 7174.2 4169.9 4196.2 4228.3 4229.3 4238.8 6587.3 6677.9 6716.4 6780.8 6801.6 6400.8 6512.9 6556.5 6611.3 6633.3 6447.6 6566.0 6567.0 6573.5 6582.0 6394.1 6399.4 6403.1 6408.7 6417.4 6332.2 6424.7 6562.4 6575.3 6591.5 6327.9 6443.0 6454.7 6494.4 6496.5
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15840.52 Median:45155.21 stddev:19451.83 (43.1%) stddev-sans-first:19473.17
13:14:22 INFO - 2016-02-19 13:14:22,184 DEBUG : BROWSER_OUTPUT: Values: 699.4 710.7 732.9 793.9 814.8 13595.2 13956.0 13983.8 13984.7 14043.6 12773.6 12777.0 12785.9 12817.9 12830.1 540.5 544.5 609.3 629.7 638.7 638.8 641.2 641.4 642.1 644.6 661.7 668.7 674.0 674.1 674.1 571.0 573.4 574.3 655.7 665.9 459.7 482.3 484.0 484.5 484.9 11493.1 11551.7 11604.4 11610.8 11626.3 12877.5 12948.1 13273.2 13330.3 13660.4 10666.8 10977.9 11065.2 11182.2 11199.9 3723.0 3753.2 3787.2 3800.7 3837.8 54778.5 55007.0 55008.4 55098.7 55267.4 9202.9 9586.4 9693.1 9700.6 10001.0 17070.7 17289.2 17402.3 17414.7 17419.2 7311.4 7353.4 7616.0 7625.6 7669.0 4333.4 4396.8 4410.7 4430.6 4553.6 2910.0 2943.7 2975.0 2987.9 2995.3 541.5 543.0 548.0 559.0 560.7 628.0 659.9 681.8 742.7 748.6 9815.6 9863.2 9944.8 10203.3 10504.8 557.7 715.1 722.7 757.5 771.1 4422.0 4751.7 4954.8 5042.0 5191.5 66439.0 68954.7 69526.0 69979.0 70583.3 61878.0 62477.7 62547.0 63147.7 64080.0 55871.4 57309.4 57794.7 58277.1 59475.0 9890.2 10087.3 10156.9 10239.0 10284.4 43825.6 43963.6 43999.6 44981.1 45329.3 50815.2 52792.2 53697.5 54837.5 55788.0 12051.2 12072.6 12364.1 12432.4 12787.2 14201.0 14328.8 14502.8 14580.9 14657.9 13345.9 13465.0 13798.8 13924.9 13941.4 12458.1 12971.3 13053.5 13103.2 13323.3 11354.4 11888.3 12291.1 13441.9 13478.3 11084.4 11273.2 11354.1 11499.0 11617.9 10774.7 11343.4 11401.8 11468.2 11519.7
13:14:22 INFO - 2016-02-19 13:14:22,187 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
13:14:22 INFO - 2016-02-19 13:14:22,187 DEBUG : BROWSER_OUTPUT:
13:14:22 INFO - 2016-02-19 13:14:22,678 INFO : Browser exited with error code: 0
13:14:23 INFO - 2016-02-19 13:14:23,358 INFO : Completed test dromaeo_css (00:18:36)
13:14:23 INFO - 2016-02-19 13:14:23,358 INFO : Starting test kraken
13:14:23 INFO - 2016-02-19 13:14:23,358 DEBUG : operating with platform_type : w7_
13:14:23 INFO - 2016-02-19 13:14:23,358 INFO : Initialising browser for kraken test...
13:14:23 INFO - 2016-02-19 13:14:23,384 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqqjhli\profile http://localhost:49570/getInfo.html
13:14:28 INFO - 2016-02-19 13:14:28,066 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
13:14:28 INFO - 2016-02-19 13:14:28,066 DEBUG : BROWSER_OUTPUT: colorDepth:24
13:14:28 INFO - 2016-02-19 13:14:28,066 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
13:14:28 INFO - 2016-02-19 13:14:28,066 DEBUG : BROWSER_OUTPUT: __metrics
13:14:28 INFO - 2016-02-19 13:14:28,207 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
13:14:29 INFO - 2016-02-19 13:14:29,255 INFO : Browser initialized.
13:14:29 INFO - 2016-02-19 13:14:29,255 INFO : Running cycle 1/1 for kraken test...
13:14:29 INFO - 2016-02-19 13:14:29,255 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqqjhli\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1
13:14:29 INFO - 2016-02-19 13:14:29,763 DEBUG : BROWSER_OUTPUT: [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
13:14:40 INFO - 2016-02-19 13:14:40,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 107741184
13:14:40 INFO - 2016-02-19 13:14:40,638 DEBUG : BROWSER_OUTPUT:
13:14:42 INFO - 2016-02-19 13:14:42,957 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49570/tests/kraken/driver.html?testName=audio-beat-detection)
13:14:43 INFO - 2016-02-19 13:14:43,519 DEBUG : BROWSER_OUTPUT: RSS: Main: 150016000
13:14:43 INFO - 2016-02-19 13:14:43,519 DEBUG : BROWSER_OUTPUT:
13:14:46 INFO - 2016-02-19 13:14:46,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49570/tests/kraken/driver.html?testName=audio-dft)
13:14:46 INFO - 2016-02-19 13:14:46,634 DEBUG : BROWSER_OUTPUT: RSS: Main: 152670208
13:14:46 INFO - 2016-02-19 13:14:46,635 DEBUG : BROWSER_OUTPUT:
13:14:49 INFO - 2016-02-19 13:14:49,371 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49570/tests/kraken/driver.html?testName=audio-fft)
13:14:49 INFO - 2016-02-19 13:14:49,934 DEBUG : BROWSER_OUTPUT: RSS: Main: 147017728
13:14:49 INFO - 2016-02-19 13:14:49,934 DEBUG : BROWSER_OUTPUT:
13:14:52 INFO - 2016-02-19 13:14:52,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49570/tests/kraken/driver.html?testName=audio-oscillator)
13:14:52 INFO - 2016-02-19 13:14:52,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 147927040
13:14:52 INFO - 2016-02-19 13:14:52,582 DEBUG : BROWSER_OUTPUT:
13:14:54 INFO - 2016-02-19 13:14:54,756 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49570/tests/kraken/driver.html?testName=imaging-gaussian-blur)
13:14:55 INFO - 2016-02-19 13:14:55,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 147795968
13:14:55 INFO - 2016-02-19 13:14:55,312 DEBUG : BROWSER_OUTPUT:
13:14:58 INFO - 2016-02-19 13:14:58,447 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49570/tests/kraken/driver.html?testName=imaging-darkroom)
13:14:59 INFO - 2016-02-19 13:14:59,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 146370560
13:14:59 INFO - 2016-02-19 13:14:59,006 DEBUG : BROWSER_OUTPUT:
13:15:02 INFO - 2016-02-19 13:15:02,325 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49570/tests/kraken/driver.html?testName=imaging-desaturate)
13:15:02 INFO - 2016-02-19 13:15:02,892 DEBUG : BROWSER_OUTPUT: RSS: Main: 147083264
13:15:02 INFO - 2016-02-19 13:15:02,894 DEBUG : BROWSER_OUTPUT:
13:15:05 INFO - 2016-02-19 13:15:05,780 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49570/tests/kraken/driver.html?testName=json-parse-financial)
13:15:06 INFO - 2016-02-19 13:15:06,341 DEBUG : BROWSER_OUTPUT: RSS: Main: 147259392
13:15:06 INFO - 2016-02-19 13:15:06,341 DEBUG : BROWSER_OUTPUT:
13:15:08 INFO - 2016-02-19 13:15:08,055 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49570/tests/kraken/driver.html?testName=json-stringify-tinderbox)
13:15:08 INFO - 2016-02-19 13:15:08,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 179183616
13:15:08 INFO - 2016-02-19 13:15:08,611 DEBUG : BROWSER_OUTPUT:
13:15:10 INFO - 2016-02-19 13:15:10,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-aes)
13:15:11 INFO - 2016-02-19 13:15:11,072 DEBUG : BROWSER_OUTPUT: RSS: Main: 181862400
13:15:11 INFO - 2016-02-19 13:15:11,072 DEBUG : BROWSER_OUTPUT:
13:15:13 INFO - 2016-02-19 13:15:13,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-ccm)
13:15:13 INFO - 2016-02-19 13:15:13,858 DEBUG : BROWSER_OUTPUT: RSS: Main: 187301888
13:15:13 INFO - 2016-02-19 13:15:13,858 DEBUG : BROWSER_OUTPUT:
13:15:16 INFO - 2016-02-19 13:15:16,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2)
13:15:16 INFO - 2016-02-19 13:15:16,809 DEBUG : BROWSER_OUTPUT: RSS: Main: 187850752
13:15:16 INFO - 2016-02-19 13:15:16,809 DEBUG : BROWSER_OUTPUT:
13:15:19 INFO - 2016-02-19 13:15:19,743 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative)
13:15:20 INFO - 2016-02-19 13:15:20,306 DEBUG : BROWSER_OUTPUT: RSS: Main: 188600320
13:15:20 INFO - 2016-02-19 13:15:20,306 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,109 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49570/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49570/tests/kraken/driver.html?testName=ai-astar)
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 237207552
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: __start_tp_report
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |i|pagename|runs|
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |0;ai-astar;97;89;97;98;94;97;98;97;97;103
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;127;129;158;156;128;129;159;129;155;127
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |2;audio-dft;153;158;160;164;161;156;153;152;151;157
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |3;audio-fft;99;103;77;115;83;79;77;78;128;81
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;91;91;134;95;91;91;142;92;92;135
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;122;122;123;128;123;123;123;123;122;123
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;138;138;139;139;139;140;138;138;142;139
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;100;99;104;104;100;100;100;100;100;100
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;74;74;74;75;74;74;75;75;85
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;76;76;76;77;80;77;76;77;76;76
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;94;100;94;95;94;97;95;95;95;94
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;120;115;117;126;117;114;116;121;119
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;184;180;182;184;191;179;189;185;190;182
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;81;81;81;82;81;81;82;81;82;84
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: __end_tp_report
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: __start_cc_report
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,797
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: __end_cc_report
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: __startTimestamp1455916522359__endTimestamp
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,365 DEBUG : BROWSER_OUTPUT: ------- Summary: start -------
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: Number of tests: 14
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:96.70 Median:97.00 stddev:3.50 (3.6%) stddev-sans-first:3.71
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: Values: 97.0 89.0 97.0 98.0 94.0 97.0 98.0 97.0 97.0 103.0
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.70 Median:142.00 stddev:14.94 (10.5%) stddev-sans-first:15.13
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: Values: 127.0 129.0 158.0 156.0 128.0 129.0 159.0 129.0 155.0 127.0
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:156.50 Median:157.50 stddev:4.30 (2.7%) stddev-sans-first:4.37
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: Values: 153.0 158.0 160.0 164.0 161.0 156.0 153.0 152.0 151.0 157.0
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:92.00 Median:78.50 stddev:18.29 (23.3%) stddev-sans-first:19.23
13:15:22 INFO - 2016-02-19 13:15:22,367 DEBUG : BROWSER_OUTPUT: Values: 99.0 103.0 77.0 115.0 83.0 79.0 77.0 78.0 128.0 81.0
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.40 Median:91.00 stddev:21.94 (24.1%) stddev-sans-first:22.64
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: Values: 91.0 91.0 134.0 95.0 91.0 91.0 142.0 92.0 92.0 135.0
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:123.20 Median:123.00 stddev:1.75 (1.4%) stddev-sans-first:1.80
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: Values: 122.0 122.0 123.0 128.0 123.0 123.0 123.0 123.0 122.0 123.0
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:139.00 Median:139.00 stddev:1.25 (0.9%) stddev-sans-first:1.27
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: Values: 138.0 138.0 139.0 139.0 139.0 140.0 138.0 138.0 142.0 139.0
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:100.70 Median:100.00 stddev:1.77 (1.8%) stddev-sans-first:1.86
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: Values: 100.0 99.0 104.0 104.0 100.0 100.0 100.0 100.0 100.0 100.0
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,368 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:75.20 Median:74.50 stddev:3.55 (4.8%) stddev-sans-first:3.57
13:15:22 INFO - 2016-02-19 13:15:22,369 DEBUG : BROWSER_OUTPUT: Values: 72.0 74.0 74.0 74.0 75.0 74.0 74.0 75.0 75.0 85.0
13:15:22 INFO - 2016-02-19 13:15:22,369 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,369 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:76.50 stddev:1.25 (1.6%) stddev-sans-first:1.30
13:15:22 INFO - 2016-02-19 13:15:22,369 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 76.0 77.0 80.0 77.0 76.0 77.0 76.0 76.0
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:95.00 stddev:1.89 (2.0%) stddev-sans-first:1.94
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT: Values: 94.0 100.0 94.0 95.0 94.0 97.0 95.0 95.0 95.0 94.0
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:119.00 stddev:3.47 (2.9%) stddev-sans-first:3.67
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT: Values: 119.0 120.0 115.0 117.0 126.0 117.0 114.0 116.0 121.0 119.0
13:15:22 INFO - 2016-02-19 13:15:22,371 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,372 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:184.50 stddev:4.17 (2.3%) stddev-sans-first:4.42
13:15:22 INFO - 2016-02-19 13:15:22,372 DEBUG : BROWSER_OUTPUT: Values: 184.0 180.0 182.0 184.0 191.0 179.0 189.0 185.0 190.0 182.0
13:15:22 INFO - 2016-02-19 13:15:22,372 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,372 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.60 Median:81.50 stddev:0.97 (1.2%) stddev-sans-first:1.00
13:15:22 INFO - 2016-02-19 13:15:22,374 DEBUG : BROWSER_OUTPUT: Values: 81.0 81.0 81.0 82.0 81.0 81.0 82.0 81.0 82.0 84.0
13:15:22 INFO - 2016-02-19 13:15:22,374 DEBUG : BROWSER_OUTPUT: -------- Summary: end --------
13:15:22 INFO - 2016-02-19 13:15:22,374 DEBUG : BROWSER_OUTPUT:
13:15:22 INFO - 2016-02-19 13:15:22,971 INFO : Browser exited with error code: 0
13:15:23 INFO - 2016-02-19 13:15:23,654 INFO : Completed test kraken (00:01:00)
13:15:23 INFO - 2016-02-19 13:15:23,654 INFO : Starting test v8_7
13:15:23 INFO - 2016-02-19 13:15:23,654 DEBUG : operating with platform_type : w7_
13:15:23 INFO - 2016-02-19 13:15:23,654 INFO : Initialising browser for v8_7 test...
13:15:23 INFO - 2016-02-19 13:15:23,680 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmprlcajk\profile http://localhost:49570/getInfo.html
13:15:28 INFO - 2016-02-19 13:15:28,223 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200
13:15:28 INFO - 2016-02-19 13:15:28,223 DEBUG : BROWSER_OUTPUT: colorDepth:24
13:15:28 INFO - 2016-02-19 13:15:28,223 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674
13:15:28 INFO - 2016-02-19 13:15:28,223 DEBUG : BROWSER_OUTPUT: __metrics
13:15:28 INFO - 2016-02-19 13:15:28,369 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
13:15:28 INFO - 2016-02-19 13:15:28,892 DEBUG : BROWSER_OUTPUT: console.error:
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction.
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: Stack:
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:1
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
13:15:28 INFO - 2016-02-19 13:15:28,895 DEBUG : BROWSER_OUTPUT: Barrier/this.client.addBlocker/promisedromaeo_css: 6800.09
13:16:04 INFO - RETURN: kraken: 1585.00
13:16:04 INFO - RETURN: v8_7: 22303.29
13:16:04 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,25]]", "result": "22303.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,25]]", "result": "1585.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,25]]", "result": "6800.09"}}}
13:16:04 INFO - Return code: 0
13:16:04 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json
13:16:04 INFO - # TBPL SUCCESS #
13:16:04 INFO - Running post-action listener: _resource_record_post_action
13:16:04 INFO - Running post-run listener: _resource_record_post_run
13:16:05 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 217868800; Write bytes: 418494976; Read time: 2843330; Write time: 5344650
13:16:05 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 80
13:16:05 INFO - run-tests - Wall time: 1221s; CPU: 13.0%; Read bytes: 217868800; Write bytes: 392393216; Read time: 2843330; Write time: 5094010
13:16:05 INFO - Running post-run listener: _upload_blobber_files
13:16:05 INFO - Blob upload gear active.
13:16:05 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ...
13:16:05 INFO - Copying logs to upload dir...
13:16:05 INFO - mkdir: C:\slave\test-pgo\build\upload\logs
program finished with exit code 0
elapsedTime=1420.114000
========= master_lag: -0.47 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-02-19 13:16:05.813686) =========
========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:05.814523) =========
'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f'
in dir C:\\slave\test-pgo\properties (timeout 1200 secs)
watching logfiles {}
argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo\properties
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1455901477/firefox-45.0.en-US.win32.zip'
========= master_lag: 0.02 =========
========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:05.934016) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:05.934387) =========
'rm' '-f' 'oauth.txt'
in dir C:\\slave\test-pgo\. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-199
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-199
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test-pgo
RUNLOGFILE=C:\slave\\runslave.log
SLAVEDIR=C:\slave\
SYSTEMDRIVE=C:
SYSTEMROOT=C:\windows
TEMP=C:\Users\cltbld\AppData\Local\Temp
TEST1=testie
TMP=C:\Users\cltbld\AppData\Local\Temp
USERDOMAIN=T-W732-IX-199
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:06.056152) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:06.056513) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-02-19 13:16:06.581288) =========
========= Total master_lag: -0.25 =========