builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1477511904.48 results: success (0) buildid: 20161026095323 builduid: 0dfb9581140a4946af088611ee604e40 revision: 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.480407) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.480836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.481134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.503607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.503887) ========= 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 /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-26 12:58:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2016-10-26 12:58:24 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.647850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.648196) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.019727 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:24.696846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 12:58:24.697219) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-26 12:58:24,769 truncating revision to first 12 chars 2016-10-26 12:58:24,769 Setting DEBUG logging. 2016-10-26 12:58:24,769 attempt 1/10 2016-10-26 12:58:24,769 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/8e23fb23036b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-26 12:58:25,645 unpacking tar archive at: larch-8e23fb23036b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.126424 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-26 12:58:25.845319) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:25.845634) ========= script_repo_revision: 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:25.846033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:25.846296) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-26 12:58:25.864175) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-26 12:58:25.864460) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:58:26 INFO - MultiFileLogger online at 20161026 12:58:26 in /builds/slave/test 12:58:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 12:58:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:58:26 INFO - {'append_to_log': False, 12:58:26 INFO - 'base_work_dir': '/builds/slave/test', 12:58:26 INFO - 'blob_upload_branch': 'Larch', 12:58:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:58:26 INFO - 'branch': 'Larch', 12:58:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:58:26 INFO - 'config_files': ('talos/mac_config.py',), 12:58:26 INFO - 'default_actions': ('clobber', 12:58:26 INFO - 'read-buildbot-config', 12:58:26 INFO - 'download-and-extract', 12:58:26 INFO - 'populate-webroot', 12:58:26 INFO - 'create-virtualenv', 12:58:26 INFO - 'install', 12:58:26 INFO - 'run-tests'), 12:58:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:58:26 INFO - 'download_minidump_stackwalk': True, 12:58:26 INFO - 'download_symbols': 'ondemand', 12:58:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:58:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:58:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:58:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:58:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:58:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:58:26 INFO - 'installer_path': 'installer.exe', 12:58:26 INFO - 'log_level': 'info', 12:58:26 INFO - 'log_name': 'talos', 12:58:26 INFO - 'log_to_console': True, 12:58:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:58:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:58:26 INFO - 'opt_config_files': (), 12:58:26 INFO - 'pip_index': False, 12:58:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:26 INFO - 'cmd': ('bash', 12:58:26 INFO - '-c', 12:58:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:26 INFO - 'enabled': True, 12:58:26 INFO - 'halt_on_failure': False, 12:58:26 INFO - 'name': 'check_screen_resolution'},), 12:58:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:26 INFO - 'cmd': ('bash', 12:58:26 INFO - '-c', 12:58:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:26 INFO - 'enabled': True, 12:58:26 INFO - 'halt_on_failure': False, 12:58:26 INFO - 'name': 'check_screen_resolution'},), 12:58:26 INFO - 'run_cmd_checks_enabled': True, 12:58:26 INFO - 'sps_profile': False, 12:58:26 INFO - 'sps_profile_interval': 0, 12:58:26 INFO - 'suite': 'dromaeojs', 12:58:26 INFO - 'system_bits': '32', 12:58:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:58:26 INFO - 'title': 't-yosemite-r7-0027', 12:58:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:58:26 INFO - 'use_talos_json': True, 12:58:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:58:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:58:26 INFO - 'work_dir': 'build'} 12:58:26 INFO - [mozharness: 2016-10-26 19:58:26.065038Z] Running clobber step. 12:58:26 INFO - Running pre-action listener: _resource_record_pre_action 12:58:26 INFO - Running main action method: clobber 12:58:26 INFO - rmtree: /builds/slave/test/build 12:58:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:58:28 INFO - Running post-action listener: _resource_record_post_action 12:58:28 INFO - [mozharness: 2016-10-26 19:58:28.337807Z] Finished clobber step (success) 12:58:28 INFO - [mozharness: 2016-10-26 19:58:28.337969Z] Running read-buildbot-config step. 12:58:28 INFO - Running pre-action listener: _resource_record_pre_action 12:58:28 INFO - Running main action method: read_buildbot_config 12:58:28 INFO - Using buildbot properties: 12:58:28 INFO - { 12:58:28 INFO - "project": "", 12:58:28 INFO - "product": "firefox", 12:58:28 INFO - "script_repo_revision": "production", 12:58:28 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 12:58:28 INFO - "repository": "", 12:58:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 12:58:28 INFO - "buildid": "20161026095323", 12:58:28 INFO - "pgo_build": "False", 12:58:28 INFO - "basedir": "/builds/slave/test", 12:58:28 INFO - "buildnumber": 3, 12:58:28 INFO - "slavename": "t-yosemite-r7-0027", 12:58:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:58:28 INFO - "platform": "yosemite_r7", 12:58:28 INFO - "scheduler": "tests-larch-macosx64-talos", 12:58:28 INFO - "branch": "larch", 12:58:28 INFO - "repo_path": "projects/larch", 12:58:28 INFO - "revision": "8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b", 12:58:28 INFO - "stage_platform": "macosx64", 12:58:28 INFO - "builduid": "0dfb9581140a4946af088611ee604e40", 12:58:28 INFO - "slavebuilddir": "test" 12:58:28 INFO - } 12:58:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:58:28 INFO - Running post-action listener: _resource_record_post_action 12:58:28 INFO - [mozharness: 2016-10-26 19:58:28.341068Z] Finished read-buildbot-config step (success) 12:58:28 INFO - [mozharness: 2016-10-26 19:58:28.341178Z] Running download-and-extract step. 12:58:28 INFO - Running pre-action listener: _resource_record_pre_action 12:58:28 INFO - Running main action method: download_and_extract 12:58:28 INFO - mkdir: /builds/slave/test/build/tests 12:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:28 INFO - trying https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:58:28 INFO - Downloading https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:58:29 INFO - Downloaded 1199 bytes. 12:58:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:58:29 INFO - Using the following test package requirements: 12:58:29 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:58:29 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:58:29 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:58:29 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'jsshell-mac.zip'], 12:58:29 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:58:29 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:58:29 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:58:29 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:58:29 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:58:29 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:58:29 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:58:29 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:58:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:58:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:58:29 INFO - Fetch https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:58:30 INFO - Http code: 200 12:58:30 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 12:58:30 INFO - x-amz-cf-id: xIJn83ANA6WQFYki4aa4qKXNhcWB_0WHiwJ8-v2r_MiRDsgAlm_cFA== 12:58:30 INFO - x-amz-version-id: nrbjGuQgKoi9fBXxlk7CTojdS0uBJ.TC 12:58:30 INFO - Expected file size: 16409322 12:58:30 INFO - Obtained file size: 16409322 12:58:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:58:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:58:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:58:37 INFO - Fetch https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:58:38 INFO - Http code: 200 12:58:38 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 12:58:38 INFO - x-amz-cf-id: 6w1ePqrPy7yGH5rv758FrHQNASX2BDvxceCEINNZQqsd0HQWXMUGxA== 12:58:38 INFO - x-amz-version-id: nrbjGuQgKoi9fBXxlk7CTojdS0uBJ.TC 12:58:38 INFO - Expected file size: 16409322 12:58:38 INFO - Obtained file size: 16409322 12:58:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:58:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:58:44 INFO - Fetch https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:58:45 INFO - Http code: 200 12:58:45 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 12:58:45 INFO - x-amz-cf-id: Wh7aorqqkbb0YuiSh1kRq-q2bPnGx24hVOMMVzGtfsPc7gbh6scUdQ== 12:58:45 INFO - x-amz-version-id: Uu.ECgKTDNzLe_ZH.Rwm9lyCF0VHIxes 12:58:46 INFO - Expected file size: 14409028 12:58:46 INFO - Obtained file size: 14409028 12:58: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:46 INFO - trying https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:58:46 INFO - Downloading https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:58:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:58:49 INFO - Downloaded 111298899 bytes. 12:58:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:58:49 INFO - mkdir: /builds/slave/test/properties 12:58:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:58:49 INFO - Writing to file /builds/slave/test/properties/build_url 12:58:49 INFO - Contents: 12:58:49 INFO - build_url:https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:58:50 INFO - Running post-action listener: _resource_record_post_action 12:58:50 INFO - Running post-action listener: set_extra_try_arguments 12:58:50 INFO - [mozharness: 2016-10-26 19:58:50.025239Z] Finished download-and-extract step (success) 12:58:50 INFO - [mozharness: 2016-10-26 19:58:50.025365Z] Running populate-webroot step. 12:58:50 INFO - Running pre-action listener: _resource_record_pre_action 12:58:50 INFO - Running main action method: populate_webroot 12:58:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:58:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:58:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:58:50 INFO - '--tppagecycles', 12:58:50 INFO - '7'], 12:58:50 INFO - 'tests': ['tsvgm']}}, 12:58:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:58:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:58:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:58:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:58:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['damp', 'tps']}, 12:58:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'tests': ['damp', 'tps']}, 12:58:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['dromaeo_dom']}, 12:58:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:58:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['basic_compositor_video']}, 12:58:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:58:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'other-e10s': {'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['a11yr', 12:58:50 INFO - 'ts_paint', 12:58:50 INFO - 'tpaint', 12:58:50 INFO - 'sessionrestore', 12:58:50 INFO - 'sessionrestore_no_auto_restore', 12:58:50 INFO - 'tabpaint']}, 12:58:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['tsvgx', 12:58:50 INFO - 'tsvgr_opacity', 12:58:50 INFO - 'tart', 12:58:50 INFO - 'tscrollx', 12:58:50 INFO - 'cart']}, 12:58:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:58:50 INFO - 'tsvgr_opacity', 12:58:50 INFO - 'tart', 12:58:50 INFO - 'tscrollx', 12:58:50 INFO - 'cart']}, 12:58:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'talos_options': ['--disable-e10s'], 12:58:50 INFO - 'tests': ['tp5o']}, 12:58:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'tests': ['tp5o']}, 12:58:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'talos_options': ['--disable-e10s', 12:58:50 INFO - '--xperf_path', 12:58:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:50 INFO - 'tests': ['tp5n']}, 12:58:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:50 INFO - 'talos_options': ['--xperf_path', 12:58:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:50 INFO - 'tests': ['tp5n']}}, 12:58:50 INFO - 'talos.zip': {'path': '', 12:58:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:58:50 INFO - Running post-action listener: _resource_record_post_action 12:58:50 INFO - [mozharness: 2016-10-26 19:58:50.044062Z] Finished populate-webroot step (success) 12:58:50 INFO - [mozharness: 2016-10-26 19:58:50.044171Z] Running create-virtualenv step. 12:58:50 INFO - Running pre-action listener: _resource_record_pre_action 12:58:50 INFO - Running main action method: create_virtualenv 12:58:50 INFO - Creating virtualenv /builds/slave/test/build/venv 12:58:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:58:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:58:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:58:50 INFO - Using real prefix '/tools/python27' 12:58:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:58:50 INFO - Installing distribute.............................................................................................................................................................................................done. 12:58:53 INFO - Installing pip.................done. 12:58:53 INFO - Return code: 0 12:58:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:58:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:58:54 INFO - Reading from file tmpfile_stdout 12:58:54 INFO - Output received: 12:58:54 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:58:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:58:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:58:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:58:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:54 INFO - 'HOME': '/Users/cltbld', 12:58:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:54 INFO - 'LOGNAME': 'cltbld', 12:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:54 INFO - 'MOZ_NO_REMOTE': '1', 12:58:54 INFO - 'NO_EM_RESTART': '1', 12:58:54 INFO - 'PAGER': '/bin/cat', 12:58:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:54 INFO - 'PWD': '/builds/slave/test', 12:58:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:54 INFO - 'SHELL': '/bin/bash', 12:58:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:58:54 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:58:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:54 INFO - 'USER': 'cltbld', 12:58:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:54 INFO - 'XPC_FLAGS': '0x0', 12:58:54 INFO - 'XPC_SERVICE_NAME': '0', 12:58:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:58:54 INFO - Cleaning up... 12:58:54 INFO - Return code: 0 12:58:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:58:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:54 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:58:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:58:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:54 INFO - 'HOME': '/Users/cltbld', 12:58:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:54 INFO - 'LOGNAME': 'cltbld', 12:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:54 INFO - 'MOZ_NO_REMOTE': '1', 12:58:54 INFO - 'NO_EM_RESTART': '1', 12:58:54 INFO - 'PAGER': '/bin/cat', 12:58:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:54 INFO - 'PWD': '/builds/slave/test', 12:58:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:54 INFO - 'SHELL': '/bin/bash', 12:58:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:58:54 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:58:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:54 INFO - 'USER': 'cltbld', 12:58:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:54 INFO - 'XPC_FLAGS': '0x0', 12:58:54 INFO - 'XPC_SERVICE_NAME': '0', 12:58:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:54 INFO - Downloading/unpacking psutil>=3.1.1 12:58:54 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:58:54 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:58:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:54 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:58:54 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:58:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:58:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:58 INFO - Installing collected packages: psutil 12:58:58 INFO - Running setup.py install for psutil 12:58:58 INFO - building 'psutil._psutil_osx' extension 12:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:58:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:58:58 INFO - building 'psutil._psutil_posix' extension 12:58:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:58:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:58:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:58:58 INFO - ^ 12:58:58 INFO - 1 warning generated. 12:58:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:58:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:58 INFO - Successfully installed psutil 12:58:58 INFO - Cleaning up... 12:58:58 INFO - Return code: 0 12:58:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:58:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:58:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:58:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:58:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:58:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:58 INFO - 'HOME': '/Users/cltbld', 12:58:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:58 INFO - 'LOGNAME': 'cltbld', 12:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:58 INFO - 'MOZ_NO_REMOTE': '1', 12:58:58 INFO - 'NO_EM_RESTART': '1', 12:58:58 INFO - 'PAGER': '/bin/cat', 12:58:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:58 INFO - 'PWD': '/builds/slave/test', 12:58:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:58 INFO - 'SHELL': '/bin/bash', 12:58:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:58:58 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:58:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:58 INFO - 'USER': 'cltbld', 12:58:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:58 INFO - 'XPC_FLAGS': '0x0', 12:58:58 INFO - 'XPC_SERVICE_NAME': '0', 12:58:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:59:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:59:01 INFO - Installing collected packages: mozsystemmonitor 12:59:01 INFO - Running setup.py install for mozsystemmonitor 12:59:01 INFO - Successfully installed mozsystemmonitor 12:59:01 INFO - Cleaning up... 12:59:01 INFO - Return code: 0 12:59:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:59:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:01 INFO - 'HOME': '/Users/cltbld', 12:59:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:01 INFO - 'LOGNAME': 'cltbld', 12:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:01 INFO - 'MOZ_NO_REMOTE': '1', 12:59:01 INFO - 'NO_EM_RESTART': '1', 12:59:01 INFO - 'PAGER': '/bin/cat', 12:59:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:01 INFO - 'PWD': '/builds/slave/test', 12:59:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:01 INFO - 'SHELL': '/bin/bash', 12:59:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:01 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:01 INFO - 'USER': 'cltbld', 12:59:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:01 INFO - 'XPC_FLAGS': '0x0', 12:59:01 INFO - 'XPC_SERVICE_NAME': '0', 12:59:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:02 INFO - Downloading/unpacking jsonschema==2.5.1 12:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:59:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:05 INFO - Downloading functools32-3.2.3-2.tar.gz 12:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:59:05 INFO - warning: no files found matching '*.txt' 12:59:05 INFO - no previously-included directories found matching 'build' 12:59:05 INFO - no previously-included directories found matching 'dist' 12:59:05 INFO - no previously-included directories found matching '.git*' 12:59:05 INFO - Installing collected packages: jsonschema, functools32 12:59:05 INFO - Running setup.py install for functools32 12:59:05 INFO - warning: no files found matching '*.txt' 12:59:05 INFO - no previously-included directories found matching 'build' 12:59:05 INFO - no previously-included directories found matching 'dist' 12:59:05 INFO - no previously-included directories found matching '.git*' 12:59:05 INFO - Successfully installed jsonschema functools32 12:59:05 INFO - Cleaning up... 12:59:05 INFO - Return code: 0 12:59:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:59:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:59:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:05 INFO - 'HOME': '/Users/cltbld', 12:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:05 INFO - 'LOGNAME': 'cltbld', 12:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:05 INFO - 'MOZ_NO_REMOTE': '1', 12:59:05 INFO - 'NO_EM_RESTART': '1', 12:59:05 INFO - 'PAGER': '/bin/cat', 12:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:05 INFO - 'PWD': '/builds/slave/test', 12:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:05 INFO - 'SHELL': '/bin/bash', 12:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:05 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:05 INFO - 'USER': 'cltbld', 12:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:05 INFO - 'XPC_FLAGS': '0x0', 12:59:05 INFO - 'XPC_SERVICE_NAME': '0', 12:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:59:05 INFO - Cleaning up... 12:59:05 INFO - Return code: 0 12:59:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:59:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:05 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:59:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:59:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:05 INFO - 'HOME': '/Users/cltbld', 12:59:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:05 INFO - 'LOGNAME': 'cltbld', 12:59:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:05 INFO - 'MOZ_NO_REMOTE': '1', 12:59:05 INFO - 'NO_EM_RESTART': '1', 12:59:05 INFO - 'PAGER': '/bin/cat', 12:59:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:05 INFO - 'PWD': '/builds/slave/test', 12:59:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:05 INFO - 'SHELL': '/bin/bash', 12:59:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:05 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:05 INFO - 'USER': 'cltbld', 12:59:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:05 INFO - 'XPC_FLAGS': '0x0', 12:59:05 INFO - 'XPC_SERVICE_NAME': '0', 12:59:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:06 INFO - Downloading/unpacking blobuploader==1.2.4 12:59:06 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:59:06 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:59:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:06 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:59:06 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:59:08 INFO - Downloading blobuploader-1.2.4.tar.gz 12:59:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:59:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:59: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:59: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:59:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59: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:59: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:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:59:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:59:09 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:59:09 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:59:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:09 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:59:09 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:59:09 INFO - Downloading docopt-0.6.1.tar.gz 12:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:59:09 INFO - Installing collected packages: blobuploader, requests, docopt 12:59:09 INFO - Running setup.py install for blobuploader 12:59:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:59:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:59:09 INFO - Running setup.py install for requests 12:59:10 INFO - Running setup.py install for docopt 12:59:10 INFO - Successfully installed blobuploader requests docopt 12:59:10 INFO - Cleaning up... 12:59:10 INFO - Return code: 0 12:59:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:10 INFO - 'HOME': '/Users/cltbld', 12:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:10 INFO - 'LOGNAME': 'cltbld', 12:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:10 INFO - 'MOZ_NO_REMOTE': '1', 12:59:10 INFO - 'NO_EM_RESTART': '1', 12:59:10 INFO - 'PAGER': '/bin/cat', 12:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:10 INFO - 'PWD': '/builds/slave/test', 12:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:10 INFO - 'SHELL': '/bin/bash', 12:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:10 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:10 INFO - 'USER': 'cltbld', 12:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:10 INFO - 'XPC_FLAGS': '0x0', 12:59:10 INFO - 'XPC_SERVICE_NAME': '0', 12:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:10 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fNZWQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-6vltUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-_Flcxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-jZLb4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-T3uUFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-uFymLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Fev_Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NrC538-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-lCdyKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:11 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-jKfv2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-msd54p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7fEYO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-xhZNMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-NmWyji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-VHgQSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-AstD3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:12 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-A9cNF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:59:12 INFO - Running setup.py install for manifestparser 12:59:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:59:12 INFO - Running setup.py install for mozcrash 12:59:13 INFO - Running setup.py install for mozdebug 12:59:13 INFO - Running setup.py install for mozdevice 12:59:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Running setup.py install for mozfile 12:59:13 INFO - Running setup.py install for mozhttpd 12:59:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Running setup.py install for mozinfo 12:59:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Running setup.py install for mozInstall 12:59:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:59:13 INFO - Running setup.py install for mozleak 12:59:14 INFO - Running setup.py install for mozlog 12:59:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Running setup.py install for moznetwork 12:59:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Running setup.py install for mozprocess 12:59:14 INFO - Running setup.py install for mozprofile 12:59:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Running setup.py install for mozrunner 12:59:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:59:14 INFO - Running setup.py install for mozscreenshot 12:59:15 INFO - Running setup.py install for moztest 12:59:15 INFO - Running setup.py install for mozversion 12:59:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:59:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:59:15 INFO - Cleaning up... 12:59:15 INFO - Return code: 0 12:59:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:15 INFO - 'HOME': '/Users/cltbld', 12:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:15 INFO - 'LOGNAME': 'cltbld', 12:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:15 INFO - 'MOZ_NO_REMOTE': '1', 12:59:15 INFO - 'NO_EM_RESTART': '1', 12:59:15 INFO - 'PAGER': '/bin/cat', 12:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:15 INFO - 'PWD': '/builds/slave/test', 12:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:15 INFO - 'SHELL': '/bin/bash', 12:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:15 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:15 INFO - 'USER': 'cltbld', 12:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:15 INFO - 'XPC_FLAGS': '0x0', 12:59:15 INFO - 'XPC_SERVICE_NAME': '0', 12:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:15 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YkLOdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:15 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-poD024-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:15 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-v0n3_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-jL68uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dvExGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-hpLuo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-tkjJYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-M0t6hT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fbK6yT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-DdehLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-2vRPpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-eZgzBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:16 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ad9tIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-iPeXUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5OnyFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-YH0jb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:17 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-nGtuLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:59:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:19 INFO - Downloading blessings-1.6.tar.gz 12:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:59:20 INFO - Installing collected packages: blessings 12:59:20 INFO - Running setup.py install for blessings 12:59:20 INFO - Successfully installed blessings 12:59:20 INFO - Cleaning up... 12:59:20 INFO - Return code: 0 12:59:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:59:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:20 INFO - Reading from file tmpfile_stdout 12:59:20 INFO - Current package versions: 12:59:20 INFO - blessings == 1.6 12:59:20 INFO - blobuploader == 1.2.4 12:59:20 INFO - docopt == 0.6.1 12:59:20 INFO - functools32 == 3.2.3-2 12:59:20 INFO - jsonschema == 2.5.1 12:59:20 INFO - manifestparser == 1.1 12:59:20 INFO - mozInstall == 1.12 12:59:20 INFO - mozcrash == 0.17 12:59:20 INFO - mozdebug == 0.1 12:59:20 INFO - mozdevice == 0.48 12:59:20 INFO - mozfile == 1.2 12:59:20 INFO - mozhttpd == 0.7 12:59:20 INFO - mozinfo == 0.9 12:59:20 INFO - mozleak == 0.1 12:59:20 INFO - mozlog == 3.4 12:59:20 INFO - moznetwork == 0.27 12:59:20 INFO - mozprocess == 0.23 12:59:20 INFO - mozprofile == 0.28 12:59:20 INFO - mozrunner == 6.12 12:59:20 INFO - mozscreenshot == 0.1 12:59:20 INFO - mozsystemmonitor == 0.3 12:59:20 INFO - moztest == 0.7 12:59:20 INFO - mozversion == 1.4 12:59:20 INFO - psutil == 3.1.1 12:59:20 INFO - requests == 1.2.3 12:59:20 INFO - wsgiref == 0.1.2 12:59:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:20 INFO - 'HOME': '/Users/cltbld', 12:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:20 INFO - 'LOGNAME': 'cltbld', 12:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:20 INFO - 'MOZ_NO_REMOTE': '1', 12:59:20 INFO - 'NO_EM_RESTART': '1', 12:59:20 INFO - 'PAGER': '/bin/cat', 12:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:20 INFO - 'PWD': '/builds/slave/test', 12:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:20 INFO - 'SHELL': '/bin/bash', 12:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:20 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:20 INFO - 'USER': 'cltbld', 12:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:20 INFO - 'XPC_FLAGS': '0x0', 12:59:20 INFO - 'XPC_SERVICE_NAME': '0', 12:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:59:20 INFO - Cleaning up... 12:59:20 INFO - Return code: 0 12:59:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x107a60490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079d6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d69d76a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:59:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:59:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:20 INFO - 'HOME': '/Users/cltbld', 12:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:20 INFO - 'LOGNAME': 'cltbld', 12:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:20 INFO - 'MOZ_NO_REMOTE': '1', 12:59:20 INFO - 'NO_EM_RESTART': '1', 12:59:20 INFO - 'PAGER': '/bin/cat', 12:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:20 INFO - 'PWD': '/builds/slave/test', 12:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:20 INFO - 'SHELL': '/bin/bash', 12:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:20 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:20 INFO - 'USER': 'cltbld', 12:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:20 INFO - 'XPC_FLAGS': '0x0', 12:59:20 INFO - 'XPC_SERVICE_NAME': '0', 12:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:59:21 INFO - Cleaning up... 12:59:21 INFO - Return code: 0 12:59:21 INFO - Running post-action listener: _resource_record_post_action 12:59:21 INFO - Running post-action listener: _start_resource_monitoring 12:59:21 INFO - Starting resource monitoring. 12:59:21 INFO - [mozharness: 2016-10-26 19:59:21.230200Z] Finished create-virtualenv step (success) 12:59:21 INFO - [mozharness: 2016-10-26 19:59:21.231256Z] Running install step. 12:59:21 INFO - Running pre-action listener: _resource_record_pre_action 12:59:21 INFO - Running main action method: install 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:21 INFO - Reading from file tmpfile_stdout 12:59:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:59:21 INFO - Reading from file tmpfile_stdout 12:59:21 INFO - Output received: 12:59:21 INFO - Usage: mozinstall [options] installer 12:59:21 INFO - Options: 12:59:21 INFO - -h, --help show this help message and exit 12:59:21 INFO - -d DEST, --destination=DEST 12:59:21 INFO - Directory to install application into. [default: 12:59:21 INFO - "/builds/slave/test"] 12:59:21 INFO - --app=APP Application being installed. [default: firefox] 12:59:21 INFO - mkdir: /builds/slave/test/build/application 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:59:56 INFO - Reading from file tmpfile_stdout 12:59:56 INFO - Output received: 12:59:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:59:56 INFO - Running post-action listener: _resource_record_post_action 12:59:56 INFO - [mozharness: 2016-10-26 19:59:56.233109Z] Finished install step (success) 12:59:56 INFO - [mozharness: 2016-10-26 19:59:56.233238Z] Running run-tests step. 12:59:56 INFO - Running pre-action listener: _resource_record_pre_action 12:59:56 INFO - Running main action method: run_tests 12:59:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:56 INFO - 2016-10-26 12:59:56.247 screenresolution[1947:64084] starting screenresolution argv=screenresolution get 12:59:56 INFO - 2016-10-26 12:59:56.250 screenresolution[1947:64084] Display 0: 1600x1200x32@60 12:59:56 INFO - 2016-10-26 12:59:56.256 screenresolution[1948:64086] starting screenresolution argv=screenresolution list 12:59:56 INFO - Available Modes on Display 0 12:59:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:56 INFO - Intel Iris: 12:59:56 INFO - Chipset Model: Intel Iris 12:59:56 INFO - Type: GPU 12:59:56 INFO - Bus: Built-In 12:59:56 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:56 INFO - Vendor: Intel (0x8086) 12:59:56 INFO - Device ID: 0x0a2e 12:59:56 INFO - Revision ID: 0x0009 12:59:56 INFO - Displays: 12:59:56 INFO - Display: 12:59:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:56 INFO - Display Serial Number: 1600x1200 60 12:59:56 INFO - Main Display: Yes 12:59:56 INFO - Mirror: Off 12:59:56 INFO - Online: Yes 12:59:56 INFO - Rotation: Supported 12:59:56 INFO - Return code: 0 12:59:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:59:56 INFO - Python 2.7.3 12:59:56 INFO - Return code: 0 12:59:56 INFO - grabbing minidump binary from tooltool 12:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107874d30>, '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 0x10769adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10786cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:59:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:59:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:59:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:59:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:59:56 INFO - Return code: 0 12:59:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:59:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:59:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:59:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:59:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:59:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CITa1C1C74/Render', 12:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:56 INFO - 'HOME': '/Users/cltbld', 12:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:56 INFO - 'LOGNAME': 'cltbld', 12:59:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:59:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:56 INFO - 'MOZ_NO_REMOTE': '1', 12:59:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:59:56 INFO - 'NO_EM_RESTART': '1', 12:59:56 INFO - 'PAGER': '/bin/cat', 12:59:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:56 INFO - 'PWD': '/builds/slave/test', 12:59:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:56 INFO - 'SHELL': '/bin/bash', 12:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners', 12:59:56 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 12:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:56 INFO - 'USER': 'cltbld', 12:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:56 INFO - 'XPC_FLAGS': '0x0', 12:59:56 INFO - 'XPC_SERVICE_NAME': '0', 12:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:59:56 INFO - mozversion application_buildid: 20161026095323 12:59:56 INFO - mozversion application_changeset: 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b 12:59:56 INFO - mozversion application_display_name: Nightly 12:59:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:59:56 INFO - mozversion application_name: Firefox 12:59:56 INFO - mozversion application_remotingname: firefox 12:59:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 12:59:56 INFO - mozversion application_vendor: Mozilla 12:59:56 INFO - mozversion application_version: 52.0a1 12:59:56 INFO - mozversion platform_buildid: 20161026095323 12:59:56 INFO - mozversion platform_changeset: 8e23fb23036b1d49ab9b39c5d79fbf12d4336a8b 12:59:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 12:59:56 INFO - mozversion platform_version: 52.0a1 12:59:56 INFO - using testdate: 1477511996 12:59:56 INFO - actual date: 1477511996 12:59:56 INFO - starting webserver on 'localhost:50802' 12:59:56 INFO - SUITE-START | Running 2 tests 12:59:56 INFO - TEST-START | dromaeo_css 12:59:56 INFO - operating with platform_type : mac_ 12:59:56 INFO - Initialising browser for dromaeo_css test... 12:59:56 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpgQAkfp/profile http://localhost:50802/getInfo.html) 12:59:59 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 12:59:59 INFO - PROCESS | 1958 | colorDepth:24 12:59:59 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 12:59:59 INFO - PROCESS | 1958 | __metrics 12:59:59 INFO - PROCESS | 1958 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:59:59 INFO - PROCESS | 1958 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:00:00 INFO - TEST-INFO | 1958: exit 0 13:00:00 INFO - Browser initialized. 13:00:00 INFO - Running cycle 1/1 for dromaeo_css test... 13:00:00 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpgQAkfp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:12 INFO - PROCESS | 1960 | RSS: Main: 233000960 13:00:12 INFO - PROCESS | 1960 | 13:01:03 INFO - PROCESS | 1960 | 1477512063364 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] 13:03:16 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50802/tests/dromaeo/cssquery-ext.html) 13:03:18 INFO - PROCESS | 1960 | RSS: Main: 412667904 13:03:18 INFO - PROCESS | 1960 | 13:06:21 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-ext.html (next: http://localhost:50802/tests/dromaeo/cssquery-jquery.html) 13:06:21 INFO - PROCESS | 1960 | RSS: Main: 373161984 13:06:21 INFO - PROCESS | 1960 | 13:09:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50802/tests/dromaeo/cssquery-mootools.html) 13:09:24 INFO - PROCESS | 1960 | RSS: Main: 344264704 13:09:24 INFO - PROCESS | 1960 | 13:12:27 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50802/tests/dromaeo/cssquery-prototype.html) 13:12:27 INFO - PROCESS | 1960 | RSS: Main: 341954560 13:12:27 INFO - PROCESS | 1960 | 13:15:31 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50802/tests/dromaeo/cssquery-yui.html) 13:15:31 INFO - PROCESS | 1960 | RSS: Main: 325795840 13:15:31 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:50802/tests/dromaeo/cssquery-yui.html (next: http://localhost:50802/tests/dromaeo/cssquery-dojo.html) 13:18:35 INFO - PROCESS | 1960 | RSS: Main: 376455168 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | __start_tp_report 13:18:35 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 13:18:35 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 13:18:35 INFO - PROCESS | 1960 | |i|pagename|runs| 13:18:35 INFO - PROCESS | 1960 | |0;dojo.html;3253.5282384054326;3263.445214313567;3272.967270327297;3303.322818822142;3320.966790332096;5493.423190564993;5591.580631452637;5607.691576963271;5638.633488823224;5684.118961560955;4861.927071093936;5597.692126933017;5621.550275977922;5636.323641163056;5732.7133643317775;5177.741112944356;5202.401723801773;5205.5054769797;5215.478452154774;5226;5107.3105130807235;5163.741812909339;5235.267062611238;5245.291885595453;5269.6311258211945;5233.502817232356;5254.658447200939;5260.395054568736;5271.947280527184;5277.815276465324;5079.060373830816;5204.089284375238;5206.921896171561;5221.269022336838;5314.548263397616;5107.770150343244;5129.794808207705;5155.793768249266;5167.173252279617;5176.352955880515;5497.175423686479;5519.061759500854;5521.585881058896;5529.668219906818;5538.335399752015;6129.356417576179;6134.785282515135;6142.477889379364;6154.907676384858;6158.538109641795;5112.41207261166;6005.609635373687;6102.847428814271;6117.388261173892;6238.220222472191;5707.800226992118;5715.742791574389;5724.484796368347;5732.598718089694;5737.397573254748;6247.250329960434;6304.621722696561;6321.304656487782;6326.715297811609;6331.778387756406;5861.032929566573;5865.882682346414;5932.673702946379;5947.286325640951;6064.4845188158615;6145.385461453909;6196;6221.035739460391;6227.221597300338;6237.781677641261;6120.571559990758;6128.272918551291;6141.91175000502;6151.876962460815;6156.753729850756;5950.851228719317;5957.255343082114;6023.728932198062;6143.017117261216;6143.723532441051;4816;4834.661573689878;4878.926816097762;4931.630127740434;4933.939203071357;4375.337371798696;4386.100849325944;4391.165678521071;4399.6920215584605;4449.243628583149;5524.66851988882;5693.914591281134;5704.543636509153;5711.6858572778065;5777.740001699934;6000.913441490143;6115.788007244199;6148.44663980244;6156.322804491502;6181.103739957681;5732.16883551891;5747;5751.654900705971;5765.596408251382;5788.82633520995;4987.777994391397;5298.814541491107;5309.283246761638;5381.219723140122;5405.675659460367;5127.846164615067;6174.413430724074;6185;6192.411720886508;6245.594036387621;6213.782517611862;6219.906701399392;6221.5333849960525;6233.657148856856;6253.218347706536;6269.122322874892;6323.146375239284;6324.2410910691015;6326.177596912372;6333.398327158912;5934.673592952429;6018.21763170785;6100.542459315658;6124.203853499195;6133.969330153321;6066.332703402534;6115.209918062534;6124.350421261;6192.690365481798;6201.81394558164;5841.415858414294;5999.070144127667;6012.128241405059;6012.6392416455155;6039.214902062704;5856.267966504187;5935.762569497172;5947.524198064058;5960.821175364746;5972.402759724167;5734.655920644775;5785.39253378406;5805.187273781758;5813.709314534341;5830.970845145747;6024.8795024100145;6037.547183961308;6050.485708714633;6072.05883088122;6075.36208698098;5668.801591944262;5717.113847353667;5738.340090889601;5752.798652047326;5851.795187168429 13:18:35 INFO - PROCESS | 1960 | |1;ext.html;514.6217530115365;518.7587771686165;520.222267709774;525.8895631917297;548.8902219556087;39356.40965385518;40419;40653;42196;42227.788861055655;41572.16855662885;42042.78978605103;42089.368659470136;42140;42234;9516.76208094798;9701.27240456965;9704.70885873425;9706.320557560974;9726.367786093917;8357.707480238192;9603.32776705631;9711.12599866012;9716.076972687584;9726.805463890718;9686.370385924893;9714.90285097147;9718.854217186748;9737.853932190987;9757.316987810857;9689.273304502156;9723.611055557769;9728.124468797807;9740.707778766648;9771.267154963336;7726.343260822837;9539.09378609031;9575.712728618153;9629.951850240774;9631.277654175965;39560.80219598913;39876.60123398758;39910.60089399098;39984.400233996515;40004.79997599994;2865.7850661200287;3346.498025296201;3375.2405708715587;3409.7954122752712;3431.6322034821283;30523;30585.541216881764;30650.69349306523;30860.691393086006;31390.529142062886;3071.324308652093;3153.2589841387257;3171.5559821624993;3187.346593948258;3251.0571934139307;362087;363848;364868.17565912264;365327.34672653466;365962;57501.42498575044;57678.7116064417;57902.13146802801;57952.71023644939;57986;33788.66211337905;34569.48145777815;34586;35451.29097418089;60706;12398.318092504998;14709.485168019282;14971.251437428304;14973.625659358604;14980.925095374454;11243.437828108561;11324.37715925632;11359.034482068952;11363.488643011084;11377.772444551063;18906.338278160198;18919.905400472908;18943.715844262348;18950;18976.905115474612;7677.961610192025;7755.689772409153;7825.238659326935;7840.275139469308;7901.60491975399;877.3419935048713;956.5456408206046;1000.3547711725896;1019.6380284998867;1062.2776511971779;29389.706102939126;29495.26261843471;30083.548746768814;30164;30167.547486787716;999.9250805384096;1002.6540843409012;1023.0996722883815;1117.519466629341;1165.8717541070475;14632.268386580628;15214.923925380303;15281.083135011713;15613.765793513106;15655.843441565667;601020;636841;636964;637150;638476;56577.86844263092;57136.71431642815;57150.85698286094;57267.42732572704;57292;1373.7595920713836;1375.8073869658256;1395.2605119286795;1397.9301034948212;1402.6072699644117;27932.44135117327;28875.42249155047;28997.275068123465;29296.560551591316;29391.26521836971;48388.03223935572;48877.75561122171;48881;48882.75558622184;49032.26451603157;544179;545870;546033;547660;547671;25816.612750808377;26069.348266293495;26109.216723498324;26141.477170456103;26207.73792262053;45819.54180458286;46056.309155363364;46390.768046160905;46995;47389.81525461891;32659.673403265664;38133.618663814115;38364.61635383611;38428.80785596054;39221.41167882427;37713.43429848499;37757.81121094377;38737;38957.02607434837;39012.609873900896;27625.585616216176;36635.45046824353;37175;37530.24939501249;38157.80921095377;29895.35575543366;32107.70483043765;32858.835705821315;33435.83282083574;34066.97799066032;21679.45801354979;24939;24955.126570569944;24979.375515612253;25542.616860746733 13:18:35 INFO - PROCESS | 1960 | |2;jquery.html;322.53876955952995;342.86799582160893;351.94896739972705;367.9448082787582;370.1265014565625;15149.39402423903;15587.766183507256;15894.20528973551;17749.467515974542;21083.367498975054;20017;21050.052747626356;21451.927403629794;21468.89265553674;21740.021699023542;5685.772569097241;6045.425684559959;6047.123167140762;6050.183225264599;6053.697315134248;4199.706020578546;4952.83608352037;5937.406259374071;5983.311919129313;5997.7001149942325;4426.690131690783;4430;5957.612755170921;6575.638339891304;6585.209774827027;6323.462505687022;6482.870342593169;6550.67246637671;6619.30497297784;6636.137302150713;4915.3855768029;5679.829605111853;5680.630759000652;5682.034054210752;5699.572532060112;25141.24576262715;26129.869350653316;26173.73826261751;26266.343341416425;26439.867800661064;2258;2263.852849564773;2290.267114523353;2499.987500062506;2779.9297270550983;16356.427525036686;16425.917870410573;16653.91673041627;17608.295668173258;17695.823041769676;1917.2618541861345;2003.4991252186953;2006.8294194993443;2021.9898900505402;2033.3899830051073;127154.72845271614;131423;133129.6687033127;138225;139587.20825583432;16461.506154815375;19072.71390929137;20046.888043548803;20554.075066622037;20966.475838103863;31270.24757029171;34123.829380852934;34917.30165396678;34939;35247.64752352495;9571;9704.951475242578;9889.80220395588;10025.899741002497;10060.144887684482;7929.44493885434;7935.484193527403;7945.443818932735;8072.233137851895;8115.188481151838;9532.52337383142;9547.140757331874;9632.807343853225;9748.561314740853;9779.070988256246;2937.5764993880134;2958.45268625305;3341.8663253470077;3558.7508874378545;3694.5751238607368;807.1484583764068;831.9209675080857;911.7857303533664;937.4890684576922;941.1388579449726;16271.467700981486;19863.60272794536;20166.495837604176;21530.46173845635;21562.89218553897;732.2531018361348;855.6534603485599;915.1443400420628;919.94940278284;931.6552875436132;8981.730548083568;10981.670549883516;11360.886391135982;11411.657650270505;11460.942695286636;541648;553658;561947;568729;572011;127728;130093;130097;130730;132829;921.9907800921904;935.3919952031234;960.5389413081759;962.0764066496242;982.7101005203625;18435.354762583243;19116.8088319115;19121;19148.61702765909;19212;53107.73446132745;53642.19536706874;54035;55107;55146.44853551413;72426.27573724196;75082;75896.62051689706;76918.2308176911;77029.61485192538;7101.751438699621;7826.099998500245;7850.2542258485355;8153.858459815512;8305.667773289;11770;12020.098492612915;12117.27296362221;12325.322107283826;12743.74512509726;10731.257254536355;10977.176711746491;11034.889651103707;11038.558457661604;11072.169587280825;10676.412797296223;10850.511726972147;10862.674119776419;11067.335959842114;11234.775304493702;11088.279261847954;11214.7757044857;11252.493637786156;11407.885921140682;11544.88455115438;7961.641726122454;8111.594420278844;8132.471389359673;8162.755117346489;8412;8222.465539739898;10621.522031508446;11048.668539943814;11263.324200547993;11279.436028198721 13:18:35 INFO - PROCESS | 1960 | |3;mootools.html;1521.634807646165;1572.6304318485154;1770.8848924819886;1884.896330701811;1924.9037548122599;8228;8553.871691924625;8554.401191916568;8558.443701159427;8574.442661227016;7620.352270057038;7902.881456778153;7912.525248485095;8278.379121565891;8500.829983400328;2267.30847091637;2296.552172326397;2297.6783250344965;2298.839081264304;2305.976940230593;1949.19108569944;2267.9319620411416;2300.0799680128;2303.216906251874;2305.7578954209816;2256.047974695544;2272.5454909018263;2281.3042022183226;2304.8847557622134;2316.93049208524;2256.3907744908947;2263.909443622253;2283.497630521291;2283.680284760135;2284.6001949658744;2246.6068438023444;2249.6513040478753;2250.8199344052437;2254.7068881045525;2258.864468131901;8589.914100858974;8658.783530411727;8707.259882909897;8728.30173586118;8776.64893404263;5087.745612719349;5254.133068043807;5259.316288882459;5262.342207224097;5273.525382715536;3331.3337332533592;3383.1203886989315;3521.4365701487895;3533.1343820764077;3546.8226588670723;871.6077765005709;1028.36755395432;1033.1631378583372;1043.1015551695916;1048.250500891866;11317.547298108066;11364.374959377232;11374.260673056226;11395.715107122305;11409.60066397672;4345.521992580813;4624.606908412756;4667.533246675305;4672.252439609646;4681.204195286809;9733.756656083513;9746.269029822792;9765.707028789147;9768.804623907625;9779.853302200472;5234.528892399702;5236.1098613235845;5241.764120614582;5255.947440525622;5256;4071.572484889103;4265.4881414230495;4360.8909777255185;4785.928211076836;4814.229723244334;3786.753860999026;3807.8857634271017;3895.1430685249766;3902.31709450845;3941.7437866538585;1240.1071228715311;1252.3738130934532;1253.3795771093367;1264.424686767537;1268.2834198677717;890.9866352004725;912.7124955638952;926.2312280806914;926.7358802741321;935.373299889076;4940.456549779521;5751.913721294184;5766.990776614076;5843.5909486336395;6089.238845144357;911.261877878907;956.784723437235;963.3208587945481;967.2987084363921;1017.3387298256044;3794.203217324393;3897.57126716061;3974.046228905042;4001;4060.167665628539;8135.430519863553;10324.483775811179;10344.586216551254;10355;10491.10825579819;8703.912960870437;10018.39521724357;10226.335288206243;10330.48347582633;10382.377057376581;7881.290683838482;8091.39314551411;8098.028236611644;8106.878396824052;8107.067687215927;5675.829725108253;5805.506531944663;5939.703014849327;6077.878442431215;6093.207882975362;9804.607815687292;9861.704148875546;9864.802703946025;9865.408075515492;9869.950650246703;9491.620335186515;9922.950385248028;10084.142847858015;10113.494325283853;10163.7459063524;7398.926010739824;7631.198724133886;7672.6930922764695;8036.879446808186;8205.71280005197;7162.997180394853;7571.507851989603;7635.732749353746;7876.09424916119;8229.95885020571;7242.746503872339;7344.22885597026;7376.852462950604;7395.297446742551;7582.924170758221;7163.462740294394;7204.135503739585;7243.782686519413;7309.634518273959;7319.560826350436;6804.285550017375;6807.421369183477;6892.827679308058;6900.068490753884;6915.135608048994;4907.435644900882;4948.109340318777;4963.975180124076;4996.825111121093;5061.190209566599;4885.926711099408;4907.263910413467;4967.925481117713;5046.59627229828;5047.4952504749235 13:18:35 INFO - PROCESS | 1960 | |4;prototype.html;290.46844274976786;295.158797427332;306.2084511537675;310.13627048669446;312.48752046643494;4358.476982762069;5066.429406883869;5167.560757335622;5232.912581576868;5289.497497737713;4361.208826351138;4535.251683472223;4938.580220681238;5022.673526220793;5159.3808742950805;3523.4010218262956;4055.1143718171043;4064.2887494688457;4102.2410853992105;4108.465899433069;3084.706952839477;4051.6758659307334;4058.533268674096;4066.329055705805;4102.671786257092;3974.463447434588;3981.86063487778;3990.9800450997705;3998.10042740384;4035.556088830226;3930.95590293046;3968;3975.761454312736;3985.720999530034;3991.7604943703327;2914.6939571344915;3411.385950528904;3438.5529881115544;3442.9827850860834;3447.2933048725204;4827;5104.387473503166;5123.948760512384;5153.716545589991;5168;3932.842686292545;4434.534373890728;4518.853544566337;4528.75091869947;4556.840510582147;4888.2178851383605;5543.6119471637;5546.975353995803;5548.807006493624;5555.861103472405;3422.9388889444353;3574.910627234314;3627.4921510988484;3779.7165212609166;3790.7915064671433;5740.196372507852;5756.481916627524;5760.711964401763;5767.106098554731;5805.245318108661;3476.89569312921;3635.381985062546;3755.4930084438797;3815.542134943769;3832.3485007548784;3103.5723567158907;3438.862445502152;3489.7557170998293;3535.964640353615;3892.4161375793856;3510.368133735952;4498.8425405110665;4517.390152329459;4525.456945166601;4611.100835337144;4393.824247030147;5445.98498555537;5537.892421515713;5542.61201715876;5548.361938377138;1234.6110975042832;1264.9557265495664;1282.224254326138;1291.4575878131207;1303.3027330378202;870.2472361407464;892.4377642085575;892.9687460938836;899.9301606305471;907.2469850024464;4502.4371953505815;5193.300063442591;5229.79714665629;5263.236830659533;5279.574514880961;4157.605027522381;5032.798688052437;5633.8591535211945;5656.9817432861655;5663.281624820027;4333.414988976511;5141.634847969975;5156;5166.664166829144;5185.0407674579565;2132.922873948646;2251.9078247050165;2352.412121817766;2450.914218002397;2482.9751702482745;3992.3612222044335;4347.391565060938;4559.772011399483;4780.378550788375;4785.569298763058;4403.383526306319;4725.196716558194;4735.810567577328;4826.55203438974;4831.5746854677955;4150.896227594334;4631.675782695246;4644.632641335005;4786.090642777861;4819;4042.69679774024;4135.359019352005;4662.813487460464;4694.464988905953;4701;4111.054457474737;4278.743275403485;4452.643788496977;4512.345709872116;4673.369095172245;4138.8344466221015;4273.529911709647;4468.9944762428195;4609.547992382389;4811.855644330676;3248.440748440809;3280.1799550112473;3289.950650740289;3320.6015278166774;3327.733781297539;4165.500139983222;4513.652900491791;4593.185691651845;5777.24895763548;5827.446392592697;5149;5591.021571225101;5647.802326918538;5665.603407761417;5675.687837168995;4928;5597.3003374578175;5598.972005139948;5607.635503692247;5620.487195502811;4576;4581.381513495769;4749.976250118727;5417.8103766367985;5456.836294911159;4498.6934603067375;4915.829290107789;5062.443131255485;5126.282320475211;5602.386460732723;4422.71252368595;5223.164293712987;5417.753916599283;5489.4236105209975;5489.4236105209975 13:18:35 INFO - PROCESS | 1960 | |5;yui.html;662.2582707367749;764.2035163286472;795.7612716185143;813.0812182234074;824.2869917521344;15085.396584136635;15941.760873586876;15960.600984975381;15966.28151733172;15973.840261597396;14027.509037183701;14411.207383593897;14733.26333683317;14741.705165896676;14751;858.6393714639835;870.6517393042802;885.1635204731531;886.7162507997443;888.4713595410752;701.2706784943653;720.4632548751188;890.4568213389826;895.7178488776048;897.0894542039803;753.355880721983;917.7338571814166;941.3928016429398;945.9006804285555;947.1570302430843;926.6015613286285;926.717351207878;928.4475736936512;929.6095639831287;929.6885543342988;535.7642637239602;685.7942617214866;686.1012074182787;686.7596341280562;687.0484379134884;12338.75322493554;14493.855061449462;15068.246587670572;15079.472218472301;15439.228038598025;14277.643058923506;14493.42026318946;14828.481003164838;14963.177025263607;15106.39574417022;11512.251703639236;11792.35142067186;11885.762284754343;12161.69595760113;12236.571719989757;3710.7216958728204;3714.1643130295465;3749.193923306475;3761.9811900940595;3835.6356146166067;107606;108119;111394;114466.55334466486;116337.41831290875;10816.242862999514;10817.78364432707;10823.025927666547;11019.228653994303;11249.325040497433;22069.889650551646;22084.88957555202;22108.668369974464;22113.66829497559;22123.889380553315;6818.624975626388;7656.272654097852;7701.576413297323;7825.21847690387;7856.842863142705;4359.258925982527;4383.211022016004;4437.2234858899565;4466.932996005063;4563.452385713736;3440.6215316315183;3566.465030245484;3594.1374070223483;3595;3605.351036813399;663.7013343995221;665.6738198282903;671.2213831954908;677.4140368581142;678.4335080207983;531.7102179312284;566.6175331651119;567.6622409666242;674.922383925845;680.9241398590315;8840.328281196396;9349.252059835197;9433.575489103006;9440.36545929822;9520.952395237979;541.3611937913222;589.9144624029492;648.5330561995356;655.7737580534708;666.1539844397588;4172.457580514574;4587.128445595326;4609.216433206296;4633.86098417048;4635.65565985867;102706;102845;103879.96120038854;105245;158657;111915;141666;141956.29021854824;145295.2735236317;150275;67410.95534267959;116450.41774791072;121178;124308;126338.99999999817;8415.537145456814;9757.853632195382;9825.740079781834;9874.753131171778;9943.403395795985;47894.56316310377;51699.741501293756;58983.115253272095;62368.75262494634;62712;90715.66054243219;92812;95072.19711211474;95767.0423295786;97419.025809741;12087.395630218278;12809.42357593892;12997.740045199233;13140.868591313965;13413.122162897398;11812.704682382666;14192.929035354757;14702.485413010494;14826.703465930837;14867.628309292355;13583.252921089435;13692;13853.792193117313;14036.78944815849;14227.573172804832;11895.82156267674;13786.724265514835;13966.441342346192;14382.712345753236;14390.640233993816;13527.86472135266;13600.523981660594;13917.58247252584;14167.574972750834;14376.993610447169;11508.712282193012;11538.019268361948;11612.88387116118;11704.297742135499;11717.648470545897;10810.783784324112;11320.716982075513;11442.713932151762;11527.135464840338;11570.247933884271 13:18:35 INFO - PROCESS | 1960 | __end_tp_report 13:18:35 INFO - PROCESS | 1960 | __start_cc_report 13:18:35 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,2215 13:18:35 INFO - PROCESS | 1960 | __end_cc_report 13:18:35 INFO - PROCESS | 1960 | __startTimestamp1477513115300__endTimestamp 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | ------- Summary: start ------- 13:18:35 INFO - PROCESS | 1960 | Number of tests: 6 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#0] dojo.html Cycles:165 Average:5657.15 Median:5788.83 stddev:627.89 (10.8%) stddev-sans-first:600.84 13:18:35 INFO - PROCESS | 1960 | Values: 3253.5 3263.4 3273.0 3303.3 3321.0 5493.4 5591.6 5607.7 5638.6 5684.1 4861.9 5597.7 5621.6 5636.3 5732.7 5177.7 5202.4 5205.5 5215.5 5226.0 5107.3 5163.7 5235.3 5245.3 5269.6 5233.5 5254.7 5260.4 5271.9 5277.8 5079.1 5204.1 5206.9 5221.3 5314.5 5107.8 5129.8 5155.8 5167.2 5176.4 5497.2 5519.1 5521.6 5529.7 5538.3 6129.4 6134.8 6142.5 6154.9 6158.5 5112.4 6005.6 6102.8 6117.4 6238.2 5707.8 5715.7 5724.5 5732.6 5737.4 6247.3 6304.6 6321.3 6326.7 6331.8 5861.0 5865.9 5932.7 5947.3 6064.5 6145.4 6196.0 6221.0 6227.2 6237.8 6120.6 6128.3 6141.9 6151.9 6156.8 5950.9 5957.3 6023.7 6143.0 6143.7 4816.0 4834.7 4878.9 4931.6 4933.9 4375.3 4386.1 4391.2 4399.7 4449.2 5524.7 5693.9 5704.5 5711.7 5777.7 6000.9 6115.8 6148.4 6156.3 6181.1 5732.2 5747.0 5751.7 5765.6 5788.8 4987.8 5298.8 5309.3 5381.2 5405.7 5127.8 6174.4 6185.0 6192.4 6245.6 6213.8 6219.9 6221.5 6233.7 6253.2 6269.1 6323.1 6324.2 6326.2 6333.4 5934.7 6018.2 6100.5 6124.2 6134.0 6066.3 6115.2 6124.4 6192.7 6201.8 5841.4 5999.1 6012.1 6012.6 6039.2 5856.3 5935.8 5947.5 5960.8 5972.4 5734.7 5785.4 5805.2 5813.7 5831.0 6024.9 6037.5 6050.5 6072.1 6075.4 5668.8 5717.1 5738.3 5752.8 5851.8 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#1] ext.html Cycles:180 Average:64538.35 Median:38396.71 stddev:140531.48 (366.0%) stddev-sans-first:140843.50 13:18:35 INFO - PROCESS | 1960 | Values: 514.6 518.8 520.2 525.9 548.9 39356.4 40419.0 40653.0 42196.0 42227.8 41572.2 42042.8 42089.4 42140.0 42234.0 9516.8 9701.3 9704.7 9706.3 9726.4 8357.7 9603.3 9711.1 9716.1 9726.8 9686.4 9714.9 9718.9 9737.9 9757.3 9689.3 9723.6 9728.1 9740.7 9771.3 7726.3 9539.1 9575.7 9630.0 9631.3 39560.8 39876.6 39910.6 39984.4 40004.8 2865.8 3346.5 3375.2 3409.8 3431.6 30523.0 30585.5 30650.7 30860.7 31390.5 3071.3 3153.3 3171.6 3187.3 3251.1 362087.0 363848.0 364868.2 365327.3 365962.0 57501.4 57678.7 57902.1 57952.7 57986.0 33788.7 34569.5 34586.0 35451.3 60706.0 12398.3 14709.5 14971.3 14973.6 14980.9 11243.4 11324.4 11359.0 11363.5 11377.8 18906.3 18919.9 18943.7 18950.0 18976.9 7678.0 7755.7 7825.2 7840.3 7901.6 877.3 956.5 1000.4 1019.6 1062.3 29389.7 29495.3 30083.5 30164.0 30167.5 999.9 1002.7 1023.1 1117.5 1165.9 14632.3 15214.9 15281.1 15613.8 15655.8 601020.0 636841.0 636964.0 637150.0 638476.0 56577.9 57136.7 57150.9 57267.4 57292.0 1373.8 1375.8 1395.3 1397.9 1402.6 27932.4 28875.4 28997.3 29296.6 29391.3 48388.0 48877.8 48881.0 48882.8 49032.3 544179.0 545870.0 546033.0 547660.0 547671.0 25816.6 26069.3 26109.2 26141.5 26207.7 45819.5 46056.3 46390.8 46995.0 47389.8 32659.7 38133.6 38364.6 38428.8 39221.4 37713.4 37757.8 38737.0 38957.0 39012.6 27625.6 36635.5 37175.0 37530.2 38157.8 29895.4 32107.7 32858.8 33435.8 34067.0 21679.5 24939.0 24955.1 24979.4 25542.6 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#2] jquery.html Cycles:180 Average:35476.28 Median:17233.35 stddev:94080.16 (545.9%) stddev-sans-first:94307.05 13:18:35 INFO - PROCESS | 1960 | Values: 322.5 342.9 351.9 367.9 370.1 15149.4 15587.8 15894.2 17749.5 21083.4 20017.0 21050.1 21451.9 21468.9 21740.0 5685.8 6045.4 6047.1 6050.2 6053.7 4199.7 4952.8 5937.4 5983.3 5997.7 4426.7 4430.0 5957.6 6575.6 6585.2 6323.5 6482.9 6550.7 6619.3 6636.1 4915.4 5679.8 5680.6 5682.0 5699.6 25141.2 26129.9 26173.7 26266.3 26439.9 2258.0 2263.9 2290.3 2500.0 2779.9 16356.4 16425.9 16653.9 17608.3 17695.8 1917.3 2003.5 2006.8 2022.0 2033.4 127154.7 131423.0 133129.7 138225.0 139587.2 16461.5 19072.7 20046.9 20554.1 20966.5 31270.2 34123.8 34917.3 34939.0 35247.6 9571.0 9705.0 9889.8 10025.9 10060.1 7929.4 7935.5 7945.4 8072.2 8115.2 9532.5 9547.1 9632.8 9748.6 9779.1 2937.6 2958.5 3341.9 3558.8 3694.6 807.1 831.9 911.8 937.5 941.1 16271.5 19863.6 20166.5 21530.5 21562.9 732.3 855.7 915.1 919.9 931.7 8981.7 10981.7 11360.9 11411.7 11460.9 541648.0 553658.0 561947.0 568729.0 572011.0 127728.0 130093.0 130097.0 130730.0 132829.0 922.0 935.4 960.5 962.1 982.7 18435.4 19116.8 19121.0 19148.6 19212.0 53107.7 53642.2 54035.0 55107.0 55146.4 72426.3 75082.0 75896.6 76918.2 77029.6 7101.8 7826.1 7850.3 8153.9 8305.7 11770.0 12020.1 12117.3 12325.3 12743.7 10731.3 10977.2 11034.9 11038.6 11072.2 10676.4 10850.5 10862.7 11067.3 11234.8 11088.3 11214.8 11252.5 11407.9 11544.9 7961.6 8111.6 8132.5 8162.8 8412.0 8222.5 10621.5 11048.7 11263.3 11279.4 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#3] mootools.html Cycles:180 Average:5573.06 Median:5054.34 stddev:3088.39 (61.1%) stddev-sans-first:3082.05 13:18:35 INFO - PROCESS | 1960 | Values: 1521.6 1572.6 1770.9 1884.9 1924.9 8228.0 8553.9 8554.4 8558.4 8574.4 7620.4 7902.9 7912.5 8278.4 8500.8 2267.3 2296.6 2297.7 2298.8 2306.0 1949.2 2267.9 2300.1 2303.2 2305.8 2256.0 2272.5 2281.3 2304.9 2316.9 2256.4 2263.9 2283.5 2283.7 2284.6 2246.6 2249.7 2250.8 2254.7 2258.9 8589.9 8658.8 8707.3 8728.3 8776.6 5087.7 5254.1 5259.3 5262.3 5273.5 3331.3 3383.1 3521.4 3533.1 3546.8 871.6 1028.4 1033.2 1043.1 1048.3 11317.5 11364.4 11374.3 11395.7 11409.6 4345.5 4624.6 4667.5 4672.3 4681.2 9733.8 9746.3 9765.7 9768.8 9779.9 5234.5 5236.1 5241.8 5255.9 5256.0 4071.6 4265.5 4360.9 4785.9 4814.2 3786.8 3807.9 3895.1 3902.3 3941.7 1240.1 1252.4 1253.4 1264.4 1268.3 891.0 912.7 926.2 926.7 935.4 4940.5 5751.9 5767.0 5843.6 6089.2 911.3 956.8 963.3 967.3 1017.3 3794.2 3897.6 3974.0 4001.0 4060.2 8135.4 10324.5 10344.6 10355.0 10491.1 8703.9 10018.4 10226.3 10330.5 10382.4 7881.3 8091.4 8098.0 8106.9 8107.1 5675.8 5805.5 5939.7 6077.9 6093.2 9804.6 9861.7 9864.8 9865.4 9870.0 9491.6 9923.0 10084.1 10113.5 10163.7 7398.9 7631.2 7672.7 8036.9 8205.7 7163.0 7571.5 7635.7 7876.1 8230.0 7242.7 7344.2 7376.9 7395.3 7582.9 7163.5 7204.1 7243.8 7309.6 7319.6 6804.3 6807.4 6892.8 6900.1 6915.1 4907.4 4948.1 4964.0 4996.8 5061.2 4885.9 4907.3 4967.9 5046.6 5047.5 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#4] prototype.html Cycles:180 Average:4217.04 Median:4532.00 stddev:1300.56 (28.7%) stddev-sans-first:1270.38 13:18:35 INFO - PROCESS | 1960 | Values: 290.5 295.2 306.2 310.1 312.5 4358.5 5066.4 5167.6 5232.9 5289.5 4361.2 4535.3 4938.6 5022.7 5159.4 3523.4 4055.1 4064.3 4102.2 4108.5 3084.7 4051.7 4058.5 4066.3 4102.7 3974.5 3981.9 3991.0 3998.1 4035.6 3931.0 3968.0 3975.8 3985.7 3991.8 2914.7 3411.4 3438.6 3443.0 3447.3 4827.0 5104.4 5123.9 5153.7 5168.0 3932.8 4434.5 4518.9 4528.8 4556.8 4888.2 5543.6 5547.0 5548.8 5555.9 3422.9 3574.9 3627.5 3779.7 3790.8 5740.2 5756.5 5760.7 5767.1 5805.2 3476.9 3635.4 3755.5 3815.5 3832.3 3103.6 3438.9 3489.8 3536.0 3892.4 3510.4 4498.8 4517.4 4525.5 4611.1 4393.8 5446.0 5537.9 5542.6 5548.4 1234.6 1265.0 1282.2 1291.5 1303.3 870.2 892.4 893.0 899.9 907.2 4502.4 5193.3 5229.8 5263.2 5279.6 4157.6 5032.8 5633.9 5657.0 5663.3 4333.4 5141.6 5156.0 5166.7 5185.0 2132.9 2251.9 2352.4 2450.9 2483.0 3992.4 4347.4 4559.8 4780.4 4785.6 4403.4 4725.2 4735.8 4826.6 4831.6 4150.9 4631.7 4644.6 4786.1 4819.0 4042.7 4135.4 4662.8 4694.5 4701.0 4111.1 4278.7 4452.6 4512.3 4673.4 4138.8 4273.5 4469.0 4609.5 4811.9 3248.4 3280.2 3290.0 3320.6 3327.7 4165.5 4513.7 4593.2 5777.2 5827.4 5149.0 5591.0 5647.8 5665.6 5675.7 4928.0 5597.3 5599.0 5607.6 5620.5 4576.0 4581.4 4750.0 5417.8 5456.8 4498.7 4915.8 5062.4 5126.3 5602.4 4422.7 5223.2 5417.8 5489.4 5489.4 13:18:35 INFO - PROCESS | 1960 | 13:18:35 INFO - PROCESS | 1960 | [#5] yui.html Cycles:180 Average:24227.47 Median:3580.30 stddev:38260.95 (1068.7%) stddev-sans-first:38327.37 13:18:35 INFO - PROCESS | 1960 | Values: 662.3 764.2 795.8 813.1 824.3 15085.4 15941.8 15960.6 15966.3 15973.8 14027.5 14411.2 14733.3 14741.7 14751.0 858.6 870.7 885.2 886.7 888.5 701.3 720.5 890.5 895.7 897.1 753.4 917.7 941.4 945.9 947.2 926.6 926.7 928.4 929.6 929.7 535.8 685.8 686.1 686.8 687.0 12338.8 14493.9 15068.2 15079.5 15439.2 14277.6 14493.4 14828.5 14963.2 15106.4 11512.3 11792.4 11885.8 12161.7 12236.6 3710.7 3714.2 3749.2 3762.0 3835.6 107606.0 108119.0 111394.0 114466.6 116337.4 10816.2 10817.8 10823.0 11019.2 11249.3 22069.9 22084.9 22108.7 22113.7 22123.9 6818.6 7656.3 7701.6 7825.2 7856.8 4359.3 4383.2 4437.2 4466.9 4563.5 3440.6 3566.5 3594.1 3595.0 3605.4 663.7 665.7 671.2 677.4 678.4 531.7 566.6 567.7 674.9 680.9 8840.3 9349.3 9433.6 9440.4 9521.0 541.4 589.9 648.5 655.8 666.2 4172.5 4587.1 4609.2 4633.9 4635.7 102706.0 102845.0 103880.0 105245.0 158657.0 111915.0 141666.0 141956.3 145295.3 150275.0 67411.0 116450.4 121178.0 124308.0 126339.0 8415.5 9757.9 9825.7 9874.8 9943.4 47894.6 51699.7 58983.1 62368.8 62712.0 90715.7 92812.0 95072.2 95767.0 97419.0 12087.4 12809.4 12997.7 13140.9 13413.1 11812.7 14192.9 14702.5 14826.7 14867.6 13583.3 13692.0 13853.8 14036.8 14227.6 11895.8 13786.7 13966.4 14382.7 14390.6 13527.9 13600.5 13917.6 14167.6 14377.0 11508.7 11538.0 11612.9 11704.3 11717.6 10810.8 11320.7 11442.7 11527.1 11570.2 13:18:35 INFO - PROCESS | 1960 | -------- Summary: end -------- 13:18:35 INFO - PROCESS | 1960 | 13:18:36 INFO - TEST-INFO | 1960: exit 0 13:18:36 INFO - TEST-OK | dromaeo_css | took 1119196ms 13:18:36 INFO - TEST-START | kraken 13:18:36 INFO - operating with platform_type : mac_ 13:18:36 INFO - Initialising browser for kraken test... 13:18:36 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpK2rtrT/profile http://localhost:50802/getInfo.html) 13:18:38 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 13:18:38 INFO - PROCESS | 1962 | colorDepth:24 13:18:38 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 13:18:38 INFO - PROCESS | 1962 | __metrics 13:18:38 INFO - PROCESS | 1962 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:18:38 INFO - PROCESS | 1962 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:18:39 INFO - TEST-INFO | 1962: exit 0 13:18:39 INFO - Browser initialized. 13:18:39 INFO - Running cycle 1/1 for kraken test... 13:18:39 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpK2rtrT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:51 INFO - PROCESS | 1963 | RSS: Main: 232841216 13:18:51 INFO - PROCESS | 1963 | 13:18:53 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50802/tests/kraken/driver.html?testName=audio-beat-detection) 13:18:54 INFO - PROCESS | 1963 | RSS: Main: 290971648 13:18:54 INFO - PROCESS | 1963 | 13:18:57 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50802/tests/kraken/driver.html?testName=audio-dft) 13:18:58 INFO - PROCESS | 1963 | RSS: Main: 293216256 13:18:58 INFO - PROCESS | 1963 | 13:19:01 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50802/tests/kraken/driver.html?testName=audio-fft) 13:19:02 INFO - PROCESS | 1963 | RSS: Main: 386502656 13:19:02 INFO - PROCESS | 1963 | 13:19:04 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50802/tests/kraken/driver.html?testName=audio-oscillator) 13:19:05 INFO - PROCESS | 1963 | RSS: Main: 286846976 13:19:05 INFO - PROCESS | 1963 | 13:19:07 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50802/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:19:07 INFO - PROCESS | 1963 | RSS: Main: 291008512 13:19:07 INFO - PROCESS | 1963 | 13:19:10 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50802/tests/kraken/driver.html?testName=imaging-darkroom) 13:19:11 INFO - PROCESS | 1963 | RSS: Main: 328159232 13:19:11 INFO - PROCESS | 1963 | 13:19:14 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50802/tests/kraken/driver.html?testName=imaging-desaturate) 13:19:14 INFO - PROCESS | 1963 | RSS: Main: 329543680 13:19:14 INFO - PROCESS | 1963 | 13:19:17 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50802/tests/kraken/driver.html?testName=json-parse-financial) 13:19:18 INFO - PROCESS | 1963 | RSS: Main: 329986048 13:19:18 INFO - PROCESS | 1963 | 13:19:19 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50802/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:19:20 INFO - PROCESS | 1963 | RSS: Main: 302796800 13:19:20 INFO - PROCESS | 1963 | 13:19:22 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:19:22 INFO - PROCESS | 1963 | RSS: Main: 305000448 13:19:22 INFO - PROCESS | 1963 | 13:19:24 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:19:25 INFO - PROCESS | 1963 | RSS: Main: 312057856 13:19:25 INFO - PROCESS | 1963 | 13:19:28 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:19:29 INFO - PROCESS | 1963 | RSS: Main: 316616704 13:19:29 INFO - PROCESS | 1963 | 13:19:32 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:19:32 INFO - PROCESS | 1963 | RSS: Main: 317730816 13:19:32 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50802/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50802/tests/kraken/driver.html?testName=ai-astar) 13:19:34 INFO - PROCESS | 1963 | RSS: Main: 361545728 13:19:34 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | __start_tp_report 13:19:34 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 13:19:34 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 13:19:34 INFO - PROCESS | 1963 | |i|pagename|runs| 13:19:34 INFO - PROCESS | 1963 | |0;ai-astar;111;112;112;113;114;115;113;112;113;117 13:19:34 INFO - PROCESS | 1963 | |1;audio-beat-detection;174;115;114;149;156;113;116;113;143;113 13:19:34 INFO - PROCESS | 1963 | |2;audio-dft;184;185;183;189;186;185;181;183;185;183 13:19:34 INFO - PROCESS | 1963 | |3;audio-fft;81;60;58;89;62;59;55;58;82;60 13:19:34 INFO - PROCESS | 1963 | |4;audio-oscillator;85;71;92;70;69;72;92;67;68;89 13:19:34 INFO - PROCESS | 1963 | |5;imaging-gaussian-blur;89;87;89;86;93;128;117;86;89;85 13:19:34 INFO - PROCESS | 1963 | |6;imaging-darkroom;95;95;95;94;95;94;94;100;147;130 13:19:34 INFO - PROCESS | 1963 | |7;imaging-desaturate;84;81;82;80;84;79;86;85;92;121 13:19:34 INFO - PROCESS | 1963 | |8;json-parse-financial;55;54;53;52;55;55;55;56;56;54 13:19:34 INFO - PROCESS | 1963 | |9;json-stringify-tinderbox;65;72;76;77;73;69;70;73;71;72 13:19:34 INFO - PROCESS | 1963 | |10;stanford-crypto-aes;88;90;90;86;89;86;87;92;92;103 13:19:34 INFO - PROCESS | 1963 | |11;stanford-crypto-ccm;150;149;151;154;148;163;148;150;158;144 13:19:34 INFO - PROCESS | 1963 | |12;stanford-crypto-pbkdf2;235;173;175;172;173;175;176;173;173;173 13:19:34 INFO - PROCESS | 1963 | |13;stanford-crypto-sha256-iterative;69;69;68;68;69;73;84;86;84;76 13:19:34 INFO - PROCESS | 1963 | __end_tp_report 13:19:34 INFO - PROCESS | 1963 | __start_cc_report 13:19:34 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,2196 13:19:34 INFO - PROCESS | 1963 | __end_cc_report 13:19:34 INFO - PROCESS | 1963 | __startTimestamp1477513174989__endTimestamp 13:19:34 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | ------- Summary: start ------- 13:19:34 INFO - PROCESS | 1963 | Number of tests: 14 13:19:34 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.00 stddev:1.75 (1.5%) stddev-sans-first:1.67 13:19:34 INFO - PROCESS | 1963 | Values: 111.0 112.0 112.0 113.0 114.0 115.0 113.0 112.0 113.0 117.0 13:19:34 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:129.50 stddev:22.81 (17.6%) stddev-sans-first:17.99 13:19:34 INFO - PROCESS | 1963 | Values: 174.0 115.0 114.0 149.0 156.0 113.0 116.0 113.0 143.0 113.0 13:19:34 INFO - PROCESS | 1963 | 13:19:34 INFO - PROCESS | 1963 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:2.17 (1.2%) stddev-sans-first:2.30 13:19:35 INFO - PROCESS | 1963 | Values: 184.0 185.0 183.0 189.0 186.0 185.0 181.0 183.0 185.0 183.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#3] audio-fft Cycles:10 Average:66.40 Median:61.00 stddev:12.45 (20.4%) stddev-sans-first:12.03 13:19:35 INFO - PROCESS | 1963 | Values: 81.0 60.0 58.0 89.0 62.0 59.0 55.0 58.0 82.0 60.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:10.60 (13.5%) stddev-sans-first:10.89 13:19:35 INFO - PROCESS | 1963 | Values: 85.0 71.0 92.0 70.0 69.0 72.0 92.0 67.0 68.0 89.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:88.00 stddev:14.95 (17.0%) stddev-sans-first:15.70 13:19:35 INFO - PROCESS | 1963 | Values: 89.0 87.0 89.0 86.0 93.0 128.0 117.0 86.0 89.0 85.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#6] imaging-darkroom Cycles:10 Average:103.90 Median:94.50 stddev:18.75 (19.8%) stddev-sans-first:19.61 13:19:35 INFO - PROCESS | 1963 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 94.0 100.0 147.0 130.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#7] imaging-desaturate Cycles:10 Average:87.40 Median:84.00 stddev:12.37 (14.7%) stddev-sans-first:13.06 13:19:35 INFO - PROCESS | 1963 | Values: 84.0 81.0 82.0 80.0 84.0 79.0 86.0 85.0 92.0 121.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.27 (2.3%) stddev-sans-first:1.33 13:19:35 INFO - PROCESS | 1963 | Values: 55.0 54.0 53.0 52.0 55.0 55.0 55.0 56.0 56.0 54.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.50 stddev:3.43 (4.7%) stddev-sans-first:2.60 13:19:35 INFO - PROCESS | 1963 | Values: 65.0 72.0 76.0 77.0 73.0 69.0 70.0 73.0 71.0 72.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:89.50 stddev:4.97 (5.6%) stddev-sans-first:5.20 13:19:35 INFO - PROCESS | 1963 | Values: 88.0 90.0 90.0 86.0 89.0 86.0 87.0 92.0 92.0 103.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#11] stanford-crypto-ccm Cycles:10 Average:151.50 Median:150.50 stddev:5.50 (3.7%) stddev-sans-first:5.81 13:19:35 INFO - PROCESS | 1963 | Values: 150.0 149.0 151.0 154.0 148.0 163.0 148.0 150.0 158.0 144.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:174.00 stddev:19.44 (11.2%) stddev-sans-first:1.32 13:19:35 INFO - PROCESS | 1963 | Values: 235.0 173.0 175.0 172.0 173.0 175.0 176.0 173.0 173.0 173.0 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - PROCESS | 1963 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:74.50 stddev:7.40 (9.9%) stddev-sans-first:7.56 13:19:35 INFO - PROCESS | 1963 | Values: 69.0 69.0 68.0 68.0 69.0 73.0 84.0 86.0 84.0 76.0 13:19:35 INFO - PROCESS | 1963 | -------- Summary: end -------- 13:19:35 INFO - PROCESS | 1963 | 13:19:35 INFO - TEST-INFO | 1963: exit 0 13:19:35 INFO - TEST-OK | kraken | took 59847ms 13:19:35 INFO - SUITE-END | took 1179s 13:19:36 INFO - Completed test suite (00:19:39) 13:19:36 INFO - javascript benchmark 13:19:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3253.5282384054326, 3263.445214313567, 3272.967270327297, 3303.322818822142, 3320.966790332096, 5493.423190564993, 5591.580631452637, 5607.691576963271, 5638.633488823224, 5684.118961560955, 4861.927071093936, 5597.692126933017, 5621.550275977922, 5636.323641163056, 5732.7133643317775, 5177.741112944356, 5202.401723801773, 5205.5054769797, 5215.478452154774, 5226.0, 5107.3105130807235, 5163.741812909339, 5235.267062611238, 5245.291885595453, 5269.6311258211945, 5233.502817232356, 5254.658447200939, 5260.395054568736, 5271.947280527184, 5277.815276465324, 5079.060373830816, 5204.089284375238, 5206.921896171561, 5221.269022336838, 5314.548263397616, 5107.770150343244, 5129.794808207705, 5155.793768249266, 5167.173252279617, 5176.352955880515, 5497.175423686479, 5519.061759500854, 5521.585881058896, 5529.668219906818, 5538.335399752015, 6129.356417576179, 6134.785282515135, 6142.477889379364, 6154.907676384858, 6158.538109641795, 5112.41207261166, 6005.609635373687, 6102.847428814271, 6117.388261173892, 6238.220222472191, 5707.800226992118, 5715.742791574389, 5724.484796368347, 5732.598718089694, 5737.397573254748, 6247.250329960434, 6304.621722696561, 6321.304656487782, 6326.715297811609, 6331.778387756406, 5861.032929566573, 5865.882682346414, 5932.673702946379, 5947.286325640951, 6064.4845188158615, 6145.385461453909, 6196.0, 6221.035739460391, 6227.221597300338, 6237.781677641261, 6120.571559990758, 6128.272918551291, 6141.91175000502, 6151.876962460815, 6156.753729850756, 5950.851228719317, 5957.255343082114, 6023.728932198062, 6143.017117261216, 6143.723532441051, 4816.0, 4834.661573689878, 4878.926816097762, 4931.630127740434, 4933.939203071357, 4375.337371798696, 4386.100849325944, 4391.165678521071, 4399.6920215584605, 4449.243628583149, 5524.66851988882, 5693.914591281134, 5704.543636509153, 5711.6858572778065, 5777.740001699934, 6000.913441490143, 6115.788007244199, 6148.44663980244, 6156.322804491502, 6181.103739957681, 5732.16883551891, 5747.0, 5751.654900705971, 5765.596408251382, 5788.82633520995, 4987.777994391397, 5298.814541491107, 5309.283246761638, 5381.219723140122, 5405.675659460367, 5127.846164615067, 6174.413430724074, 6185.0, 6192.411720886508, 6245.594036387621, 6213.782517611862, 6219.906701399392, 6221.5333849960525, 6233.657148856856, 6253.218347706536, 6269.122322874892, 6323.146375239284, 6324.2410910691015, 6326.177596912372, 6333.398327158912, 5934.673592952429, 6018.21763170785, 6100.542459315658, 6124.203853499195, 6133.969330153321, 6066.332703402534, 6115.209918062534, 6124.350421261, 6192.690365481798, 6201.81394558164, 5841.415858414294, 5999.070144127667, 6012.128241405059, 6012.6392416455155, 6039.214902062704, 5856.267966504187, 5935.762569497172, 5947.524198064058, 5960.821175364746, 5972.402759724167, 5734.655920644775, 5785.39253378406, 5805.187273781758, 5813.709314534341, 5830.970845145747, 6024.8795024100145, 6037.547183961308, 6050.485708714633, 6072.05883088122, 6075.36208698098, 5668.801591944262, 5717.113847353667, 5738.340090889601, 5752.798652047326, 5851.795187168429], "value": 5617.25966845248, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.6217530115365, 518.7587771686165, 520.222267709774, 525.8895631917297, 548.8902219556087, 39356.40965385518, 40419.0, 40653.0, 42196.0, 42227.788861055655, 41572.16855662885, 42042.78978605103, 42089.368659470136, 42140.0, 42234.0, 9516.76208094798, 9701.27240456965, 9704.70885873425, 9706.320557560974, 9726.367786093917, 8357.707480238192, 9603.32776705631, 9711.12599866012, 9716.076972687584, 9726.805463890718, 9686.370385924893, 9714.90285097147, 9718.854217186748, 9737.853932190987, 9757.316987810857, 9689.273304502156, 9723.611055557769, 9728.124468797807, 9740.707778766648, 9771.267154963336, 7726.343260822837, 9539.09378609031, 9575.712728618153, 9629.951850240774, 9631.277654175965, 39560.80219598913, 39876.60123398758, 39910.60089399098, 39984.400233996515, 40004.79997599994, 2865.7850661200287, 3346.498025296201, 3375.2405708715587, 3409.7954122752712, 3431.6322034821283, 30523.0, 30585.541216881764, 30650.69349306523, 30860.691393086006, 31390.529142062886, 3071.324308652093, 3153.2589841387257, 3171.5559821624993, 3187.346593948258, 3251.0571934139307, 362087.0, 363848.0, 364868.17565912264, 365327.34672653466, 365962.0, 57501.42498575044, 57678.7116064417, 57902.13146802801, 57952.71023644939, 57986.0, 33788.66211337905, 34569.48145777815, 34586.0, 35451.29097418089, 60706.0, 12398.318092504998, 14709.485168019282, 14971.251437428304, 14973.625659358604, 14980.925095374454, 11243.437828108561, 11324.37715925632, 11359.034482068952, 11363.488643011084, 11377.772444551063, 18906.338278160198, 18919.905400472908, 18943.715844262348, 18950.0, 18976.905115474612, 7677.961610192025, 7755.689772409153, 7825.238659326935, 7840.275139469308, 7901.60491975399, 877.3419935048713, 956.5456408206046, 1000.3547711725896, 1019.6380284998867, 1062.2776511971779, 29389.706102939126, 29495.26261843471, 30083.548746768814, 30164.0, 30167.547486787716, 999.9250805384096, 1002.6540843409012, 1023.0996722883815, 1117.519466629341, 1165.8717541070475, 14632.268386580628, 15214.923925380303, 15281.083135011713, 15613.765793513106, 15655.843441565667, 601020.0, 636841.0, 636964.0, 637150.0, 638476.0, 56577.86844263092, 57136.71431642815, 57150.85698286094, 57267.42732572704, 57292.0, 1373.7595920713836, 1375.8073869658256, 1395.2605119286795, 1397.9301034948212, 1402.6072699644117, 27932.44135117327, 28875.42249155047, 28997.275068123465, 29296.560551591316, 29391.26521836971, 48388.03223935572, 48877.75561122171, 48881.0, 48882.75558622184, 49032.26451603157, 544179.0, 545870.0, 546033.0, 547660.0, 547671.0, 25816.612750808377, 26069.348266293495, 26109.216723498324, 26141.477170456103, 26207.73792262053, 45819.54180458286, 46056.309155363364, 46390.768046160905, 46995.0, 47389.81525461891, 32659.673403265664, 38133.618663814115, 38364.61635383611, 38428.80785596054, 39221.41167882427, 37713.43429848499, 37757.81121094377, 38737.0, 38957.02607434837, 39012.609873900896, 27625.585616216176, 36635.45046824353, 37175.0, 37530.24939501249, 38157.80921095377, 29895.35575543366, 32107.70483043765, 32858.835705821315, 33435.83282083574, 34066.97799066032, 21679.45801354979, 24939.0, 24955.126570569944, 24979.375515612253, 25542.616860746733], "value": 19231.54481874161, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.53876955952995, 342.86799582160893, 351.94896739972705, 367.9448082787582, 370.1265014565625, 15149.39402423903, 15587.766183507256, 15894.20528973551, 17749.467515974542, 21083.367498975054, 20017.0, 21050.052747626356, 21451.927403629794, 21468.89265553674, 21740.021699023542, 5685.772569097241, 6045.425684559959, 6047.123167140762, 6050.183225264599, 6053.697315134248, 4199.706020578546, 4952.83608352037, 5937.406259374071, 5983.311919129313, 5997.7001149942325, 4426.690131690783, 4430.0, 5957.612755170921, 6575.638339891304, 6585.209774827027, 6323.462505687022, 6482.870342593169, 6550.67246637671, 6619.30497297784, 6636.137302150713, 4915.3855768029, 5679.829605111853, 5680.630759000652, 5682.034054210752, 5699.572532060112, 25141.24576262715, 26129.869350653316, 26173.73826261751, 26266.343341416425, 26439.867800661064, 2258.0, 2263.852849564773, 2290.267114523353, 2499.987500062506, 2779.9297270550983, 16356.427525036686, 16425.917870410573, 16653.91673041627, 17608.295668173258, 17695.823041769676, 1917.2618541861345, 2003.4991252186953, 2006.8294194993443, 2021.9898900505402, 2033.3899830051073, 127154.72845271614, 131423.0, 133129.6687033127, 138225.0, 139587.20825583432, 16461.506154815375, 19072.71390929137, 20046.888043548803, 20554.075066622037, 20966.475838103863, 31270.24757029171, 34123.829380852934, 34917.30165396678, 34939.0, 35247.64752352495, 9571.0, 9704.951475242578, 9889.80220395588, 10025.899741002497, 10060.144887684482, 7929.44493885434, 7935.484193527403, 7945.443818932735, 8072.233137851895, 8115.188481151838, 9532.52337383142, 9547.140757331874, 9632.807343853225, 9748.561314740853, 9779.070988256246, 2937.5764993880134, 2958.45268625305, 3341.8663253470077, 3558.7508874378545, 3694.5751238607368, 807.1484583764068, 831.9209675080857, 911.7857303533664, 937.4890684576922, 941.1388579449726, 16271.467700981486, 19863.60272794536, 20166.495837604176, 21530.46173845635, 21562.89218553897, 732.2531018361348, 855.6534603485599, 915.1443400420628, 919.94940278284, 931.6552875436132, 8981.730548083568, 10981.670549883516, 11360.886391135982, 11411.657650270505, 11460.942695286636, 541648.0, 553658.0, 561947.0, 568729.0, 572011.0, 127728.0, 130093.0, 130097.0, 130730.0, 132829.0, 921.9907800921904, 935.3919952031234, 960.5389413081759, 962.0764066496242, 982.7101005203625, 18435.354762583243, 19116.8088319115, 19121.0, 19148.61702765909, 19212.0, 53107.73446132745, 53642.19536706874, 54035.0, 55107.0, 55146.44853551413, 72426.27573724196, 75082.0, 75896.62051689706, 76918.2308176911, 77029.61485192538, 7101.751438699621, 7826.099998500245, 7850.2542258485355, 8153.858459815512, 8305.667773289, 11770.0, 12020.098492612915, 12117.27296362221, 12325.322107283826, 12743.74512509726, 10731.257254536355, 10977.176711746491, 11034.889651103707, 11038.558457661604, 11072.169587280825, 10676.412797296223, 10850.511726972147, 10862.674119776419, 11067.335959842114, 11234.775304493702, 11088.279261847954, 11214.7757044857, 11252.493637786156, 11407.885921140682, 11544.88455115438, 7961.641726122454, 8111.594420278844, 8132.471389359673, 8162.755117346489, 8412.0, 8222.465539739898, 10621.522031508446, 11048.668539943814, 11263.324200547993, 11279.436028198721], "value": 10388.090948093586, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.634807646165, 1572.6304318485154, 1770.8848924819886, 1884.896330701811, 1924.9037548122599, 8228.0, 8553.871691924625, 8554.401191916568, 8558.443701159427, 8574.442661227016, 7620.352270057038, 7902.881456778153, 7912.525248485095, 8278.379121565891, 8500.829983400328, 2267.30847091637, 2296.552172326397, 2297.6783250344965, 2298.839081264304, 2305.976940230593, 1949.19108569944, 2267.9319620411416, 2300.0799680128, 2303.216906251874, 2305.7578954209816, 2256.047974695544, 2272.5454909018263, 2281.3042022183226, 2304.8847557622134, 2316.93049208524, 2256.3907744908947, 2263.909443622253, 2283.497630521291, 2283.680284760135, 2284.6001949658744, 2246.6068438023444, 2249.6513040478753, 2250.8199344052437, 2254.7068881045525, 2258.864468131901, 8589.914100858974, 8658.783530411727, 8707.259882909897, 8728.30173586118, 8776.64893404263, 5087.745612719349, 5254.133068043807, 5259.316288882459, 5262.342207224097, 5273.525382715536, 3331.3337332533592, 3383.1203886989315, 3521.4365701487895, 3533.1343820764077, 3546.8226588670723, 871.6077765005709, 1028.36755395432, 1033.1631378583372, 1043.1015551695916, 1048.250500891866, 11317.547298108066, 11364.374959377232, 11374.260673056226, 11395.715107122305, 11409.60066397672, 4345.521992580813, 4624.606908412756, 4667.533246675305, 4672.252439609646, 4681.204195286809, 9733.756656083513, 9746.269029822792, 9765.707028789147, 9768.804623907625, 9779.853302200472, 5234.528892399702, 5236.1098613235845, 5241.764120614582, 5255.947440525622, 5256.0, 4071.572484889103, 4265.4881414230495, 4360.8909777255185, 4785.928211076836, 4814.229723244334, 3786.753860999026, 3807.8857634271017, 3895.1430685249766, 3902.31709450845, 3941.7437866538585, 1240.1071228715311, 1252.3738130934532, 1253.3795771093367, 1264.424686767537, 1268.2834198677717, 890.9866352004725, 912.7124955638952, 926.2312280806914, 926.7358802741321, 935.373299889076, 4940.456549779521, 5751.913721294184, 5766.990776614076, 5843.5909486336395, 6089.238845144357, 911.261877878907, 956.784723437235, 963.3208587945481, 967.2987084363921, 1017.3387298256044, 3794.203217324393, 3897.57126716061, 3974.046228905042, 4001.0, 4060.167665628539, 8135.430519863553, 10324.483775811179, 10344.586216551254, 10355.0, 10491.10825579819, 8703.912960870437, 10018.39521724357, 10226.335288206243, 10330.48347582633, 10382.377057376581, 7881.290683838482, 8091.39314551411, 8098.028236611644, 8106.878396824052, 8107.067687215927, 5675.829725108253, 5805.506531944663, 5939.703014849327, 6077.878442431215, 6093.207882975362, 9804.607815687292, 9861.704148875546, 9864.802703946025, 9865.408075515492, 9869.950650246703, 9491.620335186515, 9922.950385248028, 10084.142847858015, 10113.494325283853, 10163.7459063524, 7398.926010739824, 7631.198724133886, 7672.6930922764695, 8036.879446808186, 8205.71280005197, 7162.997180394853, 7571.507851989603, 7635.732749353746, 7876.09424916119, 8229.95885020571, 7242.746503872339, 7344.22885597026, 7376.852462950604, 7395.297446742551, 7582.924170758221, 7163.462740294394, 7204.135503739585, 7243.782686519413, 7309.634518273959, 7319.560826350436, 6804.285550017375, 6807.421369183477, 6892.827679308058, 6900.068490753884, 6915.135608048994, 4907.435644900882, 4948.109340318777, 4963.975180124076, 4996.825111121093, 5061.190209566599, 4885.926711099408, 4907.263910413467, 4967.925481117713, 5046.59627229828, 5047.4952504749235], "value": 4498.669882771973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.46844274976786, 295.158797427332, 306.2084511537675, 310.13627048669446, 312.48752046643494, 4358.476982762069, 5066.429406883869, 5167.560757335622, 5232.912581576868, 5289.497497737713, 4361.208826351138, 4535.251683472223, 4938.580220681238, 5022.673526220793, 5159.3808742950805, 3523.4010218262956, 4055.1143718171043, 4064.2887494688457, 4102.2410853992105, 4108.465899433069, 3084.706952839477, 4051.6758659307334, 4058.533268674096, 4066.329055705805, 4102.671786257092, 3974.463447434588, 3981.86063487778, 3990.9800450997705, 3998.10042740384, 4035.556088830226, 3930.95590293046, 3968.0, 3975.761454312736, 3985.720999530034, 3991.7604943703327, 2914.6939571344915, 3411.385950528904, 3438.5529881115544, 3442.9827850860834, 3447.2933048725204, 4827.0, 5104.387473503166, 5123.948760512384, 5153.716545589991, 5168.0, 3932.842686292545, 4434.534373890728, 4518.853544566337, 4528.75091869947, 4556.840510582147, 4888.2178851383605, 5543.6119471637, 5546.975353995803, 5548.807006493624, 5555.861103472405, 3422.9388889444353, 3574.910627234314, 3627.4921510988484, 3779.7165212609166, 3790.7915064671433, 5740.196372507852, 5756.481916627524, 5760.711964401763, 5767.106098554731, 5805.245318108661, 3476.89569312921, 3635.381985062546, 3755.4930084438797, 3815.542134943769, 3832.3485007548784, 3103.5723567158907, 3438.862445502152, 3489.7557170998293, 3535.964640353615, 3892.4161375793856, 3510.368133735952, 4498.8425405110665, 4517.390152329459, 4525.456945166601, 4611.100835337144, 4393.824247030147, 5445.98498555537, 5537.892421515713, 5542.61201715876, 5548.361938377138, 1234.6110975042832, 1264.9557265495664, 1282.224254326138, 1291.4575878131207, 1303.3027330378202, 870.2472361407464, 892.4377642085575, 892.9687460938836, 899.9301606305471, 907.2469850024464, 4502.4371953505815, 5193.300063442591, 5229.79714665629, 5263.236830659533, 5279.574514880961, 4157.605027522381, 5032.798688052437, 5633.8591535211945, 5656.9817432861655, 5663.281624820027, 4333.414988976511, 5141.634847969975, 5156.0, 5166.664166829144, 5185.0407674579565, 2132.922873948646, 2251.9078247050165, 2352.412121817766, 2450.914218002397, 2482.9751702482745, 3992.3612222044335, 4347.391565060938, 4559.772011399483, 4780.378550788375, 4785.569298763058, 4403.383526306319, 4725.196716558194, 4735.810567577328, 4826.55203438974, 4831.5746854677955, 4150.896227594334, 4631.675782695246, 4644.632641335005, 4786.090642777861, 4819.0, 4042.69679774024, 4135.359019352005, 4662.813487460464, 4694.464988905953, 4701.0, 4111.054457474737, 4278.743275403485, 4452.643788496977, 4512.345709872116, 4673.369095172245, 4138.8344466221015, 4273.529911709647, 4468.9944762428195, 4609.547992382389, 4811.855644330676, 3248.440748440809, 3280.1799550112473, 3289.950650740289, 3320.6015278166774, 3327.733781297539, 4165.500139983222, 4513.652900491791, 4593.185691651845, 5777.24895763548, 5827.446392592697, 5149.0, 5591.021571225101, 5647.802326918538, 5665.603407761417, 5675.687837168995, 4928.0, 5597.3003374578175, 5598.972005139948, 5607.635503692247, 5620.487195502811, 4576.0, 4581.381513495769, 4749.976250118727, 5417.8103766367985, 5456.836294911159, 4498.6934603067375, 4915.829290107789, 5062.443131255485, 5126.282320475211, 5602.386460732723, 4422.71252368595, 5223.164293712987, 5417.753916599283, 5489.4236105209975, 5489.4236105209975], "value": 3816.666901816313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.2582707367749, 764.2035163286472, 795.7612716185143, 813.0812182234074, 824.2869917521344, 15085.396584136635, 15941.760873586876, 15960.600984975381, 15966.28151733172, 15973.840261597396, 14027.509037183701, 14411.207383593897, 14733.26333683317, 14741.705165896676, 14751.0, 858.6393714639835, 870.6517393042802, 885.1635204731531, 886.7162507997443, 888.4713595410752, 701.2706784943653, 720.4632548751188, 890.4568213389826, 895.7178488776048, 897.0894542039803, 753.355880721983, 917.7338571814166, 941.3928016429398, 945.9006804285555, 947.1570302430843, 926.6015613286285, 926.717351207878, 928.4475736936512, 929.6095639831287, 929.6885543342988, 535.7642637239602, 685.7942617214866, 686.1012074182787, 686.7596341280562, 687.0484379134884, 12338.75322493554, 14493.855061449462, 15068.246587670572, 15079.472218472301, 15439.228038598025, 14277.643058923506, 14493.42026318946, 14828.481003164838, 14963.177025263607, 15106.39574417022, 11512.251703639236, 11792.35142067186, 11885.762284754343, 12161.69595760113, 12236.571719989757, 3710.7216958728204, 3714.1643130295465, 3749.193923306475, 3761.9811900940595, 3835.6356146166067, 107606.0, 108119.0, 111394.0, 114466.55334466486, 116337.41831290875, 10816.242862999514, 10817.78364432707, 10823.025927666547, 11019.228653994303, 11249.325040497433, 22069.889650551646, 22084.88957555202, 22108.668369974464, 22113.66829497559, 22123.889380553315, 6818.624975626388, 7656.272654097852, 7701.576413297323, 7825.21847690387, 7856.842863142705, 4359.258925982527, 4383.211022016004, 4437.2234858899565, 4466.932996005063, 4563.452385713736, 3440.6215316315183, 3566.465030245484, 3594.1374070223483, 3595.0, 3605.351036813399, 663.7013343995221, 665.6738198282903, 671.2213831954908, 677.4140368581142, 678.4335080207983, 531.7102179312284, 566.6175331651119, 567.6622409666242, 674.922383925845, 680.9241398590315, 8840.328281196396, 9349.252059835197, 9433.575489103006, 9440.36545929822, 9520.952395237979, 541.3611937913222, 589.9144624029492, 648.5330561995356, 655.7737580534708, 666.1539844397588, 4172.457580514574, 4587.128445595326, 4609.216433206296, 4633.86098417048, 4635.65565985867, 102706.0, 102845.0, 103879.96120038854, 105245.0, 158657.0, 111915.0, 141666.0, 141956.29021854824, 145295.2735236317, 150275.0, 67410.95534267959, 116450.41774791072, 121178.0, 124308.0, 126338.99999999817, 8415.537145456814, 9757.853632195382, 9825.740079781834, 9874.753131171778, 9943.403395795985, 47894.56316310377, 51699.741501293756, 58983.115253272095, 62368.75262494634, 62712.0, 90715.66054243219, 92812.0, 95072.19711211474, 95767.0423295786, 97419.025809741, 12087.395630218278, 12809.42357593892, 12997.740045199233, 13140.868591313965, 13413.122162897398, 11812.704682382666, 14192.929035354757, 14702.485413010494, 14826.703465930837, 14867.628309292355, 13583.252921089435, 13692.0, 13853.792193117313, 14036.78944815849, 14227.573172804832, 11895.82156267674, 13786.724265514835, 13966.441342346192, 14382.712345753236, 14390.640233993816, 13527.86472135266, 13600.523981660594, 13917.58247252584, 14167.574972750834, 14376.993610447169, 11508.712282193012, 11538.019268361948, 11612.88387116118, 11704.297742135499, 11717.648470545897, 10810.783784324112, 11320.716982075513, 11442.713932151762, 11527.135464840338, 11570.247933884271], "value": 7802.6874223845125, "unit": "score"}], "name": "dromaeo_css", "value": 7292.160593533423, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 112.0, 113.0, 114.0, 115.0, 113.0, 112.0, 113.0, 117.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 115.0, 114.0, 149.0, 156.0, 113.0, 116.0, 113.0, 143.0, 113.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 183.0, 189.0, 186.0, 185.0, 181.0, 183.0, 185.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 89.0, 62.0, 59.0, 55.0, 58.0, 82.0, 60.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 92.0, 70.0, 69.0, 72.0, 92.0, 67.0, 68.0, 89.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 93.0, 128.0, 117.0, 86.0, 89.0, 85.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 100.0, 147.0, 130.0], "value": 103.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 80.0, 84.0, 79.0, 86.0, 85.0, 92.0, 121.0], "value": 87.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 52.0, 55.0, 55.0, 55.0, 56.0, 56.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 76.0, 77.0, 73.0, 69.0, 70.0, 73.0, 71.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 90.0, 86.0, 89.0, 86.0, 87.0, 92.0, 92.0, 103.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 151.0, 154.0, 148.0, 163.0, 148.0, 150.0, 158.0, 144.0], "value": 151.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [235.0, 173.0, 175.0, 172.0, 173.0, 175.0, 176.0, 173.0, 173.0, 173.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 68.0, 69.0, 73.0, 84.0, 86.0, 84.0, 76.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1480.7999999999997, "alertThreshold": 2.0}]} 13:19:36 INFO - Return code: 0 13:19:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:19:36 INFO - # TBPL SUCCESS # 13:19:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:19:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:19:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:19:36 INFO - 2016-10-26 13:19:36.284 screenresolution[1967:66087] starting screenresolution argv=screenresolution get 13:19:36 INFO - 2016-10-26 13:19:36.288 screenresolution[1967:66087] Display 0: 1600x1200x32@60 13:19:36 INFO - 2016-10-26 13:19:36.294 screenresolution[1968:66090] starting screenresolution argv=screenresolution list 13:19:36 INFO - Available Modes on Display 0 13:19:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:19:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:19:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:19:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:19:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:19:36 INFO - Intel Iris: 13:19:36 INFO - Chipset Model: Intel Iris 13:19:36 INFO - Type: GPU 13:19:36 INFO - Bus: Built-In 13:19:36 INFO - VRAM (Dynamic, Max): 1536 MB 13:19:36 INFO - Vendor: Intel (0x8086) 13:19:36 INFO - Device ID: 0x0a2e 13:19:36 INFO - Revision ID: 0x0009 13:19:36 INFO - Displays: 13:19:36 INFO - Display: 13:19:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:19:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:19:36 INFO - Display Serial Number: 1600x1200 60 13:19:36 INFO - Main Display: Yes 13:19:36 INFO - Mirror: Off 13:19:36 INFO - Online: Yes 13:19:36 INFO - Rotation: Supported 13:19:36 INFO - Return code: 0 13:19:36 INFO - Running post-action listener: _resource_record_post_action 13:19:36 INFO - [mozharness: 2016-10-26 20:19:36.534183Z] Finished run-tests step (success) 13:19:36 INFO - Running post-run listener: _resource_record_post_run 13:19:36 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 61440; Write bytes: 703222784; Read time: 1; Write time: 2215 13:19:36 INFO - TinderboxPrint: CPU usage
53.7% 13:19:36 INFO - TinderboxPrint: I/O read bytes / time
61,440 / 1 13:19:36 INFO - TinderboxPrint: I/O write bytes / time
703,222,784 / 2,215 13:19:36 INFO - TinderboxPrint: CPU idle
2,251.1 (46.3%) 13:19:36 INFO - TinderboxPrint: CPU system
974.1 (20.1%) 13:19:36 INFO - TinderboxPrint: CPU user
1,632.6 (33.6%) 13:19:36 INFO - TinderboxPrint: Swap in / out
404,291,584 / 0 13:19:36 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 382849536; Write bytes: 376682496; Read time: 28815; Write time: 822 13:19:36 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 61440; Write bytes: 296291328; Read time: 1; Write time: 1324 13:19:37 INFO - Running post-run listener: _upload_blobber_files 13:19:37 INFO - Blob upload gear active. 13:19:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:19:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:19:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:19:37 INFO - (blobuploader) - INFO - Open directory for files ... 13:19:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:19:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:19:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:38 INFO - (blobuploader) - INFO - Done attempting. 13:19:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:19:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:19:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:39 INFO - (blobuploader) - INFO - Done attempting. 13:19:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:19:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:19:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:42 INFO - (blobuploader) - INFO - Done attempting. 13:19:42 INFO - (blobuploader) - INFO - Iteration through files over. 13:19:42 INFO - Return code: 0 13:19:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:19:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:19:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/12be6044abee3e49afe3caf992ae816dbcda2cc6b163f12681fade2cec4be58c45ee65c9829f26f9cb2fc0e168c2d4657c61bc75e41c2cf281b0b093ca9819b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/66b63945c68e2973b96e7f6f68c66f2e877cd4eb94eb3ee20b95244ae25a91433d8adf5aaaec987f617b533862909cc9e5eba15a5ec6932aad466533549e7367", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0a832c0bba2a256baa65e9af50ffc9360e8b45d7fd95b8e20f91d5a7ca850f861cf0c15a680dc976a4527222c4d7d0998b159986fb68747f946f2606ab0d3c19"} 13:19:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:19:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:19:42 INFO - Contents: 13:19:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/12be6044abee3e49afe3caf992ae816dbcda2cc6b163f12681fade2cec4be58c45ee65c9829f26f9cb2fc0e168c2d4657c61bc75e41c2cf281b0b093ca9819b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/66b63945c68e2973b96e7f6f68c66f2e877cd4eb94eb3ee20b95244ae25a91433d8adf5aaaec987f617b533862909cc9e5eba15a5ec6932aad466533549e7367", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0a832c0bba2a256baa65e9af50ffc9360e8b45d7fd95b8e20f91d5a7ca850f861cf0c15a680dc976a4527222c4d7d0998b159986fb68747f946f2606ab0d3c19"} 13:19:42 INFO - Running post-run listener: copy_logs_to_upload_dir 13:19:42 INFO - Copying logs to upload dir... 13:19:42 INFO - mkdir: /builds/slave/test/build/upload/logs 13:19:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.680143 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-26 13:19:42.581628) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 13:19:42.585409) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/12be6044abee3e49afe3caf992ae816dbcda2cc6b163f12681fade2cec4be58c45ee65c9829f26f9cb2fc0e168c2d4657c61bc75e41c2cf281b0b093ca9819b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/66b63945c68e2973b96e7f6f68c66f2e877cd4eb94eb3ee20b95244ae25a91433d8adf5aaaec987f617b533862909cc9e5eba15a5ec6932aad466533549e7367", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0a832c0bba2a256baa65e9af50ffc9360e8b45d7fd95b8e20f91d5a7ca850f861cf0c15a680dc976a4527222c4d7d0998b159986fb68747f946f2606ab0d3c19"} build_url:https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/LkOwCC87SoGTZPc2htV5JA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/12be6044abee3e49afe3caf992ae816dbcda2cc6b163f12681fade2cec4be58c45ee65c9829f26f9cb2fc0e168c2d4657c61bc75e41c2cf281b0b093ca9819b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/66b63945c68e2973b96e7f6f68c66f2e877cd4eb94eb3ee20b95244ae25a91433d8adf5aaaec987f617b533862909cc9e5eba15a5ec6932aad466533549e7367", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/0a832c0bba2a256baa65e9af50ffc9360e8b45d7fd95b8e20f91d5a7ca850f861cf0c15a680dc976a4527222c4d7d0998b159986fb68747f946f2606ab0d3c19"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-26 13:19:42.618326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:19:42.618657) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CITa1C1C74/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.KZVfjYWAqx/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-26 13:19:42.666699) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 13:19:42.667008) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-26 13:19:42.667319) ========= ========= Total master_lag: 0.17 =========