builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0028 starttime: 1489781426.76 results: success (0) buildid: 20170317111607 builduid: 87fbda6bcec24416adf908a7c96f9df2 revision: 23a4b7430dd7e83a2809bf3dc41471f154301eda ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:26.756593) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:26.757258) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:26.757593) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:26.847450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:26.847860) ========= 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.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 --2017-03-17 13:10:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.44M=0.002s 2017-03-17 13:10:27 (7.44 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.557501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:27.429567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:27.429966) ========= 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.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.020782 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:27.480401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 13:10:27.480836) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 23a4b7430dd7e83a2809bf3dc41471f154301eda --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 23a4b7430dd7e83a2809bf3dc41471f154301eda --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 2017-03-17 13:10:27,567 truncating revision to first 12 chars 2017-03-17 13:10:27,567 Setting DEBUG logging. 2017-03-17 13:10:27,567 attempt 1/10 2017-03-17 13:10:27,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/23a4b7430dd7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 13:10:28,606 unpacking tar archive at: mozilla-central-23a4b7430dd7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.301522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-17 13:10:28.805741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:28.806087) ========= script_repo_revision: 23a4b7430dd7e83a2809bf3dc41471f154301eda ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:28.806550) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:28.806824) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 13:10:28.826676) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-17 13:10:28.826985) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:10:29 INFO - MultiFileLogger online at 20170317 13:10:29 in /builds/slave/test 13:10:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:10:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:29 INFO - {'append_to_log': False, 13:10:29 INFO - 'base_work_dir': '/builds/slave/test', 13:10:29 INFO - 'blob_upload_branch': 'Firefox', 13:10:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:29 INFO - 'branch': 'Firefox', 13:10:29 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:29 INFO - 'config_files': ('talos/mac_config.py',), 13:10:29 INFO - 'default_actions': ('clobber', 13:10:29 INFO - 'read-buildbot-config', 13:10:29 INFO - 'download-and-extract', 13:10:29 INFO - 'populate-webroot', 13:10:29 INFO - 'create-virtualenv', 13:10:29 INFO - 'install', 13:10:29 INFO - 'run-tests'), 13:10:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:29 INFO - 'download_minidump_stackwalk': True, 13:10:29 INFO - 'download_symbols': 'ondemand', 13:10:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:29 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:29 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:29 INFO - 'gecko_profile': False, 13:10:29 INFO - 'gecko_profile_interval': 0, 13:10:29 INFO - 'installer_path': 'installer.exe', 13:10:29 INFO - 'log_level': 'info', 13:10:29 INFO - 'log_name': 'talos', 13:10:29 INFO - 'log_to_console': True, 13:10:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:10:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:10:29 INFO - 'opt_config_files': (), 13:10:29 INFO - 'pip_index': False, 13:10:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:29 INFO - 'cmd': ('bash', 13:10:29 INFO - '-c', 13:10:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:29 INFO - 'enabled': True, 13:10:29 INFO - 'halt_on_failure': False, 13:10:29 INFO - 'name': 'check_screen_resolution'},), 13:10:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:29 INFO - 'cmd': ('bash', 13:10:29 INFO - '-c', 13:10:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:29 INFO - 'enabled': True, 13:10:29 INFO - 'halt_on_failure': False, 13:10:29 INFO - 'name': 'check_screen_resolution'},), 13:10:29 INFO - 'run_cmd_checks_enabled': True, 13:10:29 INFO - 'suite': 'dromaeojs-e10s', 13:10:29 INFO - 'system_bits': '32', 13:10:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:29 INFO - 'title': 't-yosemite-r7-0028', 13:10:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:29 INFO - 'use_talos_json': True, 13:10:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:29 INFO - 'work_dir': 'build'} 13:10:29 INFO - [mozharness: 2017-03-17 20:10:29.035610Z] Running clobber step. 13:10:29 INFO - Running pre-action listener: _resource_record_pre_action 13:10:29 INFO - Running main action method: clobber 13:10:29 INFO - rmtree: /builds/slave/test/build 13:10:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:30 INFO - Running post-action listener: _resource_record_post_action 13:10:30 INFO - [mozharness: 2017-03-17 20:10:30.720465Z] Finished clobber step (success) 13:10:30 INFO - [mozharness: 2017-03-17 20:10:30.720620Z] Running read-buildbot-config step. 13:10:30 INFO - Running pre-action listener: _resource_record_pre_action 13:10:30 INFO - Running main action method: read_buildbot_config 13:10:30 INFO - Using buildbot properties: 13:10:30 INFO - { 13:10:30 INFO - "project": "", 13:10:30 INFO - "product": "firefox", 13:10:30 INFO - "script_repo_revision": "production", 13:10:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:10:30 INFO - "repository": "", 13:10:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:10:30 INFO - "buildid": "20170317111607", 13:10:30 INFO - "stage_platform": "macosx64", 13:10:30 INFO - "basedir": "/builds/slave/test", 13:10:30 INFO - "nightly_build": "True", 13:10:30 INFO - "buildnumber": 10, 13:10:30 INFO - "slavename": "t-yosemite-r7-0028", 13:10:30 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:10:30 INFO - "platform": "yosemite_r7", 13:10:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:10:30 INFO - "branch": "mozilla-central", 13:10:30 INFO - "repo_path": "mozilla-central", 13:10:30 INFO - "revision": "23a4b7430dd7e83a2809bf3dc41471f154301eda", 13:10:30 INFO - "pgo_build": "False", 13:10:30 INFO - "builduid": "87fbda6bcec24416adf908a7c96f9df2", 13:10:30 INFO - "slavebuilddir": "test" 13:10:30 INFO - } 13:10:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:10:30 INFO - Running post-action listener: _resource_record_post_action 13:10:30 INFO - [mozharness: 2017-03-17 20:10:30.724045Z] Finished read-buildbot-config step (success) 13:10:30 INFO - [mozharness: 2017-03-17 20:10:30.724160Z] Running download-and-extract step. 13:10:30 INFO - Running pre-action listener: _resource_record_pre_action 13:10:30 INFO - Running main action method: download_and_extract 13:10:30 INFO - mkdir: /builds/slave/test/build/tests 13:10:30 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')]} 13:10:30 INFO - trying https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:10:30 INFO - Downloading https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 13:10:32 INFO - Downloaded 1325 bytes. 13:10:32 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:10:32 INFO - Using the following test package requirements: 13:10:32 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:10:32 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:10:32 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:10:32 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:10:32 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'jsshell-mac.zip'], 13:10:32 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:10:32 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:10:32 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:10:32 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:10:32 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:10:32 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:10:32 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:10:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:10:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:10:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:10:32 INFO - Fetch https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:10:33 INFO - Expected file size: 52473402 13:10:33 INFO - Obtained file size: 52473402 13:10:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:10:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:10:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:10:50 INFO - Fetch https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:10:52 INFO - Expected file size: 52473402 13:10:52 INFO - Obtained file size: 52473402 13:11:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:11:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:11:07 INFO - Fetch https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:11:08 INFO - Expected file size: 14404171 13:11:08 INFO - Obtained file size: 14404171 13:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:09 INFO - trying https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:11:09 INFO - Downloading https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:11:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 13:11:11 INFO - Downloaded 75111896 bytes. 13:11:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:11:11 INFO - mkdir: /builds/slave/test/properties 13:11:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:11 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:11 INFO - Contents: 13:11:11 INFO - build_url:https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:11:11 INFO - Running post-action listener: _resource_record_post_action 13:11:11 INFO - Running post-action listener: set_extra_try_arguments 13:11:11 INFO - [mozharness: 2017-03-17 20:11:11.839165Z] Finished download-and-extract step (success) 13:11:11 INFO - [mozharness: 2017-03-17 20:11:11.839316Z] Running populate-webroot step. 13:11:11 INFO - Running pre-action listener: _resource_record_pre_action 13:11:11 INFO - Running main action method: populate_webroot 13:11:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:11 INFO - '--tppagecycles', 13:11:11 INFO - '7'], 13:11:11 INFO - 'tests': ['tsvgm']}}, 13:11:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['damp', 'tps']}, 13:11:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'tests': ['damp', 'tps']}, 13:11:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['dromaeo_dom']}, 13:11:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['basic_compositor_video']}, 13:11:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:11:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['a11yr', 13:11:11 INFO - 'ts_paint', 13:11:11 INFO - 'tpaint', 13:11:11 INFO - 'sessionrestore', 13:11:11 INFO - 'sessionrestore_no_auto_restore', 13:11:11 INFO - 'tabpaint']}, 13:11:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['tsvgx', 13:11:11 INFO - 'tsvgr_opacity', 13:11:11 INFO - 'tart', 13:11:11 INFO - 'tscrollx', 13:11:11 INFO - 'cart', 13:11:11 INFO - 'tsvg_static']}, 13:11:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:11 INFO - 'tsvgr_opacity', 13:11:11 INFO - 'tart', 13:11:11 INFO - 'tscrollx', 13:11:11 INFO - 'cart', 13:11:11 INFO - 'tsvg_static']}, 13:11:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'talos_options': ['--disable-e10s'], 13:11:11 INFO - 'tests': ['tp5o']}, 13:11:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:11:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'talos_options': ['--disable-e10s', 13:11:11 INFO - '--xperf_path', 13:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:11 INFO - 'tests': ['tp5n']}, 13:11:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:11:11 INFO - 'talos_options': ['--xperf_path', 13:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:11 INFO - 'tests': ['tp5n']}}, 13:11:11 INFO - 'talos.zip': {'path': '', 13:11:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:11 INFO - Running post-action listener: _resource_record_post_action 13:11:11 INFO - [mozharness: 2017-03-17 20:11:11.857663Z] Finished populate-webroot step (success) 13:11:11 INFO - [mozharness: 2017-03-17 20:11:11.857776Z] Running create-virtualenv step. 13:11:11 INFO - Running pre-action listener: _resource_record_pre_action 13:11:11 INFO - Running main action method: create_virtualenv 13:11:11 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:11 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 13:11:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:11 INFO - Using real prefix '/tools/python27' 13:11:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:12 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:15 INFO - Installing pip.................done. 13:11:15 INFO - Return code: 0 13:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:11:15 INFO - Reading from file tmpfile_stdout 13:11:15 INFO - Output received: 13:11:15 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) 13:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:15 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:15 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 13:11:15 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 13:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:15 INFO - 'HOME': '/Users/cltbld', 13:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:15 INFO - 'LOGNAME': 'cltbld', 13:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:15 INFO - 'MOZ_NO_REMOTE': '1', 13:11:15 INFO - 'NO_EM_RESTART': '1', 13:11:15 INFO - 'PAGER': '/bin/cat', 13:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:15 INFO - 'PWD': '/builds/slave/test', 13:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:15 INFO - 'SHELL': '/bin/bash', 13:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:15 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:15 INFO - 'USER': 'cltbld', 13:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:15 INFO - 'XPC_FLAGS': '0x0', 13:11:15 INFO - 'XPC_SERVICE_NAME': '0', 13:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:16 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 13:11:16 INFO - Cleaning up... 13:11:16 INFO - Return code: 0 13:11:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:16 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:16 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 13:11:16 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 13:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:16 INFO - 'HOME': '/Users/cltbld', 13:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:16 INFO - 'LOGNAME': 'cltbld', 13:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:16 INFO - 'MOZ_NO_REMOTE': '1', 13:11:16 INFO - 'NO_EM_RESTART': '1', 13:11:16 INFO - 'PAGER': '/bin/cat', 13:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:16 INFO - 'PWD': '/builds/slave/test', 13:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:16 INFO - 'SHELL': '/bin/bash', 13:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:16 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:16 INFO - 'USER': 'cltbld', 13:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:16 INFO - 'XPC_FLAGS': '0x0', 13:11:16 INFO - 'XPC_SERVICE_NAME': '0', 13:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:16 INFO - Downloading/unpacking psutil>=3.1.1 13:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:16 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 13:11:16 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 13:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:20 INFO - Installing collected packages: psutil 13:11:20 INFO - Running setup.py install for psutil 13:11:20 INFO - building 'psutil._psutil_osx' extension 13:11:20 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 13:11:20 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 13:11:20 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 13:11:20 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 13:11:20 INFO - building 'psutil._psutil_posix' extension 13:11:20 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 13:11:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:11:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:11:20 INFO - ^ 13:11:20 INFO - 1 warning generated. 13:11:20 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 13:11:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:21 INFO - Successfully installed psutil 13:11:21 INFO - Cleaning up... 13:11:21 INFO - Return code: 0 13:11:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:21 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:21 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 13:11:21 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 13:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:21 INFO - 'HOME': '/Users/cltbld', 13:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:21 INFO - 'LOGNAME': 'cltbld', 13:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:21 INFO - 'MOZ_NO_REMOTE': '1', 13:11:21 INFO - 'NO_EM_RESTART': '1', 13:11:21 INFO - 'PAGER': '/bin/cat', 13:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:21 INFO - 'PWD': '/builds/slave/test', 13:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:21 INFO - 'SHELL': '/bin/bash', 13:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:21 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:21 INFO - 'USER': 'cltbld', 13:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:21 INFO - 'XPC_FLAGS': '0x0', 13:11:21 INFO - 'XPC_SERVICE_NAME': '0', 13:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:21 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 13:11:21 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 13:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:11:24 INFO - Installing collected packages: mozsystemmonitor 13:11:24 INFO - Running setup.py install for mozsystemmonitor 13:11:24 INFO - Successfully installed mozsystemmonitor 13:11:24 INFO - Cleaning up... 13:11:24 INFO - Return code: 0 13:11:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:11:24 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')]} 13:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:24 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')]} 13:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:24 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:24 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 13:11:24 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 13:11:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:24 INFO - 'HOME': '/Users/cltbld', 13:11:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:24 INFO - 'LOGNAME': 'cltbld', 13:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:24 INFO - 'MOZ_NO_REMOTE': '1', 13:11:24 INFO - 'NO_EM_RESTART': '1', 13:11:24 INFO - 'PAGER': '/bin/cat', 13:11:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:24 INFO - 'PWD': '/builds/slave/test', 13:11:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:24 INFO - 'SHELL': '/bin/bash', 13:11:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:24 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:24 INFO - 'USER': 'cltbld', 13:11:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:24 INFO - 'XPC_FLAGS': '0x0', 13:11:24 INFO - 'XPC_SERVICE_NAME': '0', 13:11:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:24 INFO - Downloading/unpacking jsonschema==2.5.1 13:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:24 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 13:11:24 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 13:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:11:27 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 13:11:27 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 13:11:27 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 13:11:27 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 13:11:27 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 13:11:27 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 13:11:27 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:27 INFO - warning: no files found matching '*.txt' 13:11:27 INFO - no previously-included directories found matching 'build' 13:11:27 INFO - no previously-included directories found matching 'dist' 13:11:27 INFO - no previously-included directories found matching '.git*' 13:11:27 INFO - Installing collected packages: jsonschema, functools32 13:11:27 INFO - Running setup.py install for functools32 13:11:27 INFO - warning: no files found matching '*.txt' 13:11:27 INFO - no previously-included directories found matching 'build' 13:11:27 INFO - no previously-included directories found matching 'dist' 13:11:27 INFO - no previously-included directories found matching '.git*' 13:11:27 INFO - Successfully installed jsonschema functools32 13:11:27 INFO - Cleaning up... 13:11:28 INFO - Return code: 0 13:11:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:28 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 13:11:28 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 13:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:28 INFO - 'HOME': '/Users/cltbld', 13:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:28 INFO - 'LOGNAME': 'cltbld', 13:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:28 INFO - 'MOZ_NO_REMOTE': '1', 13:11:28 INFO - 'NO_EM_RESTART': '1', 13:11:28 INFO - 'PAGER': '/bin/cat', 13:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:28 INFO - 'PWD': '/builds/slave/test', 13:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:28 INFO - 'SHELL': '/bin/bash', 13:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:28 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:28 INFO - 'USER': 'cltbld', 13:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:28 INFO - 'XPC_FLAGS': '0x0', 13:11:28 INFO - 'XPC_SERVICE_NAME': '0', 13:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:11:28 INFO - Cleaning up... 13:11:28 INFO - Return code: 0 13:11:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:28 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 13:11:28 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 13:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:28 INFO - 'HOME': '/Users/cltbld', 13:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:28 INFO - 'LOGNAME': 'cltbld', 13:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:28 INFO - 'MOZ_NO_REMOTE': '1', 13:11:28 INFO - 'NO_EM_RESTART': '1', 13:11:28 INFO - 'PAGER': '/bin/cat', 13:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:28 INFO - 'PWD': '/builds/slave/test', 13:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:28 INFO - 'SHELL': '/bin/bash', 13:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:28 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:28 INFO - 'USER': 'cltbld', 13:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:28 INFO - 'XPC_FLAGS': '0x0', 13:11:28 INFO - 'XPC_SERVICE_NAME': '0', 13:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:28 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:28 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 13:11:31 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:31 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 13:11:32 INFO - Downloading docopt-0.6.1.tar.gz 13:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:32 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:32 INFO - Running setup.py install for blobuploader 13:11:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:32 INFO - Running setup.py install for requests 13:11:33 INFO - Running setup.py install for docopt 13:11:33 INFO - Successfully installed blobuploader requests docopt 13:11:33 INFO - Cleaning up... 13:11:33 INFO - Return code: 0 13:11:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:33 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:33 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 13:11:33 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 13:11:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:33 INFO - 'HOME': '/Users/cltbld', 13:11:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:33 INFO - 'LOGNAME': 'cltbld', 13:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:33 INFO - 'MOZ_NO_REMOTE': '1', 13:11:33 INFO - 'NO_EM_RESTART': '1', 13:11:33 INFO - 'PAGER': '/bin/cat', 13:11:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:33 INFO - 'PWD': '/builds/slave/test', 13:11:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:33 INFO - 'SHELL': '/bin/bash', 13:11:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:33 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:33 INFO - 'USER': 'cltbld', 13:11:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:33 INFO - 'XPC_FLAGS': '0x0', 13:11:33 INFO - 'XPC_SERVICE_NAME': '0', 13:11:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:33 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Rqhlzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:33 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-liUo24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:33 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-we8qbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:33 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-3f5G_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-mIb__L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-t_Go4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tAHcQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-2jNxFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GE2mqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tcp5n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-dyUYqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Y5dZGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ufILMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:34 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ueaszT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:35 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-MDBHTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:35 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-d5AQAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:35 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-lwMjeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:35 INFO - Running setup.py install for manifestparser 13:11:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:35 INFO - Running setup.py install for mozcrash 13:11:35 INFO - Running setup.py install for mozdebug 13:11:35 INFO - Running setup.py install for mozdevice 13:11:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Running setup.py install for mozfile 13:11:36 INFO - Running setup.py install for mozhttpd 13:11:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Running setup.py install for mozinfo 13:11:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Running setup.py install for mozInstall 13:11:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Running setup.py install for mozleak 13:11:36 INFO - Running setup.py install for mozlog 13:11:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:36 INFO - Running setup.py install for moznetwork 13:11:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Running setup.py install for mozprocess 13:11:37 INFO - Running setup.py install for mozprofile 13:11:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Running setup.py install for mozrunner 13:11:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Running setup.py install for mozscreenshot 13:11:37 INFO - Running setup.py install for moztest 13:11:37 INFO - Running setup.py install for mozversion 13:11:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:37 INFO - Cleaning up... 13:11:37 INFO - Return code: 0 13:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:38 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:38 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 13:11:38 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 13:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:38 INFO - 'HOME': '/Users/cltbld', 13:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:38 INFO - 'LOGNAME': 'cltbld', 13:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:38 INFO - 'MOZ_NO_REMOTE': '1', 13:11:38 INFO - 'NO_EM_RESTART': '1', 13:11:38 INFO - 'PAGER': '/bin/cat', 13:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:38 INFO - 'PWD': '/builds/slave/test', 13:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:38 INFO - 'SHELL': '/bin/bash', 13:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:38 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:38 INFO - 'USER': 'cltbld', 13:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:38 INFO - 'XPC_FLAGS': '0x0', 13:11:38 INFO - 'XPC_SERVICE_NAME': '0', 13:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-FEL1Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:38 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-l5HZfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-MmYtoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:38 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-z1ZFWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-1ENRmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:38 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-KQGcpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:38 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)) 13:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:38 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-FBEmNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-i0xZSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-lBkisF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ccVGvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-i7jEO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-lTIaeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-J9as_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YvbWxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-4_JF7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:39 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GXuy61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 13:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:39 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-cFQUct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:40 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)) 13:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:11:40 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)) 13:11:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:11:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:11:40 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 13:11:40 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 13:11:40 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 13:11:40 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 13:11:40 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 13:11:40 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 13:11:42 INFO - Downloading blessings-1.6.tar.gz 13:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:42 INFO - Installing collected packages: blessings 13:11:42 INFO - Running setup.py install for blessings 13:11:42 INFO - Successfully installed blessings 13:11:42 INFO - Cleaning up... 13:11:43 INFO - Return code: 0 13:11:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:43 INFO - Reading from file tmpfile_stdout 13:11:43 INFO - Current package versions: 13:11:43 INFO - blessings == 1.6 13:11:43 INFO - blobuploader == 1.2.4 13:11:43 INFO - docopt == 0.6.1 13:11:43 INFO - functools32 == 3.2.3-2 13:11:43 INFO - jsonschema == 2.5.1 13:11:43 INFO - manifestparser == 1.1 13:11:43 INFO - mozInstall == 1.12 13:11:43 INFO - mozcrash == 1.0 13:11:43 INFO - mozdebug == 0.1 13:11:43 INFO - mozdevice == 0.49 13:11:43 INFO - mozfile == 1.2 13:11:43 INFO - mozhttpd == 0.7 13:11:43 INFO - mozinfo == 0.9 13:11:43 INFO - mozleak == 0.1 13:11:43 INFO - mozlog == 3.4 13:11:43 INFO - moznetwork == 0.27 13:11:43 INFO - mozprocess == 0.25 13:11:43 INFO - mozprofile == 0.28 13:11:43 INFO - mozrunner == 6.13 13:11:43 INFO - mozscreenshot == 0.1 13:11:43 INFO - mozsystemmonitor == 0.3 13:11:43 INFO - moztest == 0.8 13:11:43 INFO - mozversion == 1.4 13:11:43 INFO - psutil == 3.1.1 13:11:43 INFO - requests == 1.2.3 13:11:43 INFO - wsgiref == 0.1.2 13:11:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:43 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:43 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 13:11:43 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 13:11:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:43 INFO - 'HOME': '/Users/cltbld', 13:11:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:43 INFO - 'LOGNAME': 'cltbld', 13:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:43 INFO - 'MOZ_NO_REMOTE': '1', 13:11:43 INFO - 'NO_EM_RESTART': '1', 13:11:43 INFO - 'PAGER': '/bin/cat', 13:11:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:43 INFO - 'PWD': '/builds/slave/test', 13:11:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:43 INFO - 'SHELL': '/bin/bash', 13:11:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:43 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:43 INFO - 'USER': 'cltbld', 13:11:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:43 INFO - 'XPC_FLAGS': '0x0', 13:11:43 INFO - 'XPC_SERVICE_NAME': '0', 13:11:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 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)) 13:11:43 INFO - Cleaning up... 13:11:43 INFO - Return code: 0 13:11:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:43 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 0x10ebc1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ebc4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc13da8240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, '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.vldyT3zmxL/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.QQWEfmLNJg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 13:11:43 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 13:11:43 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 13:11:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:11:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:43 INFO - 'HOME': '/Users/cltbld', 13:11:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:43 INFO - 'LOGNAME': 'cltbld', 13:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:43 INFO - 'MOZ_NO_REMOTE': '1', 13:11:43 INFO - 'NO_EM_RESTART': '1', 13:11:43 INFO - 'PAGER': '/bin/cat', 13:11:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:43 INFO - 'PWD': '/builds/slave/test', 13:11:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:43 INFO - 'SHELL': '/bin/bash', 13:11:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:11:43 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:11:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:43 INFO - 'USER': 'cltbld', 13:11:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:43 INFO - 'XPC_FLAGS': '0x0', 13:11:43 INFO - 'XPC_SERVICE_NAME': '0', 13:11:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:11:43 INFO - Cleaning up... 13:11:43 INFO - Return code: 0 13:11:43 INFO - Running post-action listener: _resource_record_post_action 13:11:43 INFO - Running post-action listener: _start_resource_monitoring 13:11:43 INFO - Starting resource monitoring. 13:11:43 INFO - [mozharness: 2017-03-17 20:11:43.888284Z] Finished create-virtualenv step (success) 13:11:43 INFO - [mozharness: 2017-03-17 20:11:43.889297Z] Running install step. 13:11:43 INFO - Running pre-action listener: _resource_record_pre_action 13:11:43 INFO - Running main action method: install 13:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:44 INFO - Reading from file tmpfile_stdout 13:11:44 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:11:44 INFO - Reading from file tmpfile_stdout 13:11:44 INFO - Output received: 13:11:44 INFO - Usage: mozinstall [options] installer 13:11:44 INFO - Options: 13:11:44 INFO - -h, --help show this help message and exit 13:11:44 INFO - -d DEST, --destination=DEST 13:11:44 INFO - Directory to install application into. [default: 13:11:44 INFO - "/builds/slave/test"] 13:11:44 INFO - --app=APP Application being installed. [default: firefox] 13:11:44 INFO - mkdir: /builds/slave/test/build/application 13:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:12:04 INFO - Reading from file tmpfile_stdout 13:12:04 INFO - Output received: 13:12:04 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 13:12:04 INFO - Running post-action listener: _resource_record_post_action 13:12:04 INFO - [mozharness: 2017-03-17 20:12:04.720350Z] Finished install step (success) 13:12:04 INFO - [mozharness: 2017-03-17 20:12:04.720507Z] Running run-tests step. 13:12:04 INFO - Running pre-action listener: _resource_record_pre_action 13:12:04 INFO - Running main action method: run_tests 13:12:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:12:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:12:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:12:04 INFO - 2017-03-17 13:12:04.735 screenresolution[1903:22607] starting screenresolution argv=screenresolution get 13:12:04 INFO - 2017-03-17 13:12:04.738 screenresolution[1903:22607] Display 0: 1600x1200x32@60 13:12:04 INFO - 2017-03-17 13:12:04.743 screenresolution[1904:22609] starting screenresolution argv=screenresolution list 13:12:04 INFO - Available Modes on Display 0 13:12:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:12:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:12:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:12:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:12:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:12:04 INFO - Intel Iris: 13:12:04 INFO - Chipset Model: Intel Iris 13:12:04 INFO - Type: GPU 13:12:04 INFO - Bus: Built-In 13:12:04 INFO - VRAM (Dynamic, Max): 1536 MB 13:12:04 INFO - Vendor: Intel (0x8086) 13:12:04 INFO - Device ID: 0x0a2e 13:12:04 INFO - Revision ID: 0x0009 13:12:04 INFO - Displays: 13:12:04 INFO - Display: 13:12:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:12:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:12:04 INFO - Display Serial Number: 1600x1200 60 13:12:04 INFO - Main Display: Yes 13:12:04 INFO - Mirror: Off 13:12:04 INFO - Online: Yes 13:12:04 INFO - Rotation: Supported 13:12:04 INFO - Return code: 0 13:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:12:04 INFO - Python 2.7.3 13:12:04 INFO - Return code: 0 13:12:04 INFO - grabbing minidump binary from tooltool 13:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:12:04 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 0x10e99fd30>, '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 0x10e8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e994be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:12:04 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 13:12:04 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 13:12:04 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 13:12:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:12:05 INFO - Return code: 0 13:12:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:12:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:12:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:12:05 INFO - ENV: RUST_BACKTRACE is now 1 13:12:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:12:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:12:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vldyT3zmxL/Render', 13:12:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:05 INFO - 'HOME': '/Users/cltbld', 13:12:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:05 INFO - 'LOGNAME': 'cltbld', 13:12:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:05 INFO - 'MOZ_NO_REMOTE': '1', 13:12:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:05 INFO - 'NO_EM_RESTART': '1', 13:12:05 INFO - 'PAGER': '/bin/cat', 13:12:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:05 INFO - 'PWD': '/builds/slave/test', 13:12:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:05 INFO - 'RUST_BACKTRACE': '1', 13:12:05 INFO - 'SHELL': '/bin/bash', 13:12:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QQWEfmLNJg/Listeners', 13:12:05 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 13:12:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:05 INFO - 'USER': 'cltbld', 13:12:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:05 INFO - 'XPC_FLAGS': '0x0', 13:12:05 INFO - 'XPC_SERVICE_NAME': '0', 13:12:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:12:05 INFO - mozversion application_buildid: 20170317111607 13:12:05 INFO - mozversion application_changeset: 23a4b7430dd7e83a2809bf3dc41471f154301eda 13:12:05 INFO - mozversion application_display_name: Nightly 13:12:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:12:05 INFO - mozversion application_name: Firefox 13:12:05 INFO - mozversion application_remotingname: firefox 13:12:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:12:05 INFO - mozversion application_vendor: Mozilla 13:12:05 INFO - mozversion application_version: 55.0a1 13:12:05 INFO - mozversion platform_buildid: 20170317111607 13:12:05 INFO - mozversion platform_changeset: 23a4b7430dd7e83a2809bf3dc41471f154301eda 13:12:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:12:05 INFO - mozversion platform_version: 55.0a1 13:12:05 INFO - using testdate: 1489781525 13:12:05 INFO - actual date: 1489781525 13:12:05 INFO - starting webserver on 'localhost:57158' 13:12:05 INFO - SUITE-START | Running 2 tests 13:12:05 INFO - TEST-START | dromaeo_css 13:12:05 INFO - operating with platform_type : mac_ 13:12:05 INFO - Initialising browser for dromaeo_css test... 13:12:05 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpUcUldL/profile http://localhost:57158/getInfo.html) 13:12:08 INFO - PID 1914 | 2017-03-17 13:12:08.486 plugin-container[1917:22782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 13:12:08 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:08 INFO - PID 1914 | 2017-03-17 13:12:08.487 plugin-container[1917:22782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1f, name = 'com.apple.CFPasteboardClient' 13:12:08 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:08 INFO - PID 1914 | 2017-03-17 13:12:08.487 plugin-container[1917:22782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:12:08 INFO - PID 1914 | __metrics Screen width/height:1600/1200 13:12:08 INFO - PID 1914 | colorDepth:24 13:12:08 INFO - PID 1914 | Browser inner width/height: 1024/689 13:12:08 INFO - PID 1914 | __metrics 13:12:08 ERROR - PID 1914 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 13:12:09 INFO - TEST-INFO | 1914: exit 0 13:12:09 INFO - Browser initialized. 13:12:09 INFO - Running cycle 1/1 for dromaeo_css test... 13:12:09 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpUcUldL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:12 INFO - PID 1918 | 2017-03-17 13:12:12.040 plugin-container[1919:22906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 13:12:12 INFO - PID 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:12 INFO - PID 1918 | 2017-03-17 13:12:12.041 plugin-container[1919:22906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 13:12:12 INFO - PID 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:12:12 INFO - PID 1918 | 2017-03-17 13:12:12.041 plugin-container[1919:22906] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:12:21 INFO - PID 1918 | RSS: Main: 226803712 13:12:21 INFO - PID 1918 | 13:15:25 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57158/tests/dromaeo/cssquery-ext.html) 13:15:26 INFO - PID 1918 | RSS: Main: 255356928 13:15:26 INFO - PID 1918 | 13:17:09 INFO - PID 1918 | ************************* 13:17:09 INFO - PID 1918 | A coding exception was thrown and uncaught in a Task. 13:17:09 INFO - PID 1918 | 13:17:09 ERROR - PID 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:17:09 INFO - PID 1918 | Full stack: 13:17:09 INFO - PID 1918 | ************************* 13:18:29 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-ext.html (next: http://localhost:57158/tests/dromaeo/cssquery-jquery.html) 13:18:29 INFO - PID 1918 | RSS: Main: 261677056 13:18:29 INFO - PID 1918 | 13:21:31 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57158/tests/dromaeo/cssquery-mootools.html) 13:21:32 INFO - PID 1918 | RSS: Main: 273117184 13:21:32 INFO - PID 1918 | 13:24:34 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57158/tests/dromaeo/cssquery-prototype.html) 13:24:35 INFO - PID 1918 | RSS: Main: 266862592 13:24:35 INFO - PID 1918 | 13:27:38 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57158/tests/dromaeo/cssquery-yui.html) 13:27:38 INFO - PID 1918 | RSS: Main: 277458944 13:27:38 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-yui.html (next: http://localhost:57158/tests/dromaeo/cssquery-dojo.html) 13:30:41 INFO - PID 1918 | RSS: Main: 271233024 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | __start_tp_report 13:30:41 INFO - PID 1918 | _x_x_mozilla_page_load 13:30:41 INFO - PID 1918 | _x_x_mozilla_page_load_details 13:30:41 INFO - PID 1918 | |i|pagename|runs| 13:30:41 INFO - PID 1918 | |0;dojo.html;2902.767778577714;3410.76124671273;3437.089171369586;3467.150548115712;3473.6352682968286;5681.829545113643;5786.942130578687;5840.883182336356;5856.326522449919;5870;4877;5692.003899317613;5811.6803575803315;5863.912041319383;5890.852728681785;5238.607104467161;5266.789328426858;5294.41761406245;5311.123664595357;5357.223202635615;4723.504032076617;5236.554892834114;5250.921236181461;5358.91961620576;5389.946100539003;4242.618164365191;5212.582993360522;5309.41596424393;5349.8127565535215;5369.275147855068;5210.114280572312;5228.712420816853;5239.21411788231;5305.283786688825;5320.467953204688;4520.666403411002;5073.239014147871;5184.585233181337;5265.921011184797;5307.946920530784;4909.803607855682;5439.93921185369;5834.620749651302;5857.26784151981;5893.941060589382;6150.846228844314;6207;6215.533834962396;6220.1602783624085;6230.719617617218;6240.906386404162;6274.968625156891;6291.716872740738;6293.118963345135;6294.0873573332065;4866.489018653026;5974.820755377345;5985.700714964278;6016.729247183887;6018.15745795589;6192.318844927055;6217.9689101555105;6271.592346497463;6287.717052732638;6386.233651961795;5050.49495050492;6066.907956567772;6108.888502348253;6176.66028368444;6223.502119830403;6227.408396202353;6250.781222657276;6262.874742505124;6266.185395898504;6285.245770507477;6121.663308517986;6208.317085120633;6233.158523599256;6235.314115447297;6236.906446403217;6181.227346581677;6186.907196392058;6205.6586887720705;6235.96882015587;6238.344973777779;5087.516685914832;5092.8472145835685;5102.413222479462;5103.3110530077965;5140.203268493389;4518.43519560055;4531.025829446685;4546.704464209882;4548.6133678637025;4567.018091110427;6000.189974353494;6058.21243238385;6189.907151392733;6222.066689996536;6223.813285601439;5172.8704133999145;6157.953147964857;6195.411435913581;6245.812625621239;6249.500039996789;6150.50795936333;6150.661713605705;6158.938410615926;6163.075538669235;6203.286622038433;5567.55459563242;5595.272614560081;5601.8599535011135;5604.243427137284;5632.549396048306;6196.597221180609;6329.49364050884;6458.483321334376;6471.06169605414;6518.120053792677;6260.186175797117;6273.090401891792;6286.96265116269;6287.088372185916;6293.402126797947;6426.068220108152;6509.218893732783;6528.9020664689115;6539.901901471577;6574.211094668479;6111.014442317695;6199.721012554356;6206.820704066213;6219.564630475823;6229.96885015572;6167.938320616916;6222.751089956351;6234.15838861766;6243.719032643451;6269.68651567429;5099.515546023122;5699.287589051369;6052.848678783065;6199.845003874939;6207.81376558704;6161.938380616316;6179.567430279837;6180.134781130555;6231.22109736283;6244.594101383395;5922.289325280994;5929.555283353852;5986.371430999682;6005.579609427298;6129.233845769279;5046.697198168122;5961.254843144607;6142;6149.0776383542825;6267.749290028348;6040.788572399945;6101.6644084574;6123.5500404639015;6125.47964319526;6140.232470941132 13:30:41 INFO - PID 1918 | |1;ext.html;734.8206129162694;782.1630854985165;804.5293503300569;937.4937533731785;940.1303793990558;37791.43312850309;38803.61196388035;40733.18533629326;40766.388504172464;40835.795821020925;38748.418773718346;39247;39255.41116883242;39303.624373146944;39411.81764547061;7851.5681637509915;8054.919450805475;11086.833697494503;11340.886591134065;11360.091192704605;11246.325220486757;11288.887111128866;11346.659600212008;11387;11415.657530274064;11213.495392707307;11310.886891131107;11321.20751547392;11335.753067162606;11336.829947550794;11246.718832029183;11282.210245282875;11307.717307067307;11410.030147437477;11449.198556101077;11137.440758293846;11204.551817927273;11214.215004949658;11250.3812290324;11370.658880233606;39876.800615997025;40028.599714002776;40050.399244011365;40123;40299.395509067384;3267.9836600816843;3841.615838416164;3866.5940076291868;3874.2638898609175;3880.1269714314335;28046.859765701243;28074.877004919777;28082.859585702146;29010.12969610915;29083.12750617464;3122.125804774667;3124.828134452604;3128.671489493616;3140.277736120682;3247.8213698246586;344694;349616;349909;350785;351089;52399.738001310514;52507.47492525102;52636.73681631567;52641.42075737734;52759.94480110453;44589.55410445854;54777.726111369186;54866.17700734492;55112.72443637756;55197.72401138049;11741.823872641917;12351.69120771988;14668.339924703201;14930.850691493164;14958.102513848986;11954.402279885971;12009.819852702216;12012.279263244232;12037.699057523632;12040.337781422106;11567.652970410902;13594.11638243511;14091.647708807363;14210.715785684228;14259.714805703828;5761.567882408836;6176.845578860564;6185.690715464299;6210.192674952227;6230.252369715663;1024.882138554076;1083.6478144602877;1116.190761697779;1153.7000379901285;1204.4098391787961;26141.08506202274;26413.603795943076;26552.8672356637;26622.467550649268;26653.3336666581;1072.485207100596;1270.3203785974458;1278.1500302299053;1282.6985658370274;1288.9484420623257;13391.129576577687;13704.3833027514;13799.655008624864;13814.654633634038;13826;374298;444401;444664;444688;446621;52711;54531.636709081795;56706.71646641823;56717;56878.14682779762;1388.4376827384926;1426.9357878895476;1431.2557470115482;1437.3460075665755;1445.2918070145763;22335.77664223402;27526.449471010066;27929;27936.860315699105;28301.71698283073;33376.49935250924;40792;40867.59132408638;40986;43838.56161438345;370171;371691;371913;371931;372041.1397942993;32430.513542296412;32869.67130328666;32930.835345823114;33166.33667326592;33541;40968.79515602522;41854;42602.36096458617;42606.78696606621;42804.35793463038;34534;34614.82692586521;34653.306933861684;35589.82205088958;35657.82171089128;34161.65838341684;34409.13977150491;34956;35329.64670353366;35641.46537801883;34347.313053739286;35104.82447587746;35479.467807982386;35596.288074237855;35653.64346356503;31042.68957310398;31258;31280.374392512476;31336.373272533965;31515.52726709147;23433.88283058574;23456.348140855273;23524.52950941006;23565.882170589037;23940.64089038631 13:30:41 INFO - PID 1918 | |2;jquery.html;390.96872250219974;406.8596334264678;418.70272106804185;425.79349015727365;436.24094076307233;20284.898575507137;20359.28742494013;20425.182992680275;20665;21170.364889053315;18015;20930.686039709377;22261.22085726996;22323.888380558114;23364;6668.433183179423;7005.964970175142;7019.5788252705;7019.649017549128;7023.1572211334715;6516.218053833547;6847.383735463807;6904.827379315532;6922.688479018456;6949.54827936185;6022.2773267207995;6726.932730672704;6905.896411553831;6914.757983470579;6915.51591388603;5435.619506634537;5947.345791962881;6796.388325050744;6839.28187540306;6863.691133898987;6526;6595.208574970986;6645.302243264436;6646.534742568022;6647;26056.86971565149;26505.33736656599;26553.46893062147;26769.330766730793;27047.323816904536;2888.7544558712534;2995.1613548206537;3027.8334691591954;3046.5430185472137;3125.46867032605;16099.517014489584;16749.665006699797;16912.661746764996;17531.56171095733;17543;1880.6802843516634;2031.2179810772811;2068.865523740952;2070.7515098188314;2073.512724509739;130047;132426;155735;157600.42399575972;160404;23112.65331020036;23306.417339566648;24587.13945011916;24958;25566.360840978752;42128.78935605364;42128.78935605364;42355.94110147209;42387.152256955305;42452.938676532714;8535.786605334792;10667.413292269;10841.728956776144;10957.397343146044;10996.175286853519;8650.394472386999;8684.348673849487;8703.91296087031;8845.911540884508;8929.285657147413;9180.678676246433;10671.786564268672;10931.344119352865;10952.123830093733;11108.888911110947;4035.757854528738;4551.931721024187;4577.198990176706;4579.22153233951;4605.917609361797;812.9959500017474;890.105444028747;894.8299823033602;900.0504467786548;912.5300470257822;23003.88498057499;23160.18939337149;23655.93548290331;23765.93053312605;23800.16699415547;928.679605536089;933.4865823796885;938.737153596994;953.4136506048869;958.2382006305004;11308.660740177644;12132.5146994121;12186.451609677586;12197.878021219674;12645.988320934302;695214;704510;708769;723448;723686;109199.36200956992;138388;139331.91002134976;148724.25637871958;149779.75330370053;1226.6013545597716;1346.4008516210192;1360.1702961193664;1370.225822410335;1371.4856928651755;19194.904025479784;20251.608163020686;20257.493562661053;20263.79736202619;20579.58840823205;32198.678013220506;57773;62063.37936620576;64133;65398;90956.5452172735;92719;92741.07258927324;93030.60454093058;93369.53315233381;12056.397180140782;12273;12426.5650702225;12600.306983116017;12631.494740210288;11788.823167652321;12400.379981001095;12467.753224677823;13612.387442565307;13934.512292069729;9583.616655333708;9805.55874985613;9860.950695246478;11587.8261826071;11936.28382297065;11541.942290288494;11576.131790115607;11582.07343412508;11699.23954942926;11901.523939042687;11453.427328633701;11708.356040417859;11830.46762895689;11853;11860.525578976743;11256.761756206646;11559.190856639954;11973.521059157885;11988.340641264815;12333.62999110028;10279.280450368402;10728.356298622108;11457.427128643702;11663.125265604944;11893.108016898941 13:30:41 INFO - PID 1918 | |3;mootools.html;1306.3859985806669;1566.8276489586144;1580.1783072802143;1610.9113998730072;1711.1615308498835;9338.999999999984;9555;10504.002119798617;10932.508037138328;10973.286736362132;10392.532336044877;10760.246782725213;10761.730956726085;10768.407737574431;10775.946120269407;2034.745656792901;2080.3030984620173;2082.8437867159946;2085.9374218773387;2092.299079808266;2316.513532158249;2356.6818479505305;2357.351728274721;2357.3616336646037;2360.9173678921243;2360.2683168217823;2371.087131454394;2379.583572874749;2381.714194296687;2385.725641551218;2230.2194232018824;2234.128689810975;2253.256425379621;2254.729432468106;2255.842091053635;2304.8156147508157;2320.6519022146645;2351.130081869702;2352.1532248390567;2358.2807243790635;11110.777784444346;11157.051650609708;11164.051055660279;11211.327320360804;11214;4935.963447675992;5589.664620122806;5751.511121554673;5782.045962416196;5823.737931793079;3115.7818952673324;3319.96680033199;3344.2642618623863;3678.8528458861883;3689.981550092259;1184.2953442701578;1201.897838683713;1205.7769312677094;1215.5137944822054;1240.7766602011634;15347.539573812803;15352.07887526752;15504.302306396126;15512;15551.844481555152;4712.552307530779;4928.926066109011;4986.32684587576;5023.372078490189;5041.193409054607;12788.872111278954;12836.486540538273;12840.807387889188;12869.48522059126;12874.227546347249;5857.912131318034;5941.732622031933;5977.491913187428;5979.431953964367;5992.460678538952;5130.948690513122;5139.922901156486;5239.554637855748;5283.815066472732;5287.101192797234;4040.1957752217713;4111.280525907954;4116.135611521554;4128.979355103205;4136.090060186754;1154.5901205072246;1155.6359746679768;1157.3403160198607;1170.777552265067;1174.107678164584;774.6475353714159;782.6791015683542;806.9475484093632;814.2183503836266;816.7631386897733;6347.841303967345;6432.195975503062;6686.531942764057;6715.932840671629;6730.259671436138;877.4165180155253;916.6929078758557;933.1881263300836;964.6382606522554;995.5669283861398;3592.76647017943;4098.446709694213;4196.118815048874;4348.934765978513;4375.890602734957;9835.409875407498;11956.820647690292;12505.687357816127;13341.933290333487;13346.197298567215;12251.816222756666;13234;13258.27079510617;13278.336083195802;13283.3358332083;9724;9993.401055831033;10029.799404012025;10075.899241007643;10135.797284054424;6681.76613818514;6725.226598941184;6753.3921947025;6930.13373328334;6970.059042029261;10650.73373165677;11523.193376463567;11980.460879260629;12100.455479503267;12110.51557937708;10784.460328378893;11860.34768087729;12026.458809353426;12058.51765929388;12071.939640302093;8764.123587641185;8842.690505832265;9131.817363652557;9132.726018219464;9341.486218258062;10226.744331391776;10268.691939241835;10379.481025948522;10387.117095046704;10506.842397363893;7441.8139546511775;9006.909930900607;9071.727848164566;9075.2286055686;9126.315526335418;8755.956220218859;8808.251298639687;8812.16284452976;8836.160564746338;8849.69026084084;7823.413244006609;7903.68385264583;7937.325327347009;7963.681452742057;8038.83922321562;5676.659400435987;5806.477417032487;5810.244668193108;5849.327327357409;5882.852928676817;5886.6173698709445;6012.6392416455155;6037.366076561898;6186.938130618636;6196.938030619636 13:30:41 INFO - PID 1918 | |4;prototype.html;344.844819831076;383.1264716446503;388.1867487613451;390.6796426929917;393.8444314495774;5559.555235581154;5824.854378640525;5937.554683398746;6036.849078773022;6044.576879618428;4569.405977222965;5525.309336332958;5822.972054531641;5863.970680146604;5890.587658863881;4209.894752631178;4727.149113159629;4768.451628062774;4855;4885.902281954359;3860.305145073886;4891.828785992491;4896.46138924718;4927.926081108786;4941.4811444798315;4249.8950272929105;4900.6569540132205;4913.484084171164;4924.236743304776;4934.506549345073;4655.534446555352;4797.40032495938;4867.9269810952865;4879.756012199394;4895.069936712031;4462.174497717932;4478.2386994211065;4493.797779099916;4504.684672072956;4510.458744950594;5080.364954380702;5778.266520044012;5855.238818953551;5867.647941123546;5933.525317974551;5234.037236036486;5556.555475561945;5743.310802703661;5770.653760774367;5802.680852553108;4837.9032419351415;6000.979833428328;6436.356364363573;6467.579607325556;6498.707558159894;4442.489113751928;4712.198926182557;5104.872378190538;5122.718250496221;5129.690480708712;5260.631755777097;6437.806865794034;6792.728290868359;6810.3189681032;6939.23668396476;4589.8066502709535;4611.262198048296;4707.646926480527;5197.610179236497;5224.03355379256;4464.419625448671;5271.578273738169;5305.442928492453;5347.946520534745;5371.973140134274;5564.721763911788;5689.089745640677;5714.828555143353;5735.254416925773;5736.902072880598;5999.250093738283;6348.682565871688;6358.1734374531015;6401.775937842153;6439.840828650794;1658.8755843311799;1799.2443173867007;1823.5138362235002;1831.230883029131;1831.2949514437037;1185.6798664360574;1327.9535216267384;1349.84476785169;1351.216294549159;1369.3153423288356;5886.529077673776;5915.3788852170765;6185.206290175799;6190.350013248634;6191.575937534392;6642.368974947372;6650.86698266032;6655.500837437212;6668.599884006975;6699.5310328276555;5527.585431092684;5565.220869078333;6143.723532441051;6270.9372906271265;6310.652914089769;2898.4348052130053;3018;3247.675232476781;3482.355764183634;3533.699635531009;5982.820515384546;6405.423511883952;6749.493787965922;6898.448124150156;7025.262347453546;6279.985606908709;6477.384648458389;6846.554973926778;6896.413804826546;6916.619585922823;6452.612843229421;6607.2071351438135;6945.513814032969;7024.648767561602;7055.788326350218;5698.8290351289525;5851.561132915133;6416.6845796612515;6752.662366881538;6755.7973260802255;5446.836594902159;5542.722863856871;6218.440340369389;6633.436157926631;6654.3012983637955;5077.644564880423;5620.185073164286;6297.748090076346;6838.179418469616;6984.930150698428;4070.470838790938;4650.767461626838;4718.811247550059;4929.704217746947;5204.401493828258;6313.652749098844;6486.837829054311;6604.5046621502615;6660.56706314088;6722;6417.711202995783;6419.293877673359;6419.518536109904;6428.774992875227;6436.581622194542;6055.212822333068;6217.316095229611;6322.146510221061;6439.710213040518;6446.226452825691;5449.99175152598;5774.884502310014;6151.907721384272;6326.9683651581445;6340.968295158495;5558.416366281644;6231.9688401557705;6276.995680691068;6447.903281450688;6462.127612772404;5912.881742365043;6223.595466294676;6261.78083767066;6272.843178920563;6308 13:30:41 INFO - PID 1918 | |5;yui.html;629.9196877355338;716.1370548489072;776.0687175389534;821.4824660463909;834.5868794946501;14765.77851332231;15588.986715863475;15596.610084747887;15600.843991560081;15621.750259979202;14741.852581474182;14864.925675371607;14870.776938345933;14881;14892.478763243289;846.2510678049939;931.6785708930425;951.4624237305935;956.550421301987;961.4904100826573;725.8947452619367;949.3829011142743;984.148711364668;994.7314225159203;995.2983146881431;1028.5885645741726;1039.7660526381542;1042.9739256518571;1043.2540733375636;1043.3374806997574;1036.1451802263164;1036.5076588620382;1038.4911393417246;1040.063942451792;1042.0725554256717;663.5388405058486;766.8657984852684;767.7811823630238;768.4121646940096;769.0732667136087;13591.456341746318;16252.268647910872;16632.83367166337;16701.49895503137;16917;14772.778408323884;14827.258637068211;14934.850651493454;15023.399064037425;15549.766753498707;11392.715182120513;11583.594574189947;11621.302721836631;11764.470598822987;11770.764584708344;3683.894831550524;3717.962820371789;3728.291624591319;3842.1163132479687;3854.7879866607327;99029;110205;113593.43203284014;115702;116634;10786.946065269623;11379.772404551863;11487.597934072268;11667.474963626657;11681.591144309908;20356;23524.88237558801;24317.148899788775;24344.634830477557;24384.756152438247;8081.272685458337;8101.959490202511;8209.220124088202;8230.21812927771;8283.37874659403;4021.055052062763;4080.4083407905687;4228.76741779199;4259.297215959394;4275.631797824666;3420.2988387381024;3502.264524449843;3564.108972756811;3573.2496175802853;3598.5681718193982;641.6567136581906;643.3984224749873;653.761377097356;657.763205246111;658.539022684123;633.4362417448475;645;658.9522658972257;668.1481111582771;678.0575000748963;9988.450635215133;10128.189744820396;10184.185265178769;10258.525169310846;10329.328593641389;657.5955787190939;692.536000879412;699.4369532526365;708.5819366573746;718.4324383736894;4924.9507504925205;4947.332110165154;4950.480199578992;4989.625778066659;5035.647504674637;88009;88884.11115888758;89032;89035;89857.55071224603;63555.72888542203;79843;80231.19768802355;81399;82132.58933705294;63548;68372;69195;71397.92903106457;73082.63458682672;7687.461877668509;9385.436873787594;9486.810263794823;9492.430454172772;9693.660721874701;46641;47224.527754723385;47349.28976065293;47547.28679069748;48127.75936120297;56453;57078.429215707314;57235.42764572301;57781.55546111381;57782.7110864443;9178.80675512206;11700.239484433485;11841.82237266459;11920.582779602672;12050.638480845588;11529.365884876412;12364.814527781911;12369.93815030919;12532.749345012866;12993;11740.647780566596;11789.292642441482;11825.467853946766;11861.40692965331;12084.214526055779;11436.828447573127;11498.367589782307;11652.242604230698;11773.646790596296;12371.876281237073;11346.773064538827;11577.305361678325;12147;12204.633860984184;12241.387930603612;9927.255455840696;9994.450305233282;10229.693109206735;10238.641647542301;10904.890951090387;7403.962980185064;8443.324534037385;9219.76950576241;9960.601575936882;9978.201743860329 13:30:41 INFO - PID 1918 | __end_tp_report 13:30:41 INFO - PID 1918 | __start_cc_report 13:30:41 INFO - PID 1918 | _x_x_mozilla_cycle_collect,425 13:30:41 INFO - PID 1918 | __end_cc_report 13:30:41 INFO - PID 1918 | __startTimestamp1489782641669__endTimestamp 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | ------- Summary: start ------- 13:30:41 INFO - PID 1918 | Number of tests: 6 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#0] dojo.html Cycles:165 Average:5776.81 Median:6111.01 stddev:676.99 (11.1%) stddev-sans-first:640.43 13:30:41 INFO - PID 1918 | Values: 2902.8 3410.8 3437.1 3467.2 3473.6 5681.8 5786.9 5840.9 5856.3 5870.0 4877.0 5692.0 5811.7 5863.9 5890.9 5238.6 5266.8 5294.4 5311.1 5357.2 4723.5 5236.6 5250.9 5358.9 5389.9 4242.6 5212.6 5309.4 5349.8 5369.3 5210.1 5228.7 5239.2 5305.3 5320.5 4520.7 5073.2 5184.6 5265.9 5307.9 4909.8 5439.9 5834.6 5857.3 5893.9 6150.8 6207.0 6215.5 6220.2 6230.7 6240.9 6275.0 6291.7 6293.1 6294.1 4866.5 5974.8 5985.7 6016.7 6018.2 6192.3 6218.0 6271.6 6287.7 6386.2 5050.5 6066.9 6108.9 6176.7 6223.5 6227.4 6250.8 6262.9 6266.2 6285.2 6121.7 6208.3 6233.2 6235.3 6236.9 6181.2 6186.9 6205.7 6236.0 6238.3 5087.5 5092.8 5102.4 5103.3 5140.2 4518.4 4531.0 4546.7 4548.6 4567.0 6000.2 6058.2 6189.9 6222.1 6223.8 5172.9 6158.0 6195.4 6245.8 6249.5 6150.5 6150.7 6158.9 6163.1 6203.3 5567.6 5595.3 5601.9 5604.2 5632.5 6196.6 6329.5 6458.5 6471.1 6518.1 6260.2 6273.1 6287.0 6287.1 6293.4 6426.1 6509.2 6528.9 6539.9 6574.2 6111.0 6199.7 6206.8 6219.6 6230.0 6167.9 6222.8 6234.2 6243.7 6269.7 5099.5 5699.3 6052.8 6199.8 6207.8 6161.9 6179.6 6180.1 6231.2 6244.6 5922.3 5929.6 5986.4 6005.6 6129.2 5046.7 5961.3 6142.0 6149.1 6267.7 6040.8 6101.7 6123.6 6125.5 6140.2 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#1] ext.html Cycles:180 Average:53178.60 Median:32900.25 stddev:101857.24 (309.6%) stddev-sans-first:102066.87 13:30:41 INFO - PID 1918 | Values: 734.8 782.2 804.5 937.5 940.1 37791.4 38803.6 40733.2 40766.4 40835.8 38748.4 39247.0 39255.4 39303.6 39411.8 7851.6 8054.9 11086.8 11340.9 11360.1 11246.3 11288.9 11346.7 11387.0 11415.7 11213.5 11310.9 11321.2 11335.8 11336.8 11246.7 11282.2 11307.7 11410.0 11449.2 11137.4 11204.6 11214.2 11250.4 11370.7 39876.8 40028.6 40050.4 40123.0 40299.4 3268.0 3841.6 3866.6 3874.3 3880.1 28046.9 28074.9 28082.9 29010.1 29083.1 3122.1 3124.8 3128.7 3140.3 3247.8 344694.0 349616.0 349909.0 350785.0 351089.0 52399.7 52507.5 52636.7 52641.4 52759.9 44589.6 54777.7 54866.2 55112.7 55197.7 11741.8 12351.7 14668.3 14930.9 14958.1 11954.4 12009.8 12012.3 12037.7 12040.3 11567.7 13594.1 14091.6 14210.7 14259.7 5761.6 6176.8 6185.7 6210.2 6230.3 1024.9 1083.6 1116.2 1153.7 1204.4 26141.1 26413.6 26552.9 26622.5 26653.3 1072.5 1270.3 1278.2 1282.7 1288.9 13391.1 13704.4 13799.7 13814.7 13826.0 374298.0 444401.0 444664.0 444688.0 446621.0 52711.0 54531.6 56706.7 56717.0 56878.1 1388.4 1426.9 1431.3 1437.3 1445.3 22335.8 27526.4 27929.0 27936.9 28301.7 33376.5 40792.0 40867.6 40986.0 43838.6 370171.0 371691.0 371913.0 371931.0 372041.1 32430.5 32869.7 32930.8 33166.3 33541.0 40968.8 41854.0 42602.4 42606.8 42804.4 34534.0 34614.8 34653.3 35589.8 35657.8 34161.7 34409.1 34956.0 35329.6 35641.5 34347.3 35104.8 35479.5 35596.3 35653.6 31042.7 31258.0 31280.4 31336.4 31515.5 23433.9 23456.3 23524.5 23565.9 23940.6 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#2] jquery.html Cycles:180 Average:41961.70 Median:23783.05 stddev:118425.48 (497.9%) stddev-sans-first:118716.56 13:30:41 INFO - PID 1918 | Values: 391.0 406.9 418.7 425.8 436.2 20284.9 20359.3 20425.2 20665.0 21170.4 18015.0 20930.7 22261.2 22323.9 23364.0 6668.4 7006.0 7019.6 7019.6 7023.2 6516.2 6847.4 6904.8 6922.7 6949.5 6022.3 6726.9 6905.9 6914.8 6915.5 5435.6 5947.3 6796.4 6839.3 6863.7 6526.0 6595.2 6645.3 6646.5 6647.0 26056.9 26505.3 26553.5 26769.3 27047.3 2888.8 2995.2 3027.8 3046.5 3125.5 16099.5 16749.7 16912.7 17531.6 17543.0 1880.7 2031.2 2068.9 2070.8 2073.5 130047.0 132426.0 155735.0 157600.4 160404.0 23112.7 23306.4 24587.1 24958.0 25566.4 42128.8 42128.8 42355.9 42387.2 42452.9 8535.8 10667.4 10841.7 10957.4 10996.2 8650.4 8684.3 8703.9 8845.9 8929.3 9180.7 10671.8 10931.3 10952.1 11108.9 4035.8 4551.9 4577.2 4579.2 4605.9 813.0 890.1 894.8 900.1 912.5 23003.9 23160.2 23655.9 23765.9 23800.2 928.7 933.5 938.7 953.4 958.2 11308.7 12132.5 12186.5 12197.9 12646.0 695214.0 704510.0 708769.0 723448.0 723686.0 109199.4 138388.0 139331.9 148724.3 149779.8 1226.6 1346.4 1360.2 1370.2 1371.5 19194.9 20251.6 20257.5 20263.8 20579.6 32198.7 57773.0 62063.4 64133.0 65398.0 90956.5 92719.0 92741.1 93030.6 93369.5 12056.4 12273.0 12426.6 12600.3 12631.5 11788.8 12400.4 12467.8 13612.4 13934.5 9583.6 9805.6 9861.0 11587.8 11936.3 11541.9 11576.1 11582.1 11699.2 11901.5 11453.4 11708.4 11830.5 11853.0 11860.5 11256.8 11559.2 11973.5 11988.3 12333.6 10279.3 10728.4 11457.4 11663.1 11893.1 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#3] mootools.html Cycles:180 Average:6656.69 Median:3217.87 stddev:4138.33 (128.6%) stddev-sans-first:4130.41 13:30:41 INFO - PID 1918 | Values: 1306.4 1566.8 1580.2 1610.9 1711.2 9339.0 9555.0 10504.0 10932.5 10973.3 10392.5 10760.2 10761.7 10768.4 10775.9 2034.7 2080.3 2082.8 2085.9 2092.3 2316.5 2356.7 2357.4 2357.4 2360.9 2360.3 2371.1 2379.6 2381.7 2385.7 2230.2 2234.1 2253.3 2254.7 2255.8 2304.8 2320.7 2351.1 2352.2 2358.3 11110.8 11157.1 11164.1 11211.3 11214.0 4936.0 5589.7 5751.5 5782.0 5823.7 3115.8 3320.0 3344.3 3678.9 3690.0 1184.3 1201.9 1205.8 1215.5 1240.8 15347.5 15352.1 15504.3 15512.0 15551.8 4712.6 4928.9 4986.3 5023.4 5041.2 12788.9 12836.5 12840.8 12869.5 12874.2 5857.9 5941.7 5977.5 5979.4 5992.5 5130.9 5139.9 5239.6 5283.8 5287.1 4040.2 4111.3 4116.1 4129.0 4136.1 1154.6 1155.6 1157.3 1170.8 1174.1 774.6 782.7 806.9 814.2 816.8 6347.8 6432.2 6686.5 6715.9 6730.3 877.4 916.7 933.2 964.6 995.6 3592.8 4098.4 4196.1 4348.9 4375.9 9835.4 11956.8 12505.7 13341.9 13346.2 12251.8 13234.0 13258.3 13278.3 13283.3 9724.0 9993.4 10029.8 10075.9 10135.8 6681.8 6725.2 6753.4 6930.1 6970.1 10650.7 11523.2 11980.5 12100.5 12110.5 10784.5 11860.3 12026.5 12058.5 12071.9 8764.1 8842.7 9131.8 9132.7 9341.5 10226.7 10268.7 10379.5 10387.1 10506.8 7441.8 9006.9 9071.7 9075.2 9126.3 8756.0 8808.3 8812.2 8836.2 8849.7 7823.4 7903.7 7937.3 7963.7 8038.8 5676.7 5806.5 5810.2 5849.3 5882.9 5886.6 6012.6 6037.4 6186.9 6196.9 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#4] prototype.html Cycles:180 Average:5336.87 Median:5790.47 stddev:1529.95 (26.4%) stddev-sans-first:1487.65 13:30:41 INFO - PID 1918 | Values: 344.8 383.1 388.2 390.7 393.8 5559.6 5824.9 5937.6 6036.8 6044.6 4569.4 5525.3 5823.0 5864.0 5890.6 4209.9 4727.1 4768.5 4855.0 4885.9 3860.3 4891.8 4896.5 4927.9 4941.5 4249.9 4900.7 4913.5 4924.2 4934.5 4655.5 4797.4 4867.9 4879.8 4895.1 4462.2 4478.2 4493.8 4504.7 4510.5 5080.4 5778.3 5855.2 5867.6 5933.5 5234.0 5556.6 5743.3 5770.7 5802.7 4837.9 6001.0 6436.4 6467.6 6498.7 4442.5 4712.2 5104.9 5122.7 5129.7 5260.6 6437.8 6792.7 6810.3 6939.2 4589.8 4611.3 4707.6 5197.6 5224.0 4464.4 5271.6 5305.4 5347.9 5372.0 5564.7 5689.1 5714.8 5735.3 5736.9 5999.3 6348.7 6358.2 6401.8 6439.8 1658.9 1799.2 1823.5 1831.2 1831.3 1185.7 1328.0 1349.8 1351.2 1369.3 5886.5 5915.4 6185.2 6190.4 6191.6 6642.4 6650.9 6655.5 6668.6 6699.5 5527.6 5565.2 6143.7 6270.9 6310.7 2898.4 3018.0 3247.7 3482.4 3533.7 5982.8 6405.4 6749.5 6898.4 7025.3 6280.0 6477.4 6846.6 6896.4 6916.6 6452.6 6607.2 6945.5 7024.6 7055.8 5698.8 5851.6 6416.7 6752.7 6755.8 5446.8 5542.7 6218.4 6633.4 6654.3 5077.6 5620.2 6297.7 6838.2 6984.9 4070.5 4650.8 4718.8 4929.7 5204.4 6313.7 6486.8 6604.5 6660.6 6722.0 6417.7 6419.3 6419.5 6428.8 6436.6 6055.2 6217.3 6322.1 6439.7 6446.2 5450.0 5774.9 6151.9 6327.0 6341.0 5558.4 6232.0 6277.0 6447.9 6462.1 5912.9 6223.6 6261.8 6272.8 6308.0 13:30:41 INFO - PID 1918 | 13:30:41 INFO - PID 1918 | [#5] yui.html Cycles:180 Average:19133.01 Median:4050.73 stddev:27176.42 (670.9%) stddev-sans-first:27217.15 13:30:41 INFO - PID 1918 | Values: 629.9 716.1 776.1 821.5 834.6 14765.8 15589.0 15596.6 15600.8 15621.8 14741.9 14864.9 14870.8 14881.0 14892.5 846.3 931.7 951.5 956.6 961.5 725.9 949.4 984.1 994.7 995.3 1028.6 1039.8 1043.0 1043.3 1043.3 1036.1 1036.5 1038.5 1040.1 1042.1 663.5 766.9 767.8 768.4 769.1 13591.5 16252.3 16632.8 16701.5 16917.0 14772.8 14827.3 14934.9 15023.4 15549.8 11392.7 11583.6 11621.3 11764.5 11770.8 3683.9 3718.0 3728.3 3842.1 3854.8 99029.0 110205.0 113593.4 115702.0 116634.0 10786.9 11379.8 11487.6 11667.5 11681.6 20356.0 23524.9 24317.1 24344.6 24384.8 8081.3 8102.0 8209.2 8230.2 8283.4 4021.1 4080.4 4228.8 4259.3 4275.6 3420.3 3502.3 3564.1 3573.2 3598.6 641.7 643.4 653.8 657.8 658.5 633.4 645.0 659.0 668.1 678.1 9988.5 10128.2 10184.2 10258.5 10329.3 657.6 692.5 699.4 708.6 718.4 4925.0 4947.3 4950.5 4989.6 5035.6 88009.0 88884.1 89032.0 89035.0 89857.6 63555.7 79843.0 80231.2 81399.0 82132.6 63548.0 68372.0 69195.0 71397.9 73082.6 7687.5 9385.4 9486.8 9492.4 9693.7 46641.0 47224.5 47349.3 47547.3 48127.8 56453.0 57078.4 57235.4 57781.6 57782.7 9178.8 11700.2 11841.8 11920.6 12050.6 11529.4 12364.8 12369.9 12532.7 12993.0 11740.6 11789.3 11825.5 11861.4 12084.2 11436.8 11498.4 11652.2 11773.6 12371.9 11346.8 11577.3 12147.0 12204.6 12241.4 9927.3 9994.5 10229.7 10238.6 10904.9 7404.0 8443.3 9219.8 9960.6 9978.2 13:30:41 INFO - PID 1918 | -------- Summary: end -------- 13:30:41 INFO - PID 1918 | 13:30:42 INFO - TEST-INFO | 1918: exit 0 13:30:42 INFO - TEST-OK | dromaeo_css | took 1117011ms 13:30:42 INFO - TEST-START | kraken 13:30:42 INFO - operating with platform_type : mac_ 13:30:42 INFO - Initialising browser for kraken test... 13:30:42 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmproD4Yq/profile http://localhost:57158/getInfo.html) 13:30:45 INFO - PID 1923 | 2017-03-17 13:30:45.283 plugin-container[1925:25099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 13:30:45 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:30:45 INFO - PID 1923 | 2017-03-17 13:30:45.284 plugin-container[1925:25099] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 13:30:45 INFO - PID 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:30:45 INFO - PID 1923 | 2017-03-17 13:30:45.284 plugin-container[1925:25099] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:30:45 INFO - PID 1923 | __metrics Screen width/height:1600/1200 13:30:45 INFO - PID 1923 | colorDepth:24 13:30:45 INFO - PID 1923 | Browser inner width/height: 1024/689 13:30:45 INFO - PID 1923 | __metrics 13:30:45 ERROR - PID 1923 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 13:30:46 INFO - TEST-INFO | 1923: exit 0 13:30:46 INFO - Browser initialized. 13:30:46 INFO - Running cycle 1/1 for kraken test... 13:30:46 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmproD4Yq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:30:48 INFO - PID 1926 | 2017-03-17 13:30:48.766 plugin-container[1927:25224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 13:30:48 INFO - PID 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:30:48 INFO - PID 1926 | 2017-03-17 13:30:48.766 plugin-container[1927:25224] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 13:30:48 INFO - PID 1926 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:30:48 INFO - PID 1926 | 2017-03-17 13:30:48.766 plugin-container[1927:25224] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:30:58 INFO - PID 1926 | RSS: Main: 228208640 13:30:58 INFO - PID 1926 | 13:31:01 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-beat-detection) 13:31:02 INFO - PID 1926 | RSS: Main: 241766400 13:31:02 INFO - PID 1926 | 13:31:04 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-dft) 13:31:05 INFO - PID 1926 | RSS: Main: 250052608 13:31:05 INFO - PID 1926 | 13:31:09 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-fft) 13:31:09 INFO - PID 1926 | RSS: Main: 252735488 13:31:09 INFO - PID 1926 | 13:31:11 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-oscillator) 13:31:12 INFO - PID 1926 | RSS: Main: 253943808 13:31:12 INFO - PID 1926 | 13:31:14 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:31:15 INFO - PID 1926 | RSS: Main: 255647744 13:31:15 INFO - PID 1926 | 13:31:18 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-darkroom) 13:31:19 INFO - PID 1926 | RSS: Main: 261513216 13:31:19 INFO - PID 1926 | 13:31:22 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-desaturate) 13:31:22 INFO - PID 1926 | RSS: Main: 262221824 13:31:22 INFO - PID 1926 | 13:31:25 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57158/tests/kraken/driver.html?testName=json-parse-financial) 13:31:26 INFO - PID 1926 | RSS: Main: 261865472 13:31:26 INFO - PID 1926 | 13:31:28 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57158/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:31:28 INFO - PID 1926 | RSS: Main: 261238784 13:31:28 INFO - PID 1926 | 13:31:30 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:31:31 INFO - PID 1926 | RSS: Main: 261246976 13:31:31 INFO - PID 1926 | 13:31:33 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:31:34 INFO - PID 1926 | RSS: Main: 261341184 13:31:34 INFO - PID 1926 | 13:31:37 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:31:38 INFO - PID 1926 | RSS: Main: 261652480 13:31:38 INFO - PID 1926 | 13:31:41 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:31:41 INFO - PID 1926 | RSS: Main: 261644288 13:31:41 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57158/tests/kraken/driver.html?testName=ai-astar) 13:31:43 INFO - PID 1926 | RSS: Main: 263647232 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | __start_tp_report 13:31:43 INFO - PID 1926 | _x_x_mozilla_page_load 13:31:43 INFO - PID 1926 | _x_x_mozilla_page_load_details 13:31:43 INFO - PID 1926 | |i|pagename|runs| 13:31:43 INFO - PID 1926 | |0;ai-astar;126;181;152;119;120;119;119;118;122;120 13:31:43 INFO - PID 1926 | |1;audio-beat-detection;148;114;114;156;113;118;122;153;179;137 13:31:43 INFO - PID 1926 | |2;audio-dft;196;206;234;256;205;200;260;273;269;241 13:31:43 INFO - PID 1926 | |3;audio-fft;86;79;55;82;69;53;49;73;92;50 13:31:43 INFO - PID 1926 | |4;audio-oscillator;93;84;86;91;89;99;119;105;93;83 13:31:43 INFO - PID 1926 | |5;imaging-gaussian-blur;89;89;90;112;83;135;134;82;136;134 13:31:43 INFO - PID 1926 | |6;imaging-darkroom;123;96;96;99;101;97;98;105;153;148 13:31:43 INFO - PID 1926 | |7;imaging-desaturate;91;94;91;91;93;92;87;93;91;92 13:31:43 INFO - PID 1926 | |8;json-parse-financial;66;68;67;68;70;66;69;69;67;66 13:31:43 INFO - PID 1926 | |9;json-stringify-tinderbox;52;88;89;87;58;85;85;82;85;83 13:31:43 INFO - PID 1926 | |10;stanford-crypto-aes;86;84;85;77;84;83;87;85;85;83 13:31:43 INFO - PID 1926 | |11;stanford-crypto-ccm;152;160;141;138;142;165;146;159;221;165 13:31:43 INFO - PID 1926 | |12;stanford-crypto-pbkdf2;186;190;183;234;198;211;166;238;227;188 13:31:43 INFO - PID 1926 | |13;stanford-crypto-sha256-iterative;69;70;71;70;68;69;70;67;69;69 13:31:43 INFO - PID 1926 | __end_tp_report 13:31:43 INFO - PID 1926 | __start_cc_report 13:31:43 INFO - PID 1926 | _x_x_mozilla_cycle_collect,1000 13:31:43 INFO - PID 1926 | __end_cc_report 13:31:43 INFO - PID 1926 | __startTimestamp1489782703812__endTimestamp 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | ------- Summary: start ------- 13:31:43 INFO - PID 1926 | Number of tests: 14 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#0] ai-astar Cycles:10 Average:129.60 Median:121.00 stddev:20.74 (17.1%) stddev-sans-first:21.95 13:31:43 INFO - PID 1926 | Values: 126.0 181.0 152.0 119.0 120.0 119.0 119.0 118.0 122.0 120.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:142.50 stddev:22.84 (16.0%) stddev-sans-first:23.77 13:31:43 INFO - PID 1926 | Values: 148.0 114.0 114.0 156.0 113.0 118.0 122.0 153.0 179.0 137.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#2] audio-dft Cycles:10 Average:234.00 Median:248.50 stddev:30.15 (12.1%) stddev-sans-first:28.67 13:31:43 INFO - PID 1926 | Values: 196.0 206.0 234.0 256.0 205.0 200.0 260.0 273.0 269.0 241.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#3] audio-fft Cycles:10 Average:68.80 Median:76.00 stddev:16.04 (21.1%) stddev-sans-first:15.76 13:31:43 INFO - PID 1926 | Values: 86.0 79.0 55.0 82.0 69.0 53.0 49.0 73.0 92.0 50.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:90.00 stddev:11.01 (12.2%) stddev-sans-first:11.67 13:31:43 INFO - PID 1926 | Values: 93.0 84.0 86.0 91.0 89.0 99.0 119.0 105.0 93.0 83.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:108.40 Median:82.50 stddev:24.10 (29.2%) stddev-sans-first:24.52 13:31:43 INFO - PID 1926 | Values: 89.0 89.0 90.0 112.0 83.0 135.0 134.0 82.0 136.0 134.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:96.00 stddev:22.03 (22.9%) stddev-sans-first:22.98 13:31:43 INFO - PID 1926 | Values: 123.0 96.0 96.0 99.0 101.0 97.0 98.0 105.0 153.0 148.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.90 (2.1%) stddev-sans-first:2.01 13:31:43 INFO - PID 1926 | Values: 91.0 94.0 91.0 91.0 93.0 92.0 87.0 93.0 91.0 92.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.43 (2.1%) stddev-sans-first:1.39 13:31:43 INFO - PID 1926 | Values: 66.0 68.0 67.0 68.0 70.0 66.0 69.0 69.0 67.0 66.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:85.00 stddev:13.11 (15.4%) stddev-sans-first:9.44 13:31:43 INFO - PID 1926 | Values: 52.0 88.0 89.0 87.0 58.0 85.0 85.0 82.0 85.0 83.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:85.00 stddev:2.73 (3.2%) stddev-sans-first:2.78 13:31:43 INFO - PID 1926 | Values: 86.0 84.0 85.0 77.0 84.0 83.0 87.0 85.0 85.0 83.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:158.90 Median:159.50 stddev:24.01 (15.1%) stddev-sans-first:25.34 13:31:43 INFO - PID 1926 | Values: 152.0 160.0 141.0 138.0 142.0 165.0 146.0 159.0 221.0 165.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.10 Median:204.50 stddev:24.26 (11.9%) stddev-sans-first:25.02 13:31:43 INFO - PID 1926 | Values: 186.0 190.0 183.0 234.0 198.0 211.0 166.0 238.0 227.0 188.0 13:31:43 INFO - PID 1926 | 13:31:43 INFO - PID 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.14 (1.6%) stddev-sans-first:1.20 13:31:43 INFO - PID 1926 | Values: 69.0 70.0 71.0 70.0 68.0 69.0 70.0 67.0 69.0 69.0 13:31:43 INFO - PID 1926 | -------- Summary: end -------- 13:31:43 INFO - PID 1926 | 13:31:44 INFO - TEST-INFO | 1926: exit 0 13:31:44 INFO - TEST-OK | kraken | took 62490ms 13:31:44 INFO - SUITE-END | took 1179s 13:31:44 INFO - Completed test suite (00:19:39) 13:31:44 INFO - javascript benchmark 13:31:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7858.725330708264, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2902.767778577714, 3410.76124671273, 3437.089171369586, 3467.150548115712, 3473.6352682968286, 5681.829545113643, 5786.942130578687, 5840.883182336356, 5856.326522449919, 5870.0, 4877.0, 5692.003899317613, 5811.6803575803315, 5863.912041319383, 5890.852728681785, 5238.607104467161, 5266.789328426858, 5294.41761406245, 5311.123664595357, 5357.223202635615, 4723.504032076617, 5236.554892834114, 5250.921236181461, 5358.91961620576, 5389.946100539003, 4242.618164365191, 5212.582993360522, 5309.41596424393, 5349.8127565535215, 5369.275147855068, 5210.114280572312, 5228.712420816853, 5239.21411788231, 5305.283786688825, 5320.467953204688, 4520.666403411002, 5073.239014147871, 5184.585233181337, 5265.921011184797, 5307.946920530784, 4909.803607855682, 5439.93921185369, 5834.620749651302, 5857.26784151981, 5893.941060589382, 6150.846228844314, 6207.0, 6215.533834962396, 6220.1602783624085, 6230.719617617218, 6240.906386404162, 6274.968625156891, 6291.716872740738, 6293.118963345135, 6294.0873573332065, 4866.489018653026, 5974.820755377345, 5985.700714964278, 6016.729247183887, 6018.15745795589, 6192.318844927055, 6217.9689101555105, 6271.592346497463, 6287.717052732638, 6386.233651961795, 5050.49495050492, 6066.907956567772, 6108.888502348253, 6176.66028368444, 6223.502119830403, 6227.408396202353, 6250.781222657276, 6262.874742505124, 6266.185395898504, 6285.245770507477, 6121.663308517986, 6208.317085120633, 6233.158523599256, 6235.314115447297, 6236.906446403217, 6181.227346581677, 6186.907196392058, 6205.6586887720705, 6235.96882015587, 6238.344973777779, 5087.516685914832, 5092.8472145835685, 5102.413222479462, 5103.3110530077965, 5140.203268493389, 4518.43519560055, 4531.025829446685, 4546.704464209882, 4548.6133678637025, 4567.018091110427, 6000.189974353494, 6058.21243238385, 6189.907151392733, 6222.066689996536, 6223.813285601439, 5172.8704133999145, 6157.953147964857, 6195.411435913581, 6245.812625621239, 6249.500039996789, 6150.50795936333, 6150.661713605705, 6158.938410615926, 6163.075538669235, 6203.286622038433, 5567.55459563242, 5595.272614560081, 5601.8599535011135, 5604.243427137284, 5632.549396048306, 6196.597221180609, 6329.49364050884, 6458.483321334376, 6471.06169605414, 6518.120053792677, 6260.186175797117, 6273.090401891792, 6286.96265116269, 6287.088372185916, 6293.402126797947, 6426.068220108152, 6509.218893732783, 6528.9020664689115, 6539.901901471577, 6574.211094668479, 6111.014442317695, 6199.721012554356, 6206.820704066213, 6219.564630475823, 6229.96885015572, 6167.938320616916, 6222.751089956351, 6234.15838861766, 6243.719032643451, 6269.68651567429, 5099.515546023122, 5699.287589051369, 6052.848678783065, 6199.845003874939, 6207.81376558704, 6161.938380616316, 6179.567430279837, 6180.134781130555, 6231.22109736283, 6244.594101383395, 5922.289325280994, 5929.555283353852, 5986.371430999682, 6005.579609427298, 6129.233845769279, 5046.697198168122, 5961.254843144607, 6142.0, 6149.0776383542825, 6267.749290028348, 6040.788572399945, 6101.6644084574, 6123.5500404639015, 6125.47964319526, 6140.232470941132], "value": 5734.742047990364, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.8206129162694, 782.1630854985165, 804.5293503300569, 937.4937533731785, 940.1303793990558, 37791.43312850309, 38803.61196388035, 40733.18533629326, 40766.388504172464, 40835.795821020925, 38748.418773718346, 39247.0, 39255.41116883242, 39303.624373146944, 39411.81764547061, 7851.5681637509915, 8054.919450805475, 11086.833697494503, 11340.886591134065, 11360.091192704605, 11246.325220486757, 11288.887111128866, 11346.659600212008, 11387.0, 11415.657530274064, 11213.495392707307, 11310.886891131107, 11321.20751547392, 11335.753067162606, 11336.829947550794, 11246.718832029183, 11282.210245282875, 11307.717307067307, 11410.030147437477, 11449.198556101077, 11137.440758293846, 11204.551817927273, 11214.215004949658, 11250.3812290324, 11370.658880233606, 39876.800615997025, 40028.599714002776, 40050.399244011365, 40123.0, 40299.395509067384, 3267.9836600816843, 3841.615838416164, 3866.5940076291868, 3874.2638898609175, 3880.1269714314335, 28046.859765701243, 28074.877004919777, 28082.859585702146, 29010.12969610915, 29083.12750617464, 3122.125804774667, 3124.828134452604, 3128.671489493616, 3140.277736120682, 3247.8213698246586, 344694.0, 349616.0, 349909.0, 350785.0, 351089.0, 52399.738001310514, 52507.47492525102, 52636.73681631567, 52641.42075737734, 52759.94480110453, 44589.55410445854, 54777.726111369186, 54866.17700734492, 55112.72443637756, 55197.72401138049, 11741.823872641917, 12351.69120771988, 14668.339924703201, 14930.850691493164, 14958.102513848986, 11954.402279885971, 12009.819852702216, 12012.279263244232, 12037.699057523632, 12040.337781422106, 11567.652970410902, 13594.11638243511, 14091.647708807363, 14210.715785684228, 14259.714805703828, 5761.567882408836, 6176.845578860564, 6185.690715464299, 6210.192674952227, 6230.252369715663, 1024.882138554076, 1083.6478144602877, 1116.190761697779, 1153.7000379901285, 1204.4098391787961, 26141.08506202274, 26413.603795943076, 26552.8672356637, 26622.467550649268, 26653.3336666581, 1072.485207100596, 1270.3203785974458, 1278.1500302299053, 1282.6985658370274, 1288.9484420623257, 13391.129576577687, 13704.3833027514, 13799.655008624864, 13814.654633634038, 13826.0, 374298.0, 444401.0, 444664.0, 444688.0, 446621.0, 52711.0, 54531.636709081795, 56706.71646641823, 56717.0, 56878.14682779762, 1388.4376827384926, 1426.9357878895476, 1431.2557470115482, 1437.3460075665755, 1445.2918070145763, 22335.77664223402, 27526.449471010066, 27929.0, 27936.860315699105, 28301.71698283073, 33376.49935250924, 40792.0, 40867.59132408638, 40986.0, 43838.56161438345, 370171.0, 371691.0, 371913.0, 371931.0, 372041.1397942993, 32430.513542296412, 32869.67130328666, 32930.835345823114, 33166.33667326592, 33541.0, 40968.79515602522, 41854.0, 42602.36096458617, 42606.78696606621, 42804.35793463038, 34534.0, 34614.82692586521, 34653.306933861684, 35589.82205088958, 35657.82171089128, 34161.65838341684, 34409.13977150491, 34956.0, 35329.64670353366, 35641.46537801883, 34347.313053739286, 35104.82447587746, 35479.467807982386, 35596.288074237855, 35653.64346356503, 31042.68957310398, 31258.0, 31280.374392512476, 31336.373272533965, 31515.52726709147, 23433.88283058574, 23456.348140855273, 23524.52950941006, 23565.882170589037, 23940.64089038631], "value": 19030.115670568306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.96872250219974, 406.8596334264678, 418.70272106804185, 425.79349015727365, 436.24094076307233, 20284.898575507137, 20359.28742494013, 20425.182992680275, 20665.0, 21170.364889053315, 18015.0, 20930.686039709377, 22261.22085726996, 22323.888380558114, 23364.0, 6668.433183179423, 7005.964970175142, 7019.5788252705, 7019.649017549128, 7023.1572211334715, 6516.218053833547, 6847.383735463807, 6904.827379315532, 6922.688479018456, 6949.54827936185, 6022.2773267207995, 6726.932730672704, 6905.896411553831, 6914.757983470579, 6915.51591388603, 5435.619506634537, 5947.345791962881, 6796.388325050744, 6839.28187540306, 6863.691133898987, 6526.0, 6595.208574970986, 6645.302243264436, 6646.534742568022, 6647.0, 26056.86971565149, 26505.33736656599, 26553.46893062147, 26769.330766730793, 27047.323816904536, 2888.7544558712534, 2995.1613548206537, 3027.8334691591954, 3046.5430185472137, 3125.46867032605, 16099.517014489584, 16749.665006699797, 16912.661746764996, 17531.56171095733, 17543.0, 1880.6802843516634, 2031.2179810772811, 2068.865523740952, 2070.7515098188314, 2073.512724509739, 130047.0, 132426.0, 155735.0, 157600.42399575972, 160404.0, 23112.65331020036, 23306.417339566648, 24587.13945011916, 24958.0, 25566.360840978752, 42128.78935605364, 42128.78935605364, 42355.94110147209, 42387.152256955305, 42452.938676532714, 8535.786605334792, 10667.413292269, 10841.728956776144, 10957.397343146044, 10996.175286853519, 8650.394472386999, 8684.348673849487, 8703.91296087031, 8845.911540884508, 8929.285657147413, 9180.678676246433, 10671.786564268672, 10931.344119352865, 10952.123830093733, 11108.888911110947, 4035.757854528738, 4551.931721024187, 4577.198990176706, 4579.22153233951, 4605.917609361797, 812.9959500017474, 890.105444028747, 894.8299823033602, 900.0504467786548, 912.5300470257822, 23003.88498057499, 23160.18939337149, 23655.93548290331, 23765.93053312605, 23800.16699415547, 928.679605536089, 933.4865823796885, 938.737153596994, 953.4136506048869, 958.2382006305004, 11308.660740177644, 12132.5146994121, 12186.451609677586, 12197.878021219674, 12645.988320934302, 695214.0, 704510.0, 708769.0, 723448.0, 723686.0, 109199.36200956992, 138388.0, 139331.91002134976, 148724.25637871958, 149779.75330370053, 1226.6013545597716, 1346.4008516210192, 1360.1702961193664, 1370.225822410335, 1371.4856928651755, 19194.904025479784, 20251.608163020686, 20257.493562661053, 20263.79736202619, 20579.58840823205, 32198.678013220506, 57773.0, 62063.37936620576, 64133.0, 65398.0, 90956.5452172735, 92719.0, 92741.07258927324, 93030.60454093058, 93369.53315233381, 12056.397180140782, 12273.0, 12426.5650702225, 12600.306983116017, 12631.494740210288, 11788.823167652321, 12400.379981001095, 12467.753224677823, 13612.387442565307, 13934.512292069729, 9583.616655333708, 9805.55874985613, 9860.950695246478, 11587.8261826071, 11936.28382297065, 11541.942290288494, 11576.131790115607, 11582.07343412508, 11699.23954942926, 11901.523939042687, 11453.427328633701, 11708.356040417859, 11830.46762895689, 11853.0, 11860.525578976743, 11256.761756206646, 11559.190856639954, 11973.521059157885, 11988.340641264815, 12333.62999110028, 10279.280450368402, 10728.356298622108, 11457.427128643702, 11663.125265604944, 11893.108016898941], "value": 11837.64583965732, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.3859985806669, 1566.8276489586144, 1580.1783072802143, 1610.9113998730072, 1711.1615308498835, 9338.999999999984, 9555.0, 10504.002119798617, 10932.508037138328, 10973.286736362132, 10392.532336044877, 10760.246782725213, 10761.730956726085, 10768.407737574431, 10775.946120269407, 2034.745656792901, 2080.3030984620173, 2082.8437867159946, 2085.9374218773387, 2092.299079808266, 2316.513532158249, 2356.6818479505305, 2357.351728274721, 2357.3616336646037, 2360.9173678921243, 2360.2683168217823, 2371.087131454394, 2379.583572874749, 2381.714194296687, 2385.725641551218, 2230.2194232018824, 2234.128689810975, 2253.256425379621, 2254.729432468106, 2255.842091053635, 2304.8156147508157, 2320.6519022146645, 2351.130081869702, 2352.1532248390567, 2358.2807243790635, 11110.777784444346, 11157.051650609708, 11164.051055660279, 11211.327320360804, 11214.0, 4935.963447675992, 5589.664620122806, 5751.511121554673, 5782.045962416196, 5823.737931793079, 3115.7818952673324, 3319.96680033199, 3344.2642618623863, 3678.8528458861883, 3689.981550092259, 1184.2953442701578, 1201.897838683713, 1205.7769312677094, 1215.5137944822054, 1240.7766602011634, 15347.539573812803, 15352.07887526752, 15504.302306396126, 15512.0, 15551.844481555152, 4712.552307530779, 4928.926066109011, 4986.32684587576, 5023.372078490189, 5041.193409054607, 12788.872111278954, 12836.486540538273, 12840.807387889188, 12869.48522059126, 12874.227546347249, 5857.912131318034, 5941.732622031933, 5977.491913187428, 5979.431953964367, 5992.460678538952, 5130.948690513122, 5139.922901156486, 5239.554637855748, 5283.815066472732, 5287.101192797234, 4040.1957752217713, 4111.280525907954, 4116.135611521554, 4128.979355103205, 4136.090060186754, 1154.5901205072246, 1155.6359746679768, 1157.3403160198607, 1170.777552265067, 1174.107678164584, 774.6475353714159, 782.6791015683542, 806.9475484093632, 814.2183503836266, 816.7631386897733, 6347.841303967345, 6432.195975503062, 6686.531942764057, 6715.932840671629, 6730.259671436138, 877.4165180155253, 916.6929078758557, 933.1881263300836, 964.6382606522554, 995.5669283861398, 3592.76647017943, 4098.446709694213, 4196.118815048874, 4348.934765978513, 4375.890602734957, 9835.409875407498, 11956.820647690292, 12505.687357816127, 13341.933290333487, 13346.197298567215, 12251.816222756666, 13234.0, 13258.27079510617, 13278.336083195802, 13283.3358332083, 9724.0, 9993.401055831033, 10029.799404012025, 10075.899241007643, 10135.797284054424, 6681.76613818514, 6725.226598941184, 6753.3921947025, 6930.13373328334, 6970.059042029261, 10650.73373165677, 11523.193376463567, 11980.460879260629, 12100.455479503267, 12110.51557937708, 10784.460328378893, 11860.34768087729, 12026.458809353426, 12058.51765929388, 12071.939640302093, 8764.123587641185, 8842.690505832265, 9131.817363652557, 9132.726018219464, 9341.486218258062, 10226.744331391776, 10268.691939241835, 10379.481025948522, 10387.117095046704, 10506.842397363893, 7441.8139546511775, 9006.909930900607, 9071.727848164566, 9075.2286055686, 9126.315526335418, 8755.956220218859, 8808.251298639687, 8812.16284452976, 8836.160564746338, 8849.69026084084, 7823.413244006609, 7903.68385264583, 7937.325327347009, 7963.681452742057, 8038.83922321562, 5676.659400435987, 5806.477417032487, 5810.244668193108, 5849.327327357409, 5882.852928676817, 5886.6173698709445, 6012.6392416455155, 6037.366076561898, 6186.938130618636, 6196.938030619636], "value": 5068.894570042428, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.844819831076, 383.1264716446503, 388.1867487613451, 390.6796426929917, 393.8444314495774, 5559.555235581154, 5824.854378640525, 5937.554683398746, 6036.849078773022, 6044.576879618428, 4569.405977222965, 5525.309336332958, 5822.972054531641, 5863.970680146604, 5890.587658863881, 4209.894752631178, 4727.149113159629, 4768.451628062774, 4855.0, 4885.902281954359, 3860.305145073886, 4891.828785992491, 4896.46138924718, 4927.926081108786, 4941.4811444798315, 4249.8950272929105, 4900.6569540132205, 4913.484084171164, 4924.236743304776, 4934.506549345073, 4655.534446555352, 4797.40032495938, 4867.9269810952865, 4879.756012199394, 4895.069936712031, 4462.174497717932, 4478.2386994211065, 4493.797779099916, 4504.684672072956, 4510.458744950594, 5080.364954380702, 5778.266520044012, 5855.238818953551, 5867.647941123546, 5933.525317974551, 5234.037236036486, 5556.555475561945, 5743.310802703661, 5770.653760774367, 5802.680852553108, 4837.9032419351415, 6000.979833428328, 6436.356364363573, 6467.579607325556, 6498.707558159894, 4442.489113751928, 4712.198926182557, 5104.872378190538, 5122.718250496221, 5129.690480708712, 5260.631755777097, 6437.806865794034, 6792.728290868359, 6810.3189681032, 6939.23668396476, 4589.8066502709535, 4611.262198048296, 4707.646926480527, 5197.610179236497, 5224.03355379256, 4464.419625448671, 5271.578273738169, 5305.442928492453, 5347.946520534745, 5371.973140134274, 5564.721763911788, 5689.089745640677, 5714.828555143353, 5735.254416925773, 5736.902072880598, 5999.250093738283, 6348.682565871688, 6358.1734374531015, 6401.775937842153, 6439.840828650794, 1658.8755843311799, 1799.2443173867007, 1823.5138362235002, 1831.230883029131, 1831.2949514437037, 1185.6798664360574, 1327.9535216267384, 1349.84476785169, 1351.216294549159, 1369.3153423288356, 5886.529077673776, 5915.3788852170765, 6185.206290175799, 6190.350013248634, 6191.575937534392, 6642.368974947372, 6650.86698266032, 6655.500837437212, 6668.599884006975, 6699.5310328276555, 5527.585431092684, 5565.220869078333, 6143.723532441051, 6270.9372906271265, 6310.652914089769, 2898.4348052130053, 3018.0, 3247.675232476781, 3482.355764183634, 3533.699635531009, 5982.820515384546, 6405.423511883952, 6749.493787965922, 6898.448124150156, 7025.262347453546, 6279.985606908709, 6477.384648458389, 6846.554973926778, 6896.413804826546, 6916.619585922823, 6452.612843229421, 6607.2071351438135, 6945.513814032969, 7024.648767561602, 7055.788326350218, 5698.8290351289525, 5851.561132915133, 6416.6845796612515, 6752.662366881538, 6755.7973260802255, 5446.836594902159, 5542.722863856871, 6218.440340369389, 6633.436157926631, 6654.3012983637955, 5077.644564880423, 5620.185073164286, 6297.748090076346, 6838.179418469616, 6984.930150698428, 4070.470838790938, 4650.767461626838, 4718.811247550059, 4929.704217746947, 5204.401493828258, 6313.652749098844, 6486.837829054311, 6604.5046621502615, 6660.56706314088, 6722.0, 6417.711202995783, 6419.293877673359, 6419.518536109904, 6428.774992875227, 6436.581622194542, 6055.212822333068, 6217.316095229611, 6322.146510221061, 6439.710213040518, 6446.226452825691, 5449.99175152598, 5774.884502310014, 6151.907721384272, 6326.9683651581445, 6340.968295158495, 5558.416366281644, 6231.9688401557705, 6276.995680691068, 6447.903281450688, 6462.127612772404, 5912.881742365043, 6223.595466294676, 6261.78083767066, 6272.843178920563, 6308.0], "value": 4876.4060273876485, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.9196877355338, 716.1370548489072, 776.0687175389534, 821.4824660463909, 834.5868794946501, 14765.77851332231, 15588.986715863475, 15596.610084747887, 15600.843991560081, 15621.750259979202, 14741.852581474182, 14864.925675371607, 14870.776938345933, 14881.0, 14892.478763243289, 846.2510678049939, 931.6785708930425, 951.4624237305935, 956.550421301987, 961.4904100826573, 725.8947452619367, 949.3829011142743, 984.148711364668, 994.7314225159203, 995.2983146881431, 1028.5885645741726, 1039.7660526381542, 1042.9739256518571, 1043.2540733375636, 1043.3374806997574, 1036.1451802263164, 1036.5076588620382, 1038.4911393417246, 1040.063942451792, 1042.0725554256717, 663.5388405058486, 766.8657984852684, 767.7811823630238, 768.4121646940096, 769.0732667136087, 13591.456341746318, 16252.268647910872, 16632.83367166337, 16701.49895503137, 16917.0, 14772.778408323884, 14827.258637068211, 14934.850651493454, 15023.399064037425, 15549.766753498707, 11392.715182120513, 11583.594574189947, 11621.302721836631, 11764.470598822987, 11770.764584708344, 3683.894831550524, 3717.962820371789, 3728.291624591319, 3842.1163132479687, 3854.7879866607327, 99029.0, 110205.0, 113593.43203284014, 115702.0, 116634.0, 10786.946065269623, 11379.772404551863, 11487.597934072268, 11667.474963626657, 11681.591144309908, 20356.0, 23524.88237558801, 24317.148899788775, 24344.634830477557, 24384.756152438247, 8081.272685458337, 8101.959490202511, 8209.220124088202, 8230.21812927771, 8283.37874659403, 4021.055052062763, 4080.4083407905687, 4228.76741779199, 4259.297215959394, 4275.631797824666, 3420.2988387381024, 3502.264524449843, 3564.108972756811, 3573.2496175802853, 3598.5681718193982, 641.6567136581906, 643.3984224749873, 653.761377097356, 657.763205246111, 658.539022684123, 633.4362417448475, 645.0, 658.9522658972257, 668.1481111582771, 678.0575000748963, 9988.450635215133, 10128.189744820396, 10184.185265178769, 10258.525169310846, 10329.328593641389, 657.5955787190939, 692.536000879412, 699.4369532526365, 708.5819366573746, 718.4324383736894, 4924.9507504925205, 4947.332110165154, 4950.480199578992, 4989.625778066659, 5035.647504674637, 88009.0, 88884.11115888758, 89032.0, 89035.0, 89857.55071224603, 63555.72888542203, 79843.0, 80231.19768802355, 81399.0, 82132.58933705294, 63548.0, 68372.0, 69195.0, 71397.92903106457, 73082.63458682672, 7687.461877668509, 9385.436873787594, 9486.810263794823, 9492.430454172772, 9693.660721874701, 46641.0, 47224.527754723385, 47349.28976065293, 47547.28679069748, 48127.75936120297, 56453.0, 57078.429215707314, 57235.42764572301, 57781.55546111381, 57782.7110864443, 9178.80675512206, 11700.239484433485, 11841.82237266459, 11920.582779602672, 12050.638480845588, 11529.365884876412, 12364.814527781911, 12369.93815030919, 12532.749345012866, 12993.0, 11740.647780566596, 11789.292642441482, 11825.467853946766, 11861.40692965331, 12084.214526055779, 11436.828447573127, 11498.367589782307, 11652.242604230698, 11773.646790596296, 12371.876281237073, 11346.773064538827, 11577.305361678325, 12147.0, 12204.633860984184, 12241.387930603612, 9927.255455840696, 9994.450305233282, 10229.693109206735, 10238.641647542301, 10904.890951090387, 7403.962980185064, 8443.324534037385, 9219.76950576241, 9960.601575936882, 9978.201743860329], "value": 7376.35169360981, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 181.0, 152.0, 119.0, 120.0, 119.0, 119.0, 118.0, 122.0, 120.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 114.0, 156.0, 113.0, 118.0, 122.0, 153.0, 179.0, 137.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 206.0, 234.0, 256.0, 205.0, 200.0, 260.0, 273.0, 269.0, 241.0], "value": 234.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 55.0, 82.0, 69.0, 53.0, 49.0, 73.0, 92.0, 50.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 84.0, 86.0, 91.0, 89.0, 99.0, 119.0, 105.0, 93.0, 83.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 112.0, 83.0, 135.0, 134.0, 82.0, 136.0, 134.0], "value": 108.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 96.0, 96.0, 99.0, 101.0, 97.0, 98.0, 105.0, 153.0, 148.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 91.0, 93.0, 92.0, 87.0, 93.0, 91.0, 92.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 68.0, 70.0, 66.0, 69.0, 69.0, 67.0, 66.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 88.0, 89.0, 87.0, 58.0, 85.0, 85.0, 82.0, 85.0, 83.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 77.0, 84.0, 83.0, 87.0, 85.0, 85.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 160.0, 141.0, 138.0, 142.0, 165.0, 146.0, 159.0, 221.0, 165.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 190.0, 183.0, 234.0, 198.0, 211.0, 166.0, 238.0, 227.0, 188.0], "value": 202.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 71.0, 70.0, 68.0, 69.0, 70.0, 67.0, 69.0, 69.0], "value": 69.2, "unit": "score"}]}]} 13:31:45 INFO - Return code: 0 13:31:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:31:45 ERROR - # TBPL SUCCESS # 13:31:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:31:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:31:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:31:45 INFO - 2017-03-17 13:31:45.155 screenresolution[1931:25608] starting screenresolution argv=screenresolution get 13:31:45 INFO - 2017-03-17 13:31:45.157 screenresolution[1931:25608] Display 0: 1600x1200x32@60 13:31:45 INFO - 2017-03-17 13:31:45.164 screenresolution[1932:25611] starting screenresolution argv=screenresolution list 13:31:45 INFO - Available Modes on Display 0 13:31:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:31:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:31:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:31:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:31:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:31:45 INFO - Intel Iris: 13:31:45 INFO - Chipset Model: Intel Iris 13:31:45 INFO - Type: GPU 13:31:45 INFO - Bus: Built-In 13:31:45 INFO - VRAM (Dynamic, Max): 1536 MB 13:31:45 INFO - Vendor: Intel (0x8086) 13:31:45 INFO - Device ID: 0x0a2e 13:31:45 INFO - Revision ID: 0x0009 13:31:45 INFO - Displays: 13:31:45 INFO - Display: 13:31:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:31:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:31:45 INFO - Display Serial Number: 1600x1200 60 13:31:45 INFO - Main Display: Yes 13:31:45 INFO - Mirror: Off 13:31:45 INFO - Online: Yes 13:31:45 INFO - Rotation: Supported 13:31:45 INFO - Return code: 0 13:31:45 INFO - Running post-action listener: _resource_record_post_action 13:31:45 INFO - [mozharness: 2017-03-17 20:31:45.404311Z] Finished run-tests step (success) 13:31:45 INFO - Running post-run listener: _resource_record_post_run 13:31:45 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1659904; Write bytes: 516973056; Read time: 17; Write time: 1808 13:31:45 INFO - TinderboxPrint: CPU usage
54.2% 13:31:45 INFO - TinderboxPrint: I/O read bytes / time
1,659,904 / 17 13:31:45 INFO - TinderboxPrint: I/O write bytes / time
516,973,056 / 1,808 13:31:45 INFO - TinderboxPrint: CPU idle
2,201.6 (45.8%) 13:31:45 INFO - TinderboxPrint: CPU system
965.8 (20.1%) 13:31:45 INFO - TinderboxPrint: CPU user
1,638.3 (34.1%) 13:31:45 INFO - TinderboxPrint: Swap in / out
211,763,200 / 0 13:31:45 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201447936; Write bytes: 203982848; Read time: 15741; Write time: 541 13:31:45 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1655808; Write bytes: 303049216; Read time: 16; Write time: 1234 13:31:45 INFO - Running post-run listener: _upload_blobber_files 13:31:45 INFO - Blob upload gear active. 13:31:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:31:45 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:31:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:31:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:31:46 INFO - (blobuploader) - INFO - Open directory for files ... 13:31:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:31:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:31:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:47 INFO - (blobuploader) - INFO - Done attempting. 13:31:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:31:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:31:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:48 INFO - (blobuploader) - INFO - Done attempting. 13:31:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:31:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:31:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:49 INFO - (blobuploader) - INFO - Done attempting. 13:31:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:31:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:31:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:51 INFO - (blobuploader) - INFO - Done attempting. 13:31:51 INFO - (blobuploader) - INFO - Iteration through files over. 13:31:51 INFO - Return code: 0 13:31:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:31:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:31:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1117927f3ac622fd905cef27881fc86366d646b79c31817c8eb4ee8cf3bbd85742cb78563ad9dcce50c13c79dae04d48e144f451e471eea5433365e3b55f1b12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89866072e3ae78c51665e4be182824b9eb37b2f54bbc1f1cbdc5c2f61f12fb61b963eaae0e57822e6b1ed9c223a8f78a956db5422c72978a7c4d8c5f0ec440b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68ad368f651195db24d3bcf635defa41699395545a18251a09e23cd5ef35027f533d4d3ccfb1dacd36dba9e0a847f2c0b14023c208c1189e978c3d542245c17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf067f199161c010c4faf801c09d46bc6b3f6486cd907adfac08d67fd2c639a2e1cfe632c8f7e863d60dcbe031f40fab893b3f09f81a16d31473580e35f05391"} 13:31:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:31:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:31:51 INFO - Contents: 13:31:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1117927f3ac622fd905cef27881fc86366d646b79c31817c8eb4ee8cf3bbd85742cb78563ad9dcce50c13c79dae04d48e144f451e471eea5433365e3b55f1b12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89866072e3ae78c51665e4be182824b9eb37b2f54bbc1f1cbdc5c2f61f12fb61b963eaae0e57822e6b1ed9c223a8f78a956db5422c72978a7c4d8c5f0ec440b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68ad368f651195db24d3bcf635defa41699395545a18251a09e23cd5ef35027f533d4d3ccfb1dacd36dba9e0a847f2c0b14023c208c1189e978c3d542245c17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf067f199161c010c4faf801c09d46bc6b3f6486cd907adfac08d67fd2c639a2e1cfe632c8f7e863d60dcbe031f40fab893b3f09f81a16d31473580e35f05391"} 13:31:51 INFO - Running post-run listener: copy_logs_to_upload_dir 13:31:51 INFO - Copying logs to upload dir... 13:31:51 INFO - mkdir: /builds/slave/test/build/upload/logs 13:31:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.474890 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-17 13:31:51.341124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 13:31:51.344402) ========= 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.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1117927f3ac622fd905cef27881fc86366d646b79c31817c8eb4ee8cf3bbd85742cb78563ad9dcce50c13c79dae04d48e144f451e471eea5433365e3b55f1b12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89866072e3ae78c51665e4be182824b9eb37b2f54bbc1f1cbdc5c2f61f12fb61b963eaae0e57822e6b1ed9c223a8f78a956db5422c72978a7c4d8c5f0ec440b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68ad368f651195db24d3bcf635defa41699395545a18251a09e23cd5ef35027f533d4d3ccfb1dacd36dba9e0a847f2c0b14023c208c1189e978c3d542245c17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf067f199161c010c4faf801c09d46bc6b3f6486cd907adfac08d67fd2c639a2e1cfe632c8f7e863d60dcbe031f40fab893b3f09f81a16d31473580e35f05391"} build_url:https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011130 build_url: 'https://queue.taskcluster.net/v1/task/JgvHncikTaahOOj68IwKVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1117927f3ac622fd905cef27881fc86366d646b79c31817c8eb4ee8cf3bbd85742cb78563ad9dcce50c13c79dae04d48e144f451e471eea5433365e3b55f1b12", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89866072e3ae78c51665e4be182824b9eb37b2f54bbc1f1cbdc5c2f61f12fb61b963eaae0e57822e6b1ed9c223a8f78a956db5422c72978a7c4d8c5f0ec440b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68ad368f651195db24d3bcf635defa41699395545a18251a09e23cd5ef35027f533d4d3ccfb1dacd36dba9e0a847f2c0b14023c208c1189e978c3d542245c17f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cf067f199161c010c4faf801c09d46bc6b3f6486cd907adfac08d67fd2c639a2e1cfe632c8f7e863d60dcbe031f40fab893b3f09f81a16d31473580e35f05391"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 13:31:51.379114) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:31:51.379441) ========= 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.vldyT3zmxL/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.QQWEfmLNJg/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005191 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 13:31:51.428041) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 13:31:51.428354) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 13:31:51.428674) ========= ========= Total master_lag: 0.18 =========