builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0298 starttime: 1486030644.53 results: success (0) buildid: 20170202004013 builduid: e660ace11c1a4a7b9a4afd1e3a1e1441 revision: d06f2bf30b22a04b03e2046d5c148adedd6777c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.534058) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.534510) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.534845) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.562277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.562663) ========= 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.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-02-02 02:17:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2017-02-02 02:17:24 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177977 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.767631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.767946) ========= 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.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.026150 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:24.816163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 02:17:24.816501) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d06f2bf30b22a04b03e2046d5c148adedd6777c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d06f2bf30b22a04b03e2046d5c148adedd6777c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-02-02 02:17:25,032 truncating revision to first 12 chars 2017-02-02 02:17:25,032 Setting DEBUG logging. 2017-02-02 02:17:25,032 attempt 1/10 2017-02-02 02:17:25,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d06f2bf30b22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 02:17:26,435 unpacking tar archive at: mozilla-aurora-d06f2bf30b22/testing/mozharness/ program finished with exit code 0 elapsedTime=1.680089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 02:17:26.519876) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:26.523224) ========= script_repo_revision: d06f2bf30b22a04b03e2046d5c148adedd6777c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:26.523613) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:26.523877) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 02:17:26.543220) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-02 02:17:26.543585) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:17:26 INFO - MultiFileLogger online at 20170202 02:17:26 in /builds/slave/test 02:17:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:17:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:17:26 INFO - {'append_to_log': False, 02:17:26 INFO - 'base_work_dir': '/builds/slave/test', 02:17:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:17:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:17:26 INFO - 'branch': 'Mozilla-Aurora', 02:17:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:17:26 INFO - 'config_files': ('talos/mac_config.py',), 02:17:26 INFO - 'default_actions': ('clobber', 02:17:26 INFO - 'read-buildbot-config', 02:17:26 INFO - 'download-and-extract', 02:17:26 INFO - 'populate-webroot', 02:17:26 INFO - 'create-virtualenv', 02:17:26 INFO - 'install', 02:17:26 INFO - 'run-tests'), 02:17:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:17:26 INFO - 'download_minidump_stackwalk': True, 02:17:26 INFO - 'download_symbols': 'ondemand', 02:17:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:17:26 INFO - 'tooltool.py': '/tools/tooltool.py', 02:17:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:17:26 INFO - '/tools/misc-python/virtualenv.py')}, 02:17:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:17:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:17:26 INFO - 'installer_path': 'installer.exe', 02:17:26 INFO - 'log_level': 'info', 02:17:26 INFO - 'log_name': 'talos', 02:17:26 INFO - 'log_to_console': True, 02:17:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:17:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:17:26 INFO - 'opt_config_files': (), 02:17:26 INFO - 'pip_index': False, 02:17:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:26 INFO - 'cmd': ('bash', 02:17:26 INFO - '-c', 02:17:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:26 INFO - 'enabled': True, 02:17:26 INFO - 'halt_on_failure': False, 02:17:26 INFO - 'name': 'check_screen_resolution'},), 02:17:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:17:26 INFO - 'cmd': ('bash', 02:17:26 INFO - '-c', 02:17:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:17:26 INFO - 'enabled': True, 02:17:26 INFO - 'halt_on_failure': False, 02:17:26 INFO - 'name': 'check_screen_resolution'},), 02:17:26 INFO - 'run_cmd_checks_enabled': True, 02:17:26 INFO - 'sps_profile': False, 02:17:26 INFO - 'sps_profile_interval': 0, 02:17:26 INFO - 'suite': 'dromaeojs-e10s', 02:17:26 INFO - 'system_bits': '32', 02:17:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:17:26 INFO - 'title': 't-yosemite-r7-0298', 02:17:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:17:26 INFO - 'use_talos_json': True, 02:17:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:17:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:17:26 INFO - 'work_dir': 'build'} 02:17:26 INFO - [mozharness: 2017-02-02 10:17:26.869989Z] Running clobber step. 02:17:26 INFO - Running pre-action listener: _resource_record_pre_action 02:17:26 INFO - Running main action method: clobber 02:17:26 INFO - rmtree: /builds/slave/test/build 02:17:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:17:29 INFO - Running post-action listener: _resource_record_post_action 02:17:29 INFO - [mozharness: 2017-02-02 10:17:29.418863Z] Finished clobber step (success) 02:17:29 INFO - [mozharness: 2017-02-02 10:17:29.419025Z] Running read-buildbot-config step. 02:17:29 INFO - Running pre-action listener: _resource_record_pre_action 02:17:29 INFO - Running main action method: read_buildbot_config 02:17:29 INFO - Using buildbot properties: 02:17:29 INFO - { 02:17:29 INFO - "project": "", 02:17:29 INFO - "product": "firefox", 02:17:29 INFO - "script_repo_revision": "production", 02:17:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:17:29 INFO - "repository": "", 02:17:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:17:29 INFO - "buildid": "20170202004013", 02:17:29 INFO - "stage_platform": "macosx64", 02:17:29 INFO - "basedir": "/builds/slave/test", 02:17:29 INFO - "nightly_build": "True", 02:17:29 INFO - "buildnumber": 43, 02:17:29 INFO - "slavename": "t-yosemite-r7-0298", 02:17:29 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:17:29 INFO - "platform": "yosemite_r7", 02:17:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:17:29 INFO - "branch": "mozilla-aurora", 02:17:29 INFO - "repo_path": "releases/mozilla-aurora", 02:17:29 INFO - "revision": "d06f2bf30b22a04b03e2046d5c148adedd6777c1", 02:17:29 INFO - "pgo_build": "False", 02:17:29 INFO - "builduid": "e660ace11c1a4a7b9a4afd1e3a1e1441", 02:17:29 INFO - "slavebuilddir": "test" 02:17:29 INFO - } 02:17:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:17:29 INFO - Running post-action listener: _resource_record_post_action 02:17:29 INFO - [mozharness: 2017-02-02 10:17:29.422670Z] Finished read-buildbot-config step (success) 02:17:29 INFO - [mozharness: 2017-02-02 10:17:29.422812Z] Running download-and-extract step. 02:17:29 INFO - Running pre-action listener: _resource_record_pre_action 02:17:29 INFO - Running main action method: download_and_extract 02:17:29 INFO - mkdir: /builds/slave/test/build/tests 02:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:29 INFO - trying https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:17:31 INFO - Downloaded 1199 bytes. 02:17:31 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:17:31 INFO - Using the following test package requirements: 02:17:31 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:17:31 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:17:31 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:17:31 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'jsshell-mac.zip'], 02:17:31 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:17:31 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:17:31 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:17:31 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:17:31 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:17:31 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:17:31 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:17:31 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:17:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:17:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:17:31 INFO - Fetch https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:17:32 INFO - Expected file size: 16356155 02:17:32 INFO - Obtained file size: 16356155 02:17:38 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:17:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:17:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:17:38 INFO - Fetch https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:17:49 INFO - Expected file size: 16356155 02:17:49 INFO - Obtained file size: 16356155 02:17:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:17:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:17:55 INFO - Fetch https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:18:01 INFO - Expected file size: 14403485 02:18:01 INFO - Obtained file size: 14403485 02:18:02 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')]} 02:18:02 INFO - trying https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:02 INFO - Downloading https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:18:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:18:04 INFO - Downloaded 73953977 bytes. 02:18:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:04 INFO - mkdir: /builds/slave/test/properties 02:18:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:04 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:04 INFO - Contents: 02:18:04 INFO - build_url:https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:05 INFO - Running post-action listener: _resource_record_post_action 02:18:05 INFO - Running post-action listener: set_extra_try_arguments 02:18:05 INFO - [mozharness: 2017-02-02 10:18:05.751793Z] Finished download-and-extract step (success) 02:18:05 INFO - [mozharness: 2017-02-02 10:18:05.751947Z] Running populate-webroot step. 02:18:05 INFO - Running pre-action listener: _resource_record_pre_action 02:18:05 INFO - Running main action method: populate_webroot 02:18:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:05 INFO - '--tppagecycles', 02:18:05 INFO - '7'], 02:18:05 INFO - 'tests': ['tsvgm']}}, 02:18:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['damp', 'tps']}, 02:18:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'tests': ['damp', 'tps']}, 02:18:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['dromaeo_dom']}, 02:18:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['basic_compositor_video']}, 02:18:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:18:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['a11yr', 02:18:05 INFO - 'ts_paint', 02:18:05 INFO - 'tpaint', 02:18:05 INFO - 'sessionrestore', 02:18:05 INFO - 'sessionrestore_no_auto_restore', 02:18:05 INFO - 'tabpaint']}, 02:18:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['tsvgx', 02:18:05 INFO - 'tsvgr_opacity', 02:18:05 INFO - 'tart', 02:18:05 INFO - 'tscrollx', 02:18:05 INFO - 'cart', 02:18:05 INFO - 'tsvg_static']}, 02:18:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:05 INFO - 'tsvgr_opacity', 02:18:05 INFO - 'tart', 02:18:05 INFO - 'tscrollx', 02:18:05 INFO - 'cart', 02:18:05 INFO - 'tsvg_static']}, 02:18:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'talos_options': ['--disable-e10s'], 02:18:05 INFO - 'tests': ['tp5o']}, 02:18:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:18:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'talos_options': ['--disable-e10s', 02:18:05 INFO - '--xperf_path', 02:18:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:05 INFO - 'tests': ['tp5n']}, 02:18:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:18:05 INFO - 'talos_options': ['--xperf_path', 02:18:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:05 INFO - 'tests': ['tp5n']}}, 02:18:05 INFO - 'talos.zip': {'path': '', 02:18:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:05 INFO - Running post-action listener: _resource_record_post_action 02:18:05 INFO - [mozharness: 2017-02-02 10:18:05.770467Z] Finished populate-webroot step (success) 02:18:05 INFO - [mozharness: 2017-02-02 10:18:05.770605Z] Running create-virtualenv step. 02:18:05 INFO - Running pre-action listener: _resource_record_pre_action 02:18:05 INFO - Running main action method: create_virtualenv 02:18:05 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:05 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 02:18:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:05 INFO - Using real prefix '/tools/python27' 02:18:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:18:06 INFO - Installing distribute.............................................................................................................................................................................................done. 02:18:09 INFO - Installing pip.................done. 02:18:09 INFO - Return code: 0 02:18:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:18:09 INFO - Reading from file tmpfile_stdout 02:18:09 INFO - Output received: 02:18:09 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) 02:18:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:10 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:10 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 02:18:10 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 02:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:10 INFO - 'HOME': '/Users/cltbld', 02:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:10 INFO - 'LOGNAME': 'cltbld', 02:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:10 INFO - 'MOZ_NO_REMOTE': '1', 02:18:10 INFO - 'NO_EM_RESTART': '1', 02:18:10 INFO - 'PAGER': '/bin/cat', 02:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:10 INFO - 'PWD': '/builds/slave/test', 02:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:10 INFO - 'SHELL': '/bin/bash', 02:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:10 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:10 INFO - 'USER': 'cltbld', 02:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:10 INFO - 'XPC_FLAGS': '0x0', 02:18:10 INFO - 'XPC_SERVICE_NAME': '0', 02:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:10 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 02:18:10 INFO - Cleaning up... 02:18:10 INFO - Return code: 0 02:18:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:11 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:11 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 02:18:11 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 02:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:11 INFO - 'HOME': '/Users/cltbld', 02:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:11 INFO - 'LOGNAME': 'cltbld', 02:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:11 INFO - 'MOZ_NO_REMOTE': '1', 02:18:11 INFO - 'NO_EM_RESTART': '1', 02:18:11 INFO - 'PAGER': '/bin/cat', 02:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:11 INFO - 'PWD': '/builds/slave/test', 02:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:11 INFO - 'SHELL': '/bin/bash', 02:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:11 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:11 INFO - 'USER': 'cltbld', 02:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:11 INFO - 'XPC_FLAGS': '0x0', 02:18:11 INFO - 'XPC_SERVICE_NAME': '0', 02:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:11 INFO - Downloading/unpacking psutil>=3.1.1 02:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:11 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 02:18:11 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 02:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:18:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:15 INFO - Installing collected packages: psutil 02:18:15 INFO - Running setup.py install for psutil 02:18:16 INFO - building 'psutil._psutil_osx' extension 02:18:16 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 02:18:16 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 02:18:16 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 02:18:16 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 02:18:16 INFO - building 'psutil._psutil_posix' extension 02:18:16 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 02:18:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:18:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:18:16 INFO - ^ 02:18:16 INFO - 1 warning generated. 02:18:16 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 02:18:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:17 INFO - Successfully installed psutil 02:18:17 INFO - Cleaning up... 02:18:17 INFO - Return code: 0 02:18:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:17 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:17 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 02:18:17 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 02:18:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:17 INFO - 'HOME': '/Users/cltbld', 02:18:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:17 INFO - 'LOGNAME': 'cltbld', 02:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:17 INFO - 'MOZ_NO_REMOTE': '1', 02:18:17 INFO - 'NO_EM_RESTART': '1', 02:18:17 INFO - 'PAGER': '/bin/cat', 02:18:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:17 INFO - 'PWD': '/builds/slave/test', 02:18:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:17 INFO - 'SHELL': '/bin/bash', 02:18:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:17 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:17 INFO - 'USER': 'cltbld', 02:18:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:17 INFO - 'XPC_FLAGS': '0x0', 02:18:17 INFO - 'XPC_SERVICE_NAME': '0', 02:18:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:18:21 INFO - Installing collected packages: mozsystemmonitor 02:18:21 INFO - Running setup.py install for mozsystemmonitor 02:18:21 INFO - Successfully installed mozsystemmonitor 02:18:21 INFO - Cleaning up... 02:18:21 INFO - Return code: 0 02:18:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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', '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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:18: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 jsonschema==2.5.1 02:18:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:21 INFO - 'HOME': '/Users/cltbld', 02:18:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:21 INFO - 'LOGNAME': 'cltbld', 02:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:21 INFO - 'MOZ_NO_REMOTE': '1', 02:18:21 INFO - 'NO_EM_RESTART': '1', 02:18:21 INFO - 'PAGER': '/bin/cat', 02:18:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:21 INFO - 'PWD': '/builds/slave/test', 02:18:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:21 INFO - 'SHELL': '/bin/bash', 02:18:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:21 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:21 INFO - 'USER': 'cltbld', 02:18:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:21 INFO - 'XPC_FLAGS': '0x0', 02:18:21 INFO - 'XPC_SERVICE_NAME': '0', 02:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:22 INFO - Downloading/unpacking jsonschema==2.5.1 02:18:22 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 02:18:22 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 02:18:22 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 02:18:22 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 02:18:22 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 02:18:22 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 02:18:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:18:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:18:25 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 02:18:25 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 02:18:25 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 02:18:25 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 02:18:25 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 02:18:25 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 02:18:26 INFO - Downloading functools32-3.2.3-2.tar.gz 02:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:18:26 INFO - warning: no files found matching '*.txt' 02:18:26 INFO - no previously-included directories found matching 'build' 02:18:26 INFO - no previously-included directories found matching 'dist' 02:18:26 INFO - no previously-included directories found matching '.git*' 02:18:26 INFO - Installing collected packages: jsonschema, functools32 02:18:26 INFO - Running setup.py install for functools32 02:18:26 INFO - warning: no files found matching '*.txt' 02:18:26 INFO - no previously-included directories found matching 'build' 02:18:26 INFO - no previously-included directories found matching 'dist' 02:18:26 INFO - no previously-included directories found matching '.git*' 02:18:26 INFO - Successfully installed jsonschema functools32 02:18:26 INFO - Cleaning up... 02:18:26 INFO - Return code: 0 02:18:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:18:26 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')]} 02:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:26 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')]} 02:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:26 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:26 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 02:18:26 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 02:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:26 INFO - 'HOME': '/Users/cltbld', 02:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:26 INFO - 'LOGNAME': 'cltbld', 02:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:26 INFO - 'MOZ_NO_REMOTE': '1', 02:18:26 INFO - 'NO_EM_RESTART': '1', 02:18:26 INFO - 'PAGER': '/bin/cat', 02:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:26 INFO - 'PWD': '/builds/slave/test', 02:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:26 INFO - 'SHELL': '/bin/bash', 02:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:26 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:26 INFO - 'USER': 'cltbld', 02:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:26 INFO - 'XPC_FLAGS': '0x0', 02:18:26 INFO - 'XPC_SERVICE_NAME': '0', 02:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:18:27 INFO - Cleaning up... 02:18:27 INFO - Return code: 0 02:18:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:27 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:27 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 02:18:27 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 02:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:27 INFO - 'HOME': '/Users/cltbld', 02:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:27 INFO - 'LOGNAME': 'cltbld', 02:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:27 INFO - 'MOZ_NO_REMOTE': '1', 02:18:27 INFO - 'NO_EM_RESTART': '1', 02:18:27 INFO - 'PAGER': '/bin/cat', 02:18:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:27 INFO - 'PWD': '/builds/slave/test', 02:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:27 INFO - 'SHELL': '/bin/bash', 02:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:27 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:27 INFO - 'USER': 'cltbld', 02:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:27 INFO - 'XPC_FLAGS': '0x0', 02:18:27 INFO - 'XPC_SERVICE_NAME': '0', 02:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:27 INFO - Downloading/unpacking blobuploader==1.2.4 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:30 INFO - Downloading blobuploader-1.2.4.tar.gz 02:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:18:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:30 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 02:18:30 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 02:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:18:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18: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 02:18:31 INFO - Downloading docopt-0.6.1.tar.gz 02:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:18:31 INFO - Installing collected packages: blobuploader, requests, docopt 02:18:31 INFO - Running setup.py install for blobuploader 02:18:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:18:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:18:31 INFO - Running setup.py install for requests 02:18:32 INFO - Running setup.py install for docopt 02:18:32 INFO - Successfully installed blobuploader requests docopt 02:18:32 INFO - Cleaning up... 02:18:32 INFO - Return code: 0 02:18:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:32 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')]} 02:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:32 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')]} 02:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:32 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:32 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 02:18:32 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 02:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:32 INFO - 'HOME': '/Users/cltbld', 02:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:32 INFO - 'LOGNAME': 'cltbld', 02:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:32 INFO - 'MOZ_NO_REMOTE': '1', 02:18:32 INFO - 'NO_EM_RESTART': '1', 02:18:32 INFO - 'PAGER': '/bin/cat', 02:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:32 INFO - 'PWD': '/builds/slave/test', 02:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:32 INFO - 'SHELL': '/bin/bash', 02:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:32 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:32 INFO - 'USER': 'cltbld', 02:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:32 INFO - 'XPC_FLAGS': '0x0', 02:18:32 INFO - 'XPC_SERVICE_NAME': '0', 02:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:32 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-mlxCpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:32 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-x3xAuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:32 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-zS1dUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-LpW2PB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-X4KHFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ZgV7tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-VUnPbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-sJI9zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-6Rckor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-4swcrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-R3PbFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:33 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-yVkJWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-9QhRx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-x6PmGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ts537Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rYGvTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-QjeNHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:18:34 INFO - Running setup.py install for manifestparser 02:18:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:18:34 INFO - Running setup.py install for mozcrash 02:18:34 INFO - Running setup.py install for mozdebug 02:18:34 INFO - Running setup.py install for mozdevice 02:18:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Running setup.py install for mozfile 02:18:35 INFO - Running setup.py install for mozhttpd 02:18:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Running setup.py install for mozinfo 02:18:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Running setup.py install for mozInstall 02:18:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:18:35 INFO - Running setup.py install for mozleak 02:18:35 INFO - Running setup.py install for mozlog 02:18:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Running setup.py install for moznetwork 02:18:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Running setup.py install for mozprocess 02:18:36 INFO - Running setup.py install for mozprofile 02:18:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Running setup.py install for mozrunner 02:18:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:18:36 INFO - Running setup.py install for mozscreenshot 02:18:36 INFO - Running setup.py install for moztest 02:18:36 INFO - Running setup.py install for mozversion 02:18:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:18:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:18:37 INFO - Cleaning up... 02:18:37 INFO - Return code: 0 02:18:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:37 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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18:37 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 02:18:37 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 02:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:37 INFO - 'HOME': '/Users/cltbld', 02:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:37 INFO - 'LOGNAME': 'cltbld', 02:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:37 INFO - 'MOZ_NO_REMOTE': '1', 02:18:37 INFO - 'NO_EM_RESTART': '1', 02:18:37 INFO - 'PAGER': '/bin/cat', 02:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:37 INFO - 'PWD': '/builds/slave/test', 02:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:37 INFO - 'SHELL': '/bin/bash', 02:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:37 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:37 INFO - 'USER': 'cltbld', 02:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:37 INFO - 'XPC_FLAGS': '0x0', 02:18:37 INFO - 'XPC_SERVICE_NAME': '0', 02:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-plM9pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:37 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)) 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-spGqLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:37 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)) 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-m41vQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:37 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)) 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-EvA7gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:37 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)) 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-uEiqP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:37 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)) 02:18:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:37 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-45AObs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18: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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-X4PSwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-f0va0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0sObVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-QWK4wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-c09W0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-qCEUAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0M7Hue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-5gJQvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:38 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)) 02:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:38 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-6V9Hii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18: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)) 02:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-IgaT5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18: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)) 02:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:39 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-YeyG2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:39 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)) 02:18:39 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)) 02:18:39 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)) 02:18:39 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)) 02:18:39 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)) 02:18:39 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)) 02:18:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:39 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 02:18:39 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 02:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:42 INFO - Downloading blessings-1.6.tar.gz 02:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:18:42 INFO - Installing collected packages: blessings 02:18:42 INFO - Running setup.py install for blessings 02:18:42 INFO - Successfully installed blessings 02:18:42 INFO - Cleaning up... 02:18:42 INFO - Return code: 0 02:18:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:43 INFO - Reading from file tmpfile_stdout 02:18:43 INFO - Current package versions: 02:18:43 INFO - blessings == 1.6 02:18:43 INFO - blobuploader == 1.2.4 02:18:43 INFO - docopt == 0.6.1 02:18:43 INFO - functools32 == 3.2.3-2 02:18:43 INFO - jsonschema == 2.5.1 02:18:43 INFO - manifestparser == 1.1 02:18:43 INFO - mozInstall == 1.12 02:18:43 INFO - mozcrash == 1.0 02:18:43 INFO - mozdebug == 0.1 02:18:43 INFO - mozdevice == 0.49 02:18:43 INFO - mozfile == 1.2 02:18:43 INFO - mozhttpd == 0.7 02:18:43 INFO - mozinfo == 0.9 02:18:43 INFO - mozleak == 0.1 02:18:43 INFO - mozlog == 3.4 02:18:43 INFO - moznetwork == 0.27 02:18:43 INFO - mozprocess == 0.24 02:18:43 INFO - mozprofile == 0.28 02:18:43 INFO - mozrunner == 6.13 02:18:43 INFO - mozscreenshot == 0.1 02:18:43 INFO - mozsystemmonitor == 0.3 02:18:43 INFO - moztest == 0.8 02:18:43 INFO - mozversion == 1.4 02:18:43 INFO - psutil == 3.1.1 02:18:43 INFO - requests == 1.2.3 02:18:43 INFO - wsgiref == 0.1.2 02:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18: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 02:18: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 02:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:43 INFO - 'HOME': '/Users/cltbld', 02:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:43 INFO - 'LOGNAME': 'cltbld', 02:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:43 INFO - 'MOZ_NO_REMOTE': '1', 02:18:43 INFO - 'NO_EM_RESTART': '1', 02:18:43 INFO - 'PAGER': '/bin/cat', 02:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:43 INFO - 'PWD': '/builds/slave/test', 02:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:43 INFO - 'SHELL': '/bin/bash', 02:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:43 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:43 INFO - 'USER': 'cltbld', 02:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:43 INFO - 'XPC_FLAGS': '0x0', 02:18:43 INFO - 'XPC_SERVICE_NAME': '0', 02:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18: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)) 02:18:43 INFO - Cleaning up... 02:18:43 INFO - Return code: 0 02:18:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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 0x102fcdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fd0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c3b5518a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, '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.VaT4iok1Vq/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.6D3n52Yims/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 02:18: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 02:18: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 02:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:43 INFO - 'HOME': '/Users/cltbld', 02:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:43 INFO - 'LOGNAME': 'cltbld', 02:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:43 INFO - 'MOZ_NO_REMOTE': '1', 02:18:43 INFO - 'NO_EM_RESTART': '1', 02:18:43 INFO - 'PAGER': '/bin/cat', 02:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:43 INFO - 'PWD': '/builds/slave/test', 02:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:43 INFO - 'SHELL': '/bin/bash', 02:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:18:43 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:43 INFO - 'USER': 'cltbld', 02:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:43 INFO - 'XPC_FLAGS': '0x0', 02:18:43 INFO - 'XPC_SERVICE_NAME': '0', 02:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:18:43 INFO - Cleaning up... 02:18:43 INFO - Return code: 0 02:18:43 INFO - Running post-action listener: _resource_record_post_action 02:18:43 INFO - Running post-action listener: _start_resource_monitoring 02:18:43 INFO - Starting resource monitoring. 02:18:43 INFO - [mozharness: 2017-02-02 10:18:43.765029Z] Finished create-virtualenv step (success) 02:18:43 INFO - [mozharness: 2017-02-02 10:18:43.766058Z] Running install step. 02:18:43 INFO - Running pre-action listener: _resource_record_pre_action 02:18:43 INFO - Running main action method: install 02:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:44 INFO - Reading from file tmpfile_stdout 02:18:44 INFO - Detecting whether we're running mozinstall >=1.0... 02:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:18:44 INFO - Reading from file tmpfile_stdout 02:18:44 INFO - Output received: 02:18:44 INFO - Usage: mozinstall [options] installer 02:18:44 INFO - Options: 02:18:44 INFO - -h, --help show this help message and exit 02:18:44 INFO - -d DEST, --destination=DEST 02:18:44 INFO - Directory to install application into. [default: 02:18:44 INFO - "/builds/slave/test"] 02:18:44 INFO - --app=APP Application being installed. [default: firefox] 02:18:44 INFO - mkdir: /builds/slave/test/build/application 02:18:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:19:04 INFO - Reading from file tmpfile_stdout 02:19:04 INFO - Output received: 02:19:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:19:04 INFO - Running post-action listener: _resource_record_post_action 02:19:04 INFO - [mozharness: 2017-02-02 10:19:04.542363Z] Finished install step (success) 02:19:04 INFO - [mozharness: 2017-02-02 10:19:04.542515Z] Running run-tests step. 02:19:04 INFO - Running pre-action listener: _resource_record_pre_action 02:19:04 INFO - Running main action method: run_tests 02:19:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:19:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:19:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:19:04 INFO - 2017-02-02 02:19:04.557 screenresolution[1626:7723] starting screenresolution argv=screenresolution get 02:19:04 INFO - 2017-02-02 02:19:04.560 screenresolution[1626:7723] Display 0: 1600x1200x32@60 02:19:04 INFO - 2017-02-02 02:19:04.566 screenresolution[1627:7725] starting screenresolution argv=screenresolution list 02:19:04 INFO - Available Modes on Display 0 02:19:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:19:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:19:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:19:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:19:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:19:04 INFO - Intel Iris: 02:19:04 INFO - Chipset Model: Intel Iris 02:19:04 INFO - Type: GPU 02:19:04 INFO - Bus: Built-In 02:19:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:19:04 INFO - Vendor: Intel (0x8086) 02:19:04 INFO - Device ID: 0x0a2e 02:19:04 INFO - Revision ID: 0x0009 02:19:04 INFO - Displays: 02:19:04 INFO - Display: 02:19:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:19:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:19:04 INFO - Display Serial Number: 1600x1200 60 02:19:04 INFO - Main Display: Yes 02:19:04 INFO - Mirror: Off 02:19:04 INFO - Online: Yes 02:19:04 INFO - Rotation: Supported 02:19:04 INFO - Return code: 0 02:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:19:04 INFO - Python 2.7.3 02:19:04 INFO - Return code: 0 02:19:04 INFO - grabbing minidump binary from tooltool 02:19: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')]} 02:19: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 0x102dabd30>, '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 0x102cd2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102da0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:19: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 02:19: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 02:19: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 02:19:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:19:04 INFO - Return code: 0 02:19:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:19:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:19:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:19:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:19:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 02:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 02:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VaT4iok1Vq/Render', 02:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:04 INFO - 'HOME': '/Users/cltbld', 02:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:04 INFO - 'LOGNAME': 'cltbld', 02:19:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:04 INFO - 'MOZ_NO_REMOTE': '1', 02:19:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:04 INFO - 'NO_EM_RESTART': '1', 02:19:04 INFO - 'PAGER': '/bin/cat', 02:19:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:04 INFO - 'PWD': '/builds/slave/test', 02:19:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:04 INFO - 'SHELL': '/bin/bash', 02:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6D3n52Yims/Listeners', 02:19:04 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 02:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:04 INFO - 'USER': 'cltbld', 02:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:04 INFO - 'XPC_FLAGS': '0x0', 02:19:04 INFO - 'XPC_SERVICE_NAME': '0', 02:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 02:19:05 INFO - mozversion application_buildid: 20170202004013 02:19:05 INFO - mozversion application_changeset: d06f2bf30b22a04b03e2046d5c148adedd6777c1 02:19:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:19:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:05 INFO - mozversion application_name: Firefox 02:19:05 INFO - mozversion application_remotingname: firefox-dev 02:19:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:05 INFO - mozversion application_vendor: Mozilla 02:19:05 INFO - mozversion application_version: 53.0a2 02:19:05 INFO - mozversion platform_buildid: 20170202004013 02:19:05 INFO - mozversion platform_changeset: d06f2bf30b22a04b03e2046d5c148adedd6777c1 02:19:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:19:05 INFO - mozversion platform_version: 53.0a2 02:19:05 INFO - using testdate: 1486030745 02:19:05 INFO - actual date: 1486030745 02:19:05 INFO - starting webserver on 'localhost:49241' 02:19:05 INFO - SUITE-START | Running 2 tests 02:19:05 INFO - TEST-START | dromaeo_css 02:19:05 INFO - operating with platform_type : mac_ 02:19:05 INFO - Initialising browser for dromaeo_css test... 02:19:05 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpMddaJN/profile http://localhost:49241/getInfo.html) 02:19:08 INFO - PROCESS | 1637 | 2017-02-02 02:19:08.290 plugin-container[1640:7901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 02:19:08 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:19:08 INFO - PROCESS | 1637 | 2017-02-02 02:19:08.291 plugin-container[1640:7901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9323, name = 'com.apple.CFPasteboardClient' 02:19:08 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:19:08 INFO - PROCESS | 1637 | 2017-02-02 02:19:08.291 plugin-container[1640:7901] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:19:08 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 02:19:08 INFO - PROCESS | 1637 | colorDepth:24 02:19:08 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 02:19:08 INFO - PROCESS | 1637 | __metrics 02:19:08 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:19:09 INFO - TEST-INFO | 1637: exit 0 02:19:09 INFO - Browser initialized. 02:19:09 INFO - Running cycle 1/1 for dromaeo_css test... 02:19:09 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpMddaJN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:11 INFO - PROCESS | 1641 | 2017-02-02 02:19:11.653 plugin-container[1642:8021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 02:19:11 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:19:11 INFO - PROCESS | 1641 | 2017-02-02 02:19:11.654 plugin-container[1642:8021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 02:19:11 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:19:11 INFO - PROCESS | 1641 | 2017-02-02 02:19:11.654 plugin-container[1642:8021] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:19:21 INFO - PROCESS | 1641 | RSS: Main: 219242496 02:19:21 INFO - PROCESS | 1641 | 02:22:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:22:25 INFO - PROCESS | 1641 | RSS: Main: 239828992 02:22:25 INFO - PROCESS | 1641 | 02:24:09 INFO - PROCESS | 1641 | ************************* 02:24:09 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:24:09 INFO - PROCESS | 1641 | 02:24:09 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:24:09 INFO - PROCESS | 1641 | Full stack: 02:24:09 INFO - PROCESS | 1641 | ************************* 02:25:27 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:25:28 INFO - PROCESS | 1641 | RSS: Main: 248909824 02:25:28 INFO - PROCESS | 1641 | 02:28:30 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:28:30 INFO - PROCESS | 1641 | RSS: Main: 255913984 02:28:30 INFO - PROCESS | 1641 | 02:31:32 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:31:33 INFO - PROCESS | 1641 | RSS: Main: 253317120 02:31:33 INFO - PROCESS | 1641 | 02:34:36 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:34:37 INFO - PROCESS | 1641 | RSS: Main: 257998848 02:34:37 INFO - PROCESS | 1641 | 02:37:39 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:37:40 INFO - PROCESS | 1641 | RSS: Main: 260153344 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | __start_tp_report 02:37:40 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 02:37:40 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 02:37:40 INFO - PROCESS | 1641 | |i|pagename|runs| 02:37:40 INFO - PROCESS | 1641 | |0;dojo.html;3334.0831271400375;3356.31195604901;3376.915577110572;3386.1026827890614;3407;4952.2819191217295;4972.955679307341;5485.259489968853;5690.487856092951;5723.11291749779;5502.477264659861;5750.654960702351;5778;5785.27684039495;5794.884102317962;5234.8691282717855;5296.655717378378;5300.814471493499;5316.9202461963105;5329.520343169114;5317.229001794737;5330.786768529254;5350.411454739976;5358.892822143574;5366.9463305367035;5176.404713457944;5196.454372290912;5355.946440535603;5365.195220716904;5392.865178370533;5195.714235717053;5252.553532949704;5270.5520030797425;5278.102722537178;5341.332333458317;5123.1546794778815;5219.582433405357;5239.292695486099;5246.396664383568;5312.256284120226;5715.799947001877;5716.485516303511;5870.412958704138;5885.176075349455;5895.764169433218;6097.878042439171;6146.89355915935;6199.907001394983;6219.564630475868;6228.128062071313;6276.246850377938;6284.151639528651;6309.463695585835;6321.620702757849;6328.019157030668;5847.444492773222;5859.912101318484;5872.853178670482;5897.7935772247765;5913.965056115206;5206.55744261742;6122.184701511125;6176.567640265138;6231.813045608639;6371.490280777527;4786.353842231324;6072.666003369769;6189.721462534197;6209.192804935329;6210.534209934273;6258.123862659231;6279.717412716347;6282.968585157137;6287.528435367366;6297.307296197415;5189.792408303626;5299.993001329735;6013.368596297326;6148.631082135086;6193.535484838565;6241;6295.087212354183;6308.179936608303;6312.147860038836;6328.6835658216905;5008;5023.2214006829;5025.84922452327;5038.0931432341795;5040.36995375578;4421.027373977719;4441.200583894865;4492.101579684077;4492.797824097924;4539.319102134707;5534.169874518854;5855.268091488564;5905.911411328833;5955.970220148958;5960.0165972614905;6259.280182778948;6262.091996660459;6286.937130628727;6294.118823364733;6305.873882522324;5362.7397561573;5790.247267855236;5874.441928016831;5883.205767221456;5954.821355359346;4767.594754445841;5543.722813859291;5555.111182210827;5556.583256255797;5591.273134492571;6439.8068057958335;6451.387118223761;6464.612123272713;6496.382843629847;6498.610083395011;6147.385261473995;6204.534659900435;6237.313895471404;6296.338884417253;6315.747370105144;6331.0819931110655;6386.201724784402;6420.518461115528;6434.742610295536;6442.581232219891;6200.224971878515;6255.9374406257175;6282.905756413749;6283.088952102011;6293.181886354744;5286.233496142961;6137.346341658241;6187.102870083902;6303.46420554258;6321.367863213642;6141.907871382022;6141.938580614136;6146.969265153645;6201.875962480815;6211.440970312694;5167.354080739908;5845.947729408747;6151.723172457339;6163.167972323679;6212.844678883064;5904.173415721716;5928.8814223716145;6015.5488338373925;6044.516438684983;6046.123312119806;6083.543734219863;6098.969505152446;6108.694565271808;6109.511239100772;6162.506999439945;5049.26785616091;6029.849253768691;6038.275406951194;6045.969770151121;6092 02:37:40 INFO - PROCESS | 1641 | |1;ext.html;438.52858177459217;461.44626448262096;483.64452127686167;502.6958689992554;522.0993785719634;33877;38256.043598910044;40486;40520.594794052085;40739.388909166315;41248.79375603118;41265.79367103168;41391;41589.79205103978;41609.95975100624;7197.640117994106;9351.392159509644;9533;9537.28470364722;9565.713028609152;8368.790780230482;11219.102471802276;11507.42462876857;11525.308481491138;11615.535378584846;11310.208285420023;11413;11487.885121148807;11502.539898404095;11519.9632033117;11310.264832785842;11341.886581134166;11382.886171138307;11462.48418821155;11471.59849405271;11280.2667826591;11339.659810205707;11361.261518001385;11377;11403.600873969453;38654.420183697264;38715.61284387148;38868.61131388678;38887.22225555473;38917.61082389168;3391.6608339165887;3449.6377880322457;3992.700547458923;4019.8995025124314;4051.3517837145914;28698.856505717336;28902.71097289021;29753.25616859574;30375.848120759474;30499.847500762353;3055.279379598343;3236.2233064064453;3245.983770081158;3260.315333779909;3287.8684852605866;416411;428636;458767;462521;462567.6871615654;59079.70460147672;60213;60255.09617355743;60271.69864150651;61072.69463652653;58392.7080364604;62839;62930;63146.68426657837;63359.049614255826;15827.920860395854;16327.93868398574;16351.018938863706;16360.018398896103;16409.83590164107;10490.475476226158;11057.512059786644;12737.172083814523;12800.61598152057;12894.67763305925;19329.130189141524;19586.824790512615;19610.215591376185;20200.393988180083;20201.29295474681;8843.955780221186;8968.910310896808;8975.820483590292;9001.684941027033;9045.231155351737;858.5492616376328;876.2289185516831;897.0894542039835;902.120432578231;923.9861402078974;26990;30081.849590751906;30354.392912141633;30810.84594577013;30879.2280192997;853.5732133933034;1080.4165750494644;1113.5156207049959;1118.3290025984506;1198.9760204795855;14895.106293622417;15110.454052812152;15444;15591.532254032398;15917.269782878155;619316;621936;622549;630972.8451357714;632738;51350.486495135316;51963.48036519662;52106.47893521092;52219.21671174935;52247.73876130595;1373.6909195430949;1386.3622733542684;1409.344654735553;1431.055503367793;1439.9280035998158;26004.73995260099;26849.731502684725;27072.72927270702;27535.44929101447;27588.89644414281;43617.78191109024;43846;44226.33660495031;44227.33658995053;44483;563059;566479;569420;569899;570755.438668412;23711.288661340186;24442;24466.755332446446;24732.010719571736;25013.12454064099;39624;39690.801545992086;39877.60428384993;40092.3986140214;40744.79627601843;35025.64974350224;35026.82486587653;35299.29401412009;35483.46774798328;36009.819950901125;34583.481247781805;34769;35557.466638000966;35889.48773585824;35933.46099808453;34100.80647177337;34103.147421314665;34319.4852077214;34595.4810677835;34843;29985.250368740082;31255.84372078125;31347.84326078355;31445.842770786;31547.684523154476;20508.38474845757;24052.759472405753;24188.879055604608;24230.030798767853;24803.88382522827 02:37:40 INFO - PROCESS | 1641 | |2;jquery.html;384.45407521319726;384.5808069204565;400.9298372784762;402.35422147453335;418.02391416043537;16537.91731041343;20135.798642013575;20449.386518404433;21300.147994080216;21420.464488387777;20908.909109089083;21377.14491420345;21416.892915535438;21452.678209826867;21578.89210553945;5385.030694474993;5985.102234664802;6155.476784473302;6204.348543402945;6210.099535567362;5117.130087885069;5707.5148612368;6125.448709616134;6131.3562075982045;6161.599496032766;6000.579959402844;6114.571979961404;6115.205023346959;6133.478654314388;6134.17188679527;5736.799212027579;6043.697815109271;6059.66671833049;6084.817455476343;6118.204633397652;5045.798168073273;5938.287405511366;6035.094735789623;6037.335893051761;6046.2139921809885;13045.608631741063;13611.319434028259;13656.93171534146;13693;13696.315184240748;2604.0885690008536;2633.793209891917;2664.1607893513483;2686.3955609987793;2686.7984901132495;9997.05028022344;10178.745531361701;10213.846792298122;10597.894021059845;10611.257211995164;1801.117452448304;1838.5955089880206;1868.392772348992;1882.2094720217542;1882.7646544181978;140412;142764;148459.25770371186;148672.2566387172;149938;12139.210951288138;12238.449269782881;12283.324417156964;12393;12400.317982510867;14206.573802785933;14932;16280.918595407184;16319.755203671955;16338.346466141222;8679.088695686989;8865.778355541162;9038.86441703375;9058.818823623491;9077.13767192129;7111.288871112847;8769.780755481037;8770.605322760492;8906.510141942126;8916;4762.14281429346;4810.470848206694;4828.734419606885;4907.460179380265;5006.674566153189;2036.8981550922394;2065.7624373196973;2089;2090.2893016374505;2098.412444515538;1015.0052948110892;1045.8483519889573;1046.5081411736624;1057.349729916096;1082.1721383141903;22383;22901.885490572775;22960.4259893504;22967.6554851674;22974.966126524345;1005.5274021210166;1007.7956841574248;1070.9946450267855;1093.2347356850237;1099.912007039435;11140.220184587162;11680.415979201007;11814.350210738492;11823.113266504875;11838.585649502225;722280;730495;735831;736724;746260;130609;159642;160347.3965260356;162506.1874690619;168540.31459685246;876.2420506262038;1009.0817356205818;1030.721705139608;1037.113268155716;1068.6847380022912;20453.079611417223;21275.787242127382;21503.892480537495;21510;21693.240736574146;49848.75075624599;60980.780384391175;61399.386006139364;64117;64866.351336486034;94079.52960235429;96594;97196;97930.51034745066;98191;7136.571805691675;8314.168583141638;8681.91318086811;8771.824563508566;8862.734117976472;10472.947635261775;13426.865731342952;13480.79778803299;13557.389917453935;13963.092398994033;11424.600138995096;11631;11807.409629518317;11840.467178976794;11867.821982670093;10779.245452818228;11074.446277686244;11401.486933087865;11681.240719353214;11809.173357865211;10710.518026688662;11232.831507527557;11548.364839933884;11818.468168932246;12183.451744671334;7222.313880181375;7649.235076492307;7689.154193038649;8556.229939305493;8772.254358379354;8243.505389676639;10811.837822432828;11830;11893.226940248856;11933.820992684943 02:37:40 INFO - PROCESS | 1641 | |3;mootools.html;1363.9522616708414;1427.7287315410072;1429.7640889253273;1440.9625069949632;1458.0158393084664;7328.193898671148;7477.775666730006;7730.88403673944;8475.364347673925;8556.57217139142;7918.683252669886;8123.675052997873;8242.175782421755;8304.584770761452;8387.370947178955;2126.479012641897;2300.3559003479013;2340.555294494049;2343.5430091132234;2347.5187586544716;2088.2273558783195;2175.1625624134745;2309.3533810533077;2344.824138189634;2360.3745007573007;2263.366257447909;2339.742628310893;2341.7424083350816;2343.2618725101624;2345.2495201535517;2308.792208701225;2309.930702078932;2335.054303007285;2335.626299792042;2336.719593648756;2269.807066399358;2272.8409011369213;2323.9419014524774;2331.988340058289;2369.312899259213;8343.99872015362;8447.57762111898;8498.957505212435;8505.149485051445;8509.574521273911;5091.159958606825;5207.140821764404;5244.344456942882;5247.134222853225;5247.370315562119;3332.266901281714;3464.532288141094;3493.825308734553;3519.7712148710507;3563.233904710474;1151.1366475143643;1169.5146514196683;1210.3222195570509;1231.0828432817518;1239.330761388858;8811.691590794291;10746.570137194425;10838.891611083867;10846.728831779268;10923.180761442924;4612.3542704021465;4692.014676917817;4718.646101542398;4764.523547645208;4776.904461910812;9400.012998635182;9569.186619137452;9576.090271424204;9586.66446674363;9597.040295970348;5171.974140129275;5219.504147106095;5270.578353731769;5278.841634750886;5286.444923283077;4591.47198072226;4785.258284965836;4788.569028787426;4804.711717296974;4825.662203645781;2889.508783506809;3841.750286231386;3921.627445392683;3953.802309884494;4018.9799051004557;1188.5899364719157;1223.7368965672424;1247.9500819967282;1280.6734282758023;1290.4773566705499;915.0803442540205;916.6104405627715;918.9172974432199;926.0693003531408;947.8672985781996;4853.4418541867435;5424.321959755031;5761.337446193741;5770.740316685759;5961;795.1531618825932;949.3307218411004;976.5215044628081;980.6861804222581;1027.4246422003703;3237.7858303136322;3844.231153769257;3877.6122387760997;3888.319544079775;3897.590752970897;9777.364471309342;10112.797744045078;10142.797144057078;10162.949185254027;10178.287519873538;8419.326453883674;9893.45585992763;9983.201343892471;10067.194624430174;10136.391816491034;7513.2862378074;7804.492707973964;7845.686172553148;7926.24700653437;7929.920700793034;4549.79525921341;4888.828890988798;5657.179708942098;5804.216430781959;5901.967155747641;9026.503542304974;9168.220701240203;9176.311776616914;9246.537673116452;9439.575219115293;9028.00691923901;9249.86125208109;9275.953620231856;9368.859467107863;9403.764905877408;7014.649267536501;7079.893801592877;7102.254263302279;7203.459740519377;7231.132264128339;7763.61181940912;7838;7843.725469608765;7851.254130857559;7870;6954.930450695429;7077.646117693992;7109.182444019004;7143.82140446493;7200.063991681048;6963.895541566779;7001.36987671112;7001.894971575329;7084.574925504487;7086.149662040588;5945.821625351246;6341.334159913327;6426;6430.067640192239;6430.324815894263;4670;4988.8752781180765;5008.649394542347;5055.443901170795;5061.645684802029;4865.197242455097;4880.389951256093;4880.90238195227;4971.403431588233;4998.4251811042195 02:37:40 INFO - PROCESS | 1641 | |4;prototype.html;336.73566250493366;348.9755717099803;366.6113919245599;368.85983326335986;372.5250305860028;4876.512348765126;5344.3586769587655;5537.338798360491;5648;5658.0295344943925;5505.476979686923;5510.6969116698565;5568.832935011946;5585.218069470267;5595.49640532352;3730.7761534307892;4148.232576973254;4349.628623233653;4360.542143074979;4365.978170109161;3488.127968007998;3507.964920350802;3987.703996201232;4168.040691109316;4216.725912815673;3873.8572121224165;4119.814608342616;4120;4168.458100446937;4200.243956087903;3471.94792078119;4011.97994010031;4043.939340909889;4204.129162522679;4217.746935183884;2785.4846853332197;3320.335932813447;3419.6580341965605;3535.699465545432;3589.300086483137;4235.49174099107;4771.161159110358;4785.712857228577;5045.545900868903;5325.961437519685;4201.369794530814;4761.333413322138;4761.904761904742;4788.904221915543;4808.711477311373;4898.43250159949;5123.871903202413;5277.577793776489;5731.202604002878;5835.270591176103;3687.8284675179325;3724.48036605408;3917.4319723639915;3943.704222183319;3973.3642617181113;5210.713410762407;5634.464490979076;5875.764969401262;6135.355787642323;6203.534734894901;3938.6849052075763;4180.602842729997;4218.6835987300465;4222.2611043067955;4306.590873866978;3747.662710356081;4355.586219309161;4362.099204029285;4404.933925991113;4405.383246345515;4418.293073108352;4773.37946067016;4973.676711013773;5018.372703412074;5034.697918124924;5811.878664287505;5855.355910849803;5884.117382392676;5914.940850591525;5985.610935289279;1210.9939450302693;1241.4847838147248;1352.5671785028894;1429.7426463236718;1430.978535321971;906.5150144672568;926.8784770427858;995.8107959487675;1000.4297550396204;1019.8929112443235;4400.537943515948;4418.779061046935;4942.7130957913305;5216.765245563959;5256.608243249312;5484.972575137179;5759.8560035999435;5786.971065144648;5826.533877289891;5854.970725146347;3948.3879998600264;4509.774511274423;4828.058528586892;5207.323048003811;5310.955282216374;2599.6750406199226;2902.753265972374;2940.867660955262;3036.711512406318;3076.9538456923165;4759.143933865795;5222.242774797709;5292.168689750964;5963.4334738199805;6061.605995610271;4938.728369939688;5348.786048558014;5717.714114294268;5876.589618491616;6226.968865155645;6055;6262.655553944577;6282.403171698773;6284.277308109626;6303.6532990685955;5589.413111623221;5925.822225333247;5959.523238141025;6083.239595050619;6123.663198524124;5462.317210348707;5898.793542226002;5964.7912323068485;6004.819855404345;6014.969925150346;4785.6171506278715;5112.870535848019;5339.958708051892;5443.1695518331035;6294.370562943669;3912.237113762789;3954.822033008579;3970.940435893406;3986.900327491836;3990.4812374391145;4898.681585696918;5988.8802223956145;6009.188759517584;6047.274327080779;6067.5123199488;4503.301988191835;4682.906341873076;4754.0016596515115;4942.505749425029;5221.190715439113;5077.492250774893;5836.212111365081;5856.472917437451;5859.94140058611;5872.324682661549;5274.496768420981;5388.865278367915;5741.1534272037;5759.51044161251;5770.2498675171955;5255.37379208345;5449.482299181572;5819.534437244925;5914.7338369774325;5928.762849486145;5768.711564421678;5772.336181339199;5782.479576838105;5800.24596802413;5812.273465816773 02:37:40 INFO - PROCESS | 1641 | |5;yui.html;701.5860642221088;704.54556810857;763.0423818108277;829.9585020748963;839.0728245288956;15464.458743943964;15991.200439977983;16005.27976241069;16033.83966160338;16069.437569685064;14603.561893143196;14621.122732636022;14717.264136793172;14737.557873263819;14801.333939972701;586.61576667283;667.9565828221174;752.4732687119011;852.6930305090193;860.7632900952241;855.6064210463195;871.2594294849391;875.4090988582712;876.6493402638931;879.4283715584857;895.8297923394567;930.2000279759435;941.7739742461794;942.5146049784366;945.8344789661816;835.7743409279522;932.9720108396725;935.6818681648205;939.0890835889245;939.426949560764;681.9590824550493;685.3831551603547;701.719312275094;703.1843062048;703.3529153179087;14915.850841491554;15407.3837046518;15433.768493472608;15948.441804536902;15988.920055399765;14373.712525749532;14686.926565367105;14851;14918.40326386954;14983.250837458192;11602.477888495037;11775.587854425054;11911.04711623068;12013.399330033551;12049.93975030128;3741.38267185914;3788.5074940257864;3833.09922168293;3933.8623148189963;3951.881443556698;106138.40792388043;107400.46299768529;108358.37462437991;108770.45614771875;109417;11261.94369028166;11272.492737826817;11343.886561134448;11442.771144577062;11561.397467950137;23742.16902408442;23828.64257036146;24124.39689007789;24195.274141775775;24260.757392426203;7660.578668173304;7975.601219939096;7979.561124138112;7980.680772769024;8066.072401673882;4259.297215959394;4294;4337.869863904088;4349.847755328612;4396.934045989313;2818.9428964138447;3099.5815564898903;3610.7291953103622;3622.5290712207243;3655.94516082259;547.6850810783815;594.910763385487;646.7219095788856;648.1671052697261;651.1502489251573;562.8817948230835;565.527784300114;576.420697199312;588.7644942023139;602.4999250621973;9861.506924653882;10009;10666.73333166677;11180.273282236629;11518.712032199095;498.9725565093882;553.3249435688454;588.9175515427844;597.7698586044318;700.9018737376771;5002.074616196015;5123.462036486189;5140.717260550706;5143.27994080829;5159.58723302135;90383;93437;94966.05033949572;95051;95694.0430595699;64360.678196608715;74939.0048796095;76071;86101.13898861056;87553.56223218971;72377.63811180911;73250.63374683092;73602.2639773606;74373.25626743663;74914.5017099655;10542;11011.339319640847;11073.667789965992;11075.280106793032;11174.27367221128;45313.773431132635;47416.05167896554;47570.52429475661;47752.7612361936;52724.20913686221;67364.98952515618;67487;67855;69837.65081174765;70688.6465567669;12694.746105078031;12842.614721558368;13111.279183169598;13206.735865282833;13272.800907986195;14150.504732334319;14182.645433864236;14684.265786710836;14974.775378369115;15503.302351393988;11361.204715669825;13464.730705386033;13629.931850340685;13641.658958525719;13998.510052148125;12811.167274127152;13707.588772336845;13716.931415342859;13907.58277251684;14007.579772606838;12754.61736147917;13444.058915876187;13642.795358069437;13728.176309421468;13756;9857.915629280917;10862.456877156268;11247.887521124683;11373.431328433711;11529.654110376701;9484.668036618685;9784.33666276749;10421.270511064457;10527.842082368617;10603.204759642904 02:37:40 INFO - PROCESS | 1641 | __end_tp_report 02:37:40 INFO - PROCESS | 1641 | __start_cc_report 02:37:40 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,374 02:37:40 INFO - PROCESS | 1641 | __end_cc_report 02:37:40 INFO - PROCESS | 1641 | __startTimestamp1486031860068__endTimestamp 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | ------- Summary: start ------- 02:37:40 INFO - PROCESS | 1641 | Number of tests: 6 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5745.14 Median:5954.82 stddev:654.16 (11.0%) stddev-sans-first:628.23 02:37:40 INFO - PROCESS | 1641 | Values: 3334.1 3356.3 3376.9 3386.1 3407.0 4952.3 4973.0 5485.3 5690.5 5723.1 5502.5 5750.7 5778.0 5785.3 5794.9 5234.9 5296.7 5300.8 5316.9 5329.5 5317.2 5330.8 5350.4 5358.9 5366.9 5176.4 5196.5 5355.9 5365.2 5392.9 5195.7 5252.6 5270.6 5278.1 5341.3 5123.2 5219.6 5239.3 5246.4 5312.3 5715.8 5716.5 5870.4 5885.2 5895.8 6097.9 6146.9 6199.9 6219.6 6228.1 6276.2 6284.2 6309.5 6321.6 6328.0 5847.4 5859.9 5872.9 5897.8 5914.0 5206.6 6122.2 6176.6 6231.8 6371.5 4786.4 6072.7 6189.7 6209.2 6210.5 6258.1 6279.7 6283.0 6287.5 6297.3 5189.8 5300.0 6013.4 6148.6 6193.5 6241.0 6295.1 6308.2 6312.1 6328.7 5008.0 5023.2 5025.8 5038.1 5040.4 4421.0 4441.2 4492.1 4492.8 4539.3 5534.2 5855.3 5905.9 5956.0 5960.0 6259.3 6262.1 6286.9 6294.1 6305.9 5362.7 5790.2 5874.4 5883.2 5954.8 4767.6 5543.7 5555.1 5556.6 5591.3 6439.8 6451.4 6464.6 6496.4 6498.6 6147.4 6204.5 6237.3 6296.3 6315.7 6331.1 6386.2 6420.5 6434.7 6442.6 6200.2 6255.9 6282.9 6283.1 6293.2 5286.2 6137.3 6187.1 6303.5 6321.4 6141.9 6141.9 6147.0 6201.9 6211.4 5167.4 5845.9 6151.7 6163.2 6212.8 5904.2 5928.9 6015.5 6044.5 6046.1 6083.5 6099.0 6108.7 6109.5 6162.5 5049.3 6029.8 6038.3 6046.0 6092.0 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:67351.27 Median:34682.24 stddev:147429.77 (425.1%) stddev-sans-first:147757.75 02:37:40 INFO - PROCESS | 1641 | Values: 438.5 461.4 483.6 502.7 522.1 33877.0 38256.0 40486.0 40520.6 40739.4 41248.8 41265.8 41391.0 41589.8 41610.0 7197.6 9351.4 9533.0 9537.3 9565.7 8368.8 11219.1 11507.4 11525.3 11615.5 11310.2 11413.0 11487.9 11502.5 11520.0 11310.3 11341.9 11382.9 11462.5 11471.6 11280.3 11339.7 11361.3 11377.0 11403.6 38654.4 38715.6 38868.6 38887.2 38917.6 3391.7 3449.6 3992.7 4019.9 4051.4 28698.9 28902.7 29753.3 30375.8 30499.8 3055.3 3236.2 3246.0 3260.3 3287.9 416411.0 428636.0 458767.0 462521.0 462567.7 59079.7 60213.0 60255.1 60271.7 61072.7 58392.7 62839.0 62930.0 63146.7 63359.0 15827.9 16327.9 16351.0 16360.0 16409.8 10490.5 11057.5 12737.2 12800.6 12894.7 19329.1 19586.8 19610.2 20200.4 20201.3 8844.0 8968.9 8975.8 9001.7 9045.2 858.5 876.2 897.1 902.1 924.0 26990.0 30081.8 30354.4 30810.8 30879.2 853.6 1080.4 1113.5 1118.3 1199.0 14895.1 15110.5 15444.0 15591.5 15917.3 619316.0 621936.0 622549.0 630972.8 632738.0 51350.5 51963.5 52106.5 52219.2 52247.7 1373.7 1386.4 1409.3 1431.1 1439.9 26004.7 26849.7 27072.7 27535.4 27588.9 43617.8 43846.0 44226.3 44227.3 44483.0 563059.0 566479.0 569420.0 569899.0 570755.4 23711.3 24442.0 24466.8 24732.0 25013.1 39624.0 39690.8 39877.6 40092.4 40744.8 35025.6 35026.8 35299.3 35483.5 36009.8 34583.5 34769.0 35557.5 35889.5 35933.5 34100.8 34103.1 34319.5 34595.5 34843.0 29985.3 31255.8 31347.8 31445.8 31547.7 20508.4 24052.8 24188.9 24230.0 24803.9 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:41099.44 Median:21418.68 stddev:122965.57 (574.1%) stddev-sans-first:123272.52 02:37:40 INFO - PROCESS | 1641 | Values: 384.5 384.6 400.9 402.4 418.0 16537.9 20135.8 20449.4 21300.1 21420.5 20908.9 21377.1 21416.9 21452.7 21578.9 5385.0 5985.1 6155.5 6204.3 6210.1 5117.1 5707.5 6125.4 6131.4 6161.6 6000.6 6114.6 6115.2 6133.5 6134.2 5736.8 6043.7 6059.7 6084.8 6118.2 5045.8 5938.3 6035.1 6037.3 6046.2 13045.6 13611.3 13656.9 13693.0 13696.3 2604.1 2633.8 2664.2 2686.4 2686.8 9997.1 10178.7 10213.8 10597.9 10611.3 1801.1 1838.6 1868.4 1882.2 1882.8 140412.0 142764.0 148459.3 148672.3 149938.0 12139.2 12238.4 12283.3 12393.0 12400.3 14206.6 14932.0 16280.9 16319.8 16338.3 8679.1 8865.8 9038.9 9058.8 9077.1 7111.3 8769.8 8770.6 8906.5 8916.0 4762.1 4810.5 4828.7 4907.5 5006.7 2036.9 2065.8 2089.0 2090.3 2098.4 1015.0 1045.8 1046.5 1057.3 1082.2 22383.0 22901.9 22960.4 22967.7 22975.0 1005.5 1007.8 1071.0 1093.2 1099.9 11140.2 11680.4 11814.4 11823.1 11838.6 722280.0 730495.0 735831.0 736724.0 746260.0 130609.0 159642.0 160347.4 162506.2 168540.3 876.2 1009.1 1030.7 1037.1 1068.7 20453.1 21275.8 21503.9 21510.0 21693.2 49848.8 60980.8 61399.4 64117.0 64866.4 94079.5 96594.0 97196.0 97930.5 98191.0 7136.6 8314.2 8681.9 8771.8 8862.7 10472.9 13426.9 13480.8 13557.4 13963.1 11424.6 11631.0 11807.4 11840.5 11867.8 10779.2 11074.4 11401.5 11681.2 11809.2 10710.5 11232.8 11548.4 11818.5 12183.5 7222.3 7649.2 7689.2 8556.2 8772.3 8243.5 10811.8 11830.0 11893.2 11933.8 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5414.51 Median:5189.56 stddev:2934.67 (56.5%) stddev-sans-first:2927.11 02:37:40 INFO - PROCESS | 1641 | Values: 1364.0 1427.7 1429.8 1441.0 1458.0 7328.2 7477.8 7730.9 8475.4 8556.6 7918.7 8123.7 8242.2 8304.6 8387.4 2126.5 2300.4 2340.6 2343.5 2347.5 2088.2 2175.2 2309.4 2344.8 2360.4 2263.4 2339.7 2341.7 2343.3 2345.2 2308.8 2309.9 2335.1 2335.6 2336.7 2269.8 2272.8 2323.9 2332.0 2369.3 8344.0 8447.6 8499.0 8505.1 8509.6 5091.2 5207.1 5244.3 5247.1 5247.4 3332.3 3464.5 3493.8 3519.8 3563.2 1151.1 1169.5 1210.3 1231.1 1239.3 8811.7 10746.6 10838.9 10846.7 10923.2 4612.4 4692.0 4718.6 4764.5 4776.9 9400.0 9569.2 9576.1 9586.7 9597.0 5172.0 5219.5 5270.6 5278.8 5286.4 4591.5 4785.3 4788.6 4804.7 4825.7 2889.5 3841.8 3921.6 3953.8 4019.0 1188.6 1223.7 1248.0 1280.7 1290.5 915.1 916.6 918.9 926.1 947.9 4853.4 5424.3 5761.3 5770.7 5961.0 795.2 949.3 976.5 980.7 1027.4 3237.8 3844.2 3877.6 3888.3 3897.6 9777.4 10112.8 10142.8 10162.9 10178.3 8419.3 9893.5 9983.2 10067.2 10136.4 7513.3 7804.5 7845.7 7926.2 7929.9 4549.8 4888.8 5657.2 5804.2 5902.0 9026.5 9168.2 9176.3 9246.5 9439.6 9028.0 9249.9 9276.0 9368.9 9403.8 7014.6 7079.9 7102.3 7203.5 7231.1 7763.6 7838.0 7843.7 7851.3 7870.0 6954.9 7077.6 7109.2 7143.8 7200.1 6963.9 7001.4 7001.9 7084.6 7086.1 5945.8 6341.3 6426.0 6430.1 6430.3 4670.0 4988.9 5008.6 5055.4 5061.6 4865.2 4880.4 4880.9 4971.4 4998.4 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4636.65 Median:5040.12 stddev:1428.94 (28.4%) stddev-sans-first:1396.03 02:37:40 INFO - PROCESS | 1641 | Values: 336.7 349.0 366.6 368.9 372.5 4876.5 5344.4 5537.3 5648.0 5658.0 5505.5 5510.7 5568.8 5585.2 5595.5 3730.8 4148.2 4349.6 4360.5 4366.0 3488.1 3508.0 3987.7 4168.0 4216.7 3873.9 4119.8 4120.0 4168.5 4200.2 3471.9 4012.0 4043.9 4204.1 4217.7 2785.5 3320.3 3419.7 3535.7 3589.3 4235.5 4771.2 4785.7 5045.5 5326.0 4201.4 4761.3 4761.9 4788.9 4808.7 4898.4 5123.9 5277.6 5731.2 5835.3 3687.8 3724.5 3917.4 3943.7 3973.4 5210.7 5634.5 5875.8 6135.4 6203.5 3938.7 4180.6 4218.7 4222.3 4306.6 3747.7 4355.6 4362.1 4404.9 4405.4 4418.3 4773.4 4973.7 5018.4 5034.7 5811.9 5855.4 5884.1 5914.9 5985.6 1211.0 1241.5 1352.6 1429.7 1431.0 906.5 926.9 995.8 1000.4 1019.9 4400.5 4418.8 4942.7 5216.8 5256.6 5485.0 5759.9 5787.0 5826.5 5855.0 3948.4 4509.8 4828.1 5207.3 5311.0 2599.7 2902.8 2940.9 3036.7 3077.0 4759.1 5222.2 5292.2 5963.4 6061.6 4938.7 5348.8 5717.7 5876.6 6227.0 6055.0 6262.7 6282.4 6284.3 6303.7 5589.4 5925.8 5959.5 6083.2 6123.7 5462.3 5898.8 5964.8 6004.8 6015.0 4785.6 5112.9 5340.0 5443.2 6294.4 3912.2 3954.8 3970.9 3986.9 3990.5 4898.7 5988.9 6009.2 6047.3 6067.5 4503.3 4682.9 4754.0 4942.5 5221.2 5077.5 5836.2 5856.5 5859.9 5872.3 5274.5 5388.9 5741.2 5759.5 5770.2 5255.4 5449.5 5819.5 5914.7 5928.8 5768.7 5772.3 5782.5 5800.2 5812.3 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20014.41 Median:4105.59 stddev:27951.90 (680.8%) stddev-sans-first:27992.70 02:37:40 INFO - PROCESS | 1641 | Values: 701.6 704.5 763.0 830.0 839.1 15464.5 15991.2 16005.3 16033.8 16069.4 14603.6 14621.1 14717.3 14737.6 14801.3 586.6 668.0 752.5 852.7 860.8 855.6 871.3 875.4 876.6 879.4 895.8 930.2 941.8 942.5 945.8 835.8 933.0 935.7 939.1 939.4 682.0 685.4 701.7 703.2 703.4 14915.9 15407.4 15433.8 15948.4 15988.9 14373.7 14686.9 14851.0 14918.4 14983.3 11602.5 11775.6 11911.0 12013.4 12049.9 3741.4 3788.5 3833.1 3933.9 3951.9 106138.4 107400.5 108358.4 108770.5 109417.0 11261.9 11272.5 11343.9 11442.8 11561.4 23742.2 23828.6 24124.4 24195.3 24260.8 7660.6 7975.6 7979.6 7980.7 8066.1 4259.3 4294.0 4337.9 4349.8 4396.9 2818.9 3099.6 3610.7 3622.5 3655.9 547.7 594.9 646.7 648.2 651.2 562.9 565.5 576.4 588.8 602.5 9861.5 10009.0 10666.7 11180.3 11518.7 499.0 553.3 588.9 597.8 700.9 5002.1 5123.5 5140.7 5143.3 5159.6 90383.0 93437.0 94966.1 95051.0 95694.0 64360.7 74939.0 76071.0 86101.1 87553.6 72377.6 73250.6 73602.3 74373.3 74914.5 10542.0 11011.3 11073.7 11075.3 11174.3 45313.8 47416.1 47570.5 47752.8 52724.2 67365.0 67487.0 67855.0 69837.7 70688.6 12694.7 12842.6 13111.3 13206.7 13272.8 14150.5 14182.6 14684.3 14974.8 15503.3 11361.2 13464.7 13629.9 13641.7 13998.5 12811.2 13707.6 13716.9 13907.6 14007.6 12754.6 13444.1 13642.8 13728.2 13756.0 9857.9 10862.5 11247.9 11373.4 11529.7 9484.7 9784.3 10421.3 10527.8 10603.2 02:37:40 INFO - PROCESS | 1641 | -------- Summary: end -------- 02:37:40 INFO - PROCESS | 1641 | 02:37:40 INFO - TEST-INFO | 1641: exit 0 02:37:40 INFO - TEST-OK | dromaeo_css | took 1115567ms 02:37:40 INFO - TEST-START | kraken 02:37:40 INFO - operating with platform_type : mac_ 02:37:40 INFO - Initialising browser for kraken test... 02:37:40 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpqMmTpV/profile http://localhost:49241/getInfo.html) 02:37:43 INFO - PROCESS | 1650 | 2017-02-02 02:37:43.527 plugin-container[1652:10173] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 02:37:43 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:43 INFO - PROCESS | 1650 | 2017-02-02 02:37:43.528 plugin-container[1652:10173] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 02:37:43 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:43 INFO - PROCESS | 1650 | 2017-02-02 02:37:43.528 plugin-container[1652:10173] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:43 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:37:43 INFO - PROCESS | 1650 | colorDepth:24 02:37:43 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:37:43 INFO - PROCESS | 1650 | __metrics 02:37:43 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 02:37:44 INFO - TEST-INFO | 1650: exit 0 02:37:44 INFO - Browser initialized. 02:37:44 INFO - Running cycle 1/1 for kraken test... 02:37:44 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpqMmTpV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:46 INFO - PROCESS | 1653 | 2017-02-02 02:37:46.874 plugin-container[1654:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 02:37:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:46 INFO - PROCESS | 1653 | 2017-02-02 02:37:46.874 plugin-container[1654:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 02:37:46 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:37:46 INFO - PROCESS | 1653 | 2017-02-02 02:37:46.874 plugin-container[1654:10307] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:37:56 INFO - PROCESS | 1653 | RSS: Main: 219000832 02:37:56 INFO - PROCESS | 1653 | 02:37:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:38:00 INFO - PROCESS | 1653 | RSS: Main: 233463808 02:38:00 INFO - PROCESS | 1653 | 02:38:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:38:03 INFO - PROCESS | 1653 | RSS: Main: 241258496 02:38:03 INFO - PROCESS | 1653 | 02:38:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:38:07 INFO - PROCESS | 1653 | RSS: Main: 244981760 02:38:07 INFO - PROCESS | 1653 | 02:38:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:38:10 INFO - PROCESS | 1653 | RSS: Main: 246767616 02:38:10 INFO - PROCESS | 1653 | 02:38:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:12 INFO - PROCESS | 1653 | RSS: Main: 247091200 02:38:12 INFO - PROCESS | 1653 | 02:38:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:16 INFO - PROCESS | 1653 | RSS: Main: 250986496 02:38:16 INFO - PROCESS | 1653 | 02:38:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:19 INFO - PROCESS | 1653 | RSS: Main: 251240448 02:38:19 INFO - PROCESS | 1653 | 02:38:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:38:23 INFO - PROCESS | 1653 | RSS: Main: 250347520 02:38:23 INFO - PROCESS | 1653 | 02:38:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:38:25 INFO - PROCESS | 1653 | RSS: Main: 249540608 02:38:25 INFO - PROCESS | 1653 | 02:38:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:38:28 INFO - PROCESS | 1653 | RSS: Main: 249610240 02:38:28 INFO - PROCESS | 1653 | 02:38:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:38:30 INFO - PROCESS | 1653 | RSS: Main: 249610240 02:38:30 INFO - PROCESS | 1653 | 02:38:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:38:34 INFO - PROCESS | 1653 | RSS: Main: 249700352 02:38:34 INFO - PROCESS | 1653 | 02:38:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:38:38 INFO - PROCESS | 1653 | RSS: Main: 249815040 02:38:38 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:38:40 INFO - PROCESS | 1653 | RSS: Main: 251703296 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | __start_tp_report 02:38:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 02:38:40 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 02:38:40 INFO - PROCESS | 1653 | |i|pagename|runs| 02:38:40 INFO - PROCESS | 1653 | |0;ai-astar;106;120;121;119;119;135;176;157;120;115 02:38:40 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;97;144;174;149;115;114;113;127;117 02:38:40 INFO - PROCESS | 1653 | |2;audio-dft;202;202;195;196;198;194;194;194;203;190 02:38:40 INFO - PROCESS | 1653 | |3;audio-fft;81;59;57;83;59;85;82;79;71;58 02:38:40 INFO - PROCESS | 1653 | |4;audio-oscillator;86;71;86;61;84;102;111;106;84;81 02:38:40 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;90;90;91;86;90;96;112;87;88;88 02:38:40 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;96;95;115;154;117;96;94;95;96 02:38:40 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;95;92;91;94;92;92;93;92;91 02:38:40 INFO - PROCESS | 1653 | |8;json-parse-financial;59;61;59;62;63;62;63;65;76;90 02:38:40 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;69;77;70;70;76;75;71;70;73;73 02:38:40 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;88;87;85;85;81;85;87;85;87;85 02:38:40 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;175;151;155;175;152;155;148;142;184;150 02:38:40 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;169;173;173;169;172;171;174;177;173;173 02:38:40 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;69;68;66;83;88;90;82;65;67 02:38:40 INFO - PROCESS | 1653 | __end_tp_report 02:38:40 INFO - PROCESS | 1653 | __start_cc_report 02:38:40 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,933 02:38:40 INFO - PROCESS | 1653 | __end_cc_report 02:38:40 INFO - PROCESS | 1653 | __startTimestamp1486031920302__endTimestamp 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | ------- Summary: start ------- 02:38:40 INFO - PROCESS | 1653 | Number of tests: 14 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:128.80 Median:120.50 stddev:21.55 (17.9%) stddev-sans-first:21.22 02:38:40 INFO - PROCESS | 1653 | Values: 106.0 120.0 121.0 119.0 119.0 135.0 176.0 157.0 120.0 115.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:142.50 stddev:22.71 (15.9%) stddev-sans-first:23.68 02:38:40 INFO - PROCESS | 1653 | Values: 141.0 97.0 144.0 174.0 149.0 115.0 114.0 113.0 127.0 117.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.80 Median:197.00 stddev:4.32 (2.2%) stddev-sans-first:4.15 02:38:40 INFO - PROCESS | 1653 | Values: 202.0 202.0 195.0 196.0 198.0 194.0 194.0 194.0 203.0 190.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:71.40 Median:80.00 stddev:11.91 (14.9%) stddev-sans-first:12.11 02:38:40 INFO - PROCESS | 1653 | Values: 81.0 59.0 57.0 83.0 59.0 85.0 82.0 79.0 71.0 58.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:82.50 stddev:15.45 (18.7%) stddev-sans-first:16.39 02:38:40 INFO - PROCESS | 1653 | Values: 86.0 71.0 86.0 61.0 84.0 102.0 111.0 106.0 84.0 81.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:90.00 stddev:7.61 (8.5%) stddev-sans-first:8.05 02:38:40 INFO - PROCESS | 1653 | Values: 90.0 90.0 91.0 86.0 90.0 96.0 112.0 87.0 88.0 88.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:95.00 stddev:19.17 (20.2%) stddev-sans-first:19.97 02:38:40 INFO - PROCESS | 1653 | Values: 95.0 96.0 95.0 115.0 154.0 117.0 96.0 94.0 95.0 96.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:92.00 stddev:1.26 (1.4%) stddev-sans-first:1.33 02:38:40 INFO - PROCESS | 1653 | Values: 92.0 95.0 92.0 91.0 94.0 92.0 92.0 93.0 92.0 91.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:63.00 stddev:9.72 (15.4%) stddev-sans-first:9.97 02:38:40 INFO - PROCESS | 1653 | Values: 59.0 61.0 59.0 62.0 63.0 62.0 63.0 65.0 76.0 90.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.00 stddev:2.84 (3.9%) stddev-sans-first:2.73 02:38:40 INFO - PROCESS | 1653 | Values: 69.0 77.0 70.0 70.0 76.0 75.0 71.0 70.0 73.0 73.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:86.00 stddev:1.96 (2.3%) stddev-sans-first:1.86 02:38:40 INFO - PROCESS | 1653 | Values: 88.0 87.0 85.0 85.0 81.0 85.0 87.0 85.0 87.0 85.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:155.00 stddev:14.03 (9.1%) stddev-sans-first:13.59 02:38:40 INFO - PROCESS | 1653 | Values: 175.0 151.0 155.0 175.0 152.0 155.0 148.0 142.0 184.0 150.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:173.00 stddev:2.37 (1.4%) stddev-sans-first:2.17 02:38:40 INFO - PROCESS | 1653 | Values: 169.0 173.0 173.0 169.0 172.0 171.0 174.0 177.0 173.0 173.0 02:38:40 INFO - PROCESS | 1653 | 02:38:40 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:75.50 stddev:10.08 (13.4%) stddev-sans-first:10.22 02:38:40 INFO - PROCESS | 1653 | Values: 66.0 69.0 68.0 66.0 83.0 88.0 90.0 82.0 65.0 67.0 02:38:40 INFO - PROCESS | 1653 | -------- Summary: end -------- 02:38:40 INFO - PROCESS | 1653 | 02:38:41 INFO - TEST-INFO | 1653: exit 0 02:38:41 INFO - TEST-OK | kraken | took 60633ms 02:38:41 INFO - SUITE-END | took 1176s 02:38:41 INFO - Completed test suite (00:19:36) 02:38:41 INFO - javascript benchmark 02:38:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7339.355652220314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.0831271400375, 3356.31195604901, 3376.915577110572, 3386.1026827890614, 3407.0, 4952.2819191217295, 4972.955679307341, 5485.259489968853, 5690.487856092951, 5723.11291749779, 5502.477264659861, 5750.654960702351, 5778.0, 5785.27684039495, 5794.884102317962, 5234.8691282717855, 5296.655717378378, 5300.814471493499, 5316.9202461963105, 5329.520343169114, 5317.229001794737, 5330.786768529254, 5350.411454739976, 5358.892822143574, 5366.9463305367035, 5176.404713457944, 5196.454372290912, 5355.946440535603, 5365.195220716904, 5392.865178370533, 5195.714235717053, 5252.553532949704, 5270.5520030797425, 5278.102722537178, 5341.332333458317, 5123.1546794778815, 5219.582433405357, 5239.292695486099, 5246.396664383568, 5312.256284120226, 5715.799947001877, 5716.485516303511, 5870.412958704138, 5885.176075349455, 5895.764169433218, 6097.878042439171, 6146.89355915935, 6199.907001394983, 6219.564630475868, 6228.128062071313, 6276.246850377938, 6284.151639528651, 6309.463695585835, 6321.620702757849, 6328.019157030668, 5847.444492773222, 5859.912101318484, 5872.853178670482, 5897.7935772247765, 5913.965056115206, 5206.55744261742, 6122.184701511125, 6176.567640265138, 6231.813045608639, 6371.490280777527, 4786.353842231324, 6072.666003369769, 6189.721462534197, 6209.192804935329, 6210.534209934273, 6258.123862659231, 6279.717412716347, 6282.968585157137, 6287.528435367366, 6297.307296197415, 5189.792408303626, 5299.993001329735, 6013.368596297326, 6148.631082135086, 6193.535484838565, 6241.0, 6295.087212354183, 6308.179936608303, 6312.147860038836, 6328.6835658216905, 5008.0, 5023.2214006829, 5025.84922452327, 5038.0931432341795, 5040.36995375578, 4421.027373977719, 4441.200583894865, 4492.101579684077, 4492.797824097924, 4539.319102134707, 5534.169874518854, 5855.268091488564, 5905.911411328833, 5955.970220148958, 5960.0165972614905, 6259.280182778948, 6262.091996660459, 6286.937130628727, 6294.118823364733, 6305.873882522324, 5362.7397561573, 5790.247267855236, 5874.441928016831, 5883.205767221456, 5954.821355359346, 4767.594754445841, 5543.722813859291, 5555.111182210827, 5556.583256255797, 5591.273134492571, 6439.8068057958335, 6451.387118223761, 6464.612123272713, 6496.382843629847, 6498.610083395011, 6147.385261473995, 6204.534659900435, 6237.313895471404, 6296.338884417253, 6315.747370105144, 6331.0819931110655, 6386.201724784402, 6420.518461115528, 6434.742610295536, 6442.581232219891, 6200.224971878515, 6255.9374406257175, 6282.905756413749, 6283.088952102011, 6293.181886354744, 5286.233496142961, 6137.346341658241, 6187.102870083902, 6303.46420554258, 6321.367863213642, 6141.907871382022, 6141.938580614136, 6146.969265153645, 6201.875962480815, 6211.440970312694, 5167.354080739908, 5845.947729408747, 6151.723172457339, 6163.167972323679, 6212.844678883064, 5904.173415721716, 5928.8814223716145, 6015.5488338373925, 6044.516438684983, 6046.123312119806, 6083.543734219863, 6098.969505152446, 6108.694565271808, 6109.511239100772, 6162.506999439945, 5049.26785616091, 6029.849253768691, 6038.275406951194, 6045.969770151121, 6092.0], "value": 5705.62137702004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.52858177459217, 461.44626448262096, 483.64452127686167, 502.6958689992554, 522.0993785719634, 33877.0, 38256.043598910044, 40486.0, 40520.594794052085, 40739.388909166315, 41248.79375603118, 41265.79367103168, 41391.0, 41589.79205103978, 41609.95975100624, 7197.640117994106, 9351.392159509644, 9533.0, 9537.28470364722, 9565.713028609152, 8368.790780230482, 11219.102471802276, 11507.42462876857, 11525.308481491138, 11615.535378584846, 11310.208285420023, 11413.0, 11487.885121148807, 11502.539898404095, 11519.9632033117, 11310.264832785842, 11341.886581134166, 11382.886171138307, 11462.48418821155, 11471.59849405271, 11280.2667826591, 11339.659810205707, 11361.261518001385, 11377.0, 11403.600873969453, 38654.420183697264, 38715.61284387148, 38868.61131388678, 38887.22225555473, 38917.61082389168, 3391.6608339165887, 3449.6377880322457, 3992.700547458923, 4019.8995025124314, 4051.3517837145914, 28698.856505717336, 28902.71097289021, 29753.25616859574, 30375.848120759474, 30499.847500762353, 3055.279379598343, 3236.2233064064453, 3245.983770081158, 3260.315333779909, 3287.8684852605866, 416411.0, 428636.0, 458767.0, 462521.0, 462567.6871615654, 59079.70460147672, 60213.0, 60255.09617355743, 60271.69864150651, 61072.69463652653, 58392.7080364604, 62839.0, 62930.0, 63146.68426657837, 63359.049614255826, 15827.920860395854, 16327.93868398574, 16351.018938863706, 16360.018398896103, 16409.83590164107, 10490.475476226158, 11057.512059786644, 12737.172083814523, 12800.61598152057, 12894.67763305925, 19329.130189141524, 19586.824790512615, 19610.215591376185, 20200.393988180083, 20201.29295474681, 8843.955780221186, 8968.910310896808, 8975.820483590292, 9001.684941027033, 9045.231155351737, 858.5492616376328, 876.2289185516831, 897.0894542039835, 902.120432578231, 923.9861402078974, 26990.0, 30081.849590751906, 30354.392912141633, 30810.84594577013, 30879.2280192997, 853.5732133933034, 1080.4165750494644, 1113.5156207049959, 1118.3290025984506, 1198.9760204795855, 14895.106293622417, 15110.454052812152, 15444.0, 15591.532254032398, 15917.269782878155, 619316.0, 621936.0, 622549.0, 630972.8451357714, 632738.0, 51350.486495135316, 51963.48036519662, 52106.47893521092, 52219.21671174935, 52247.73876130595, 1373.6909195430949, 1386.3622733542684, 1409.344654735553, 1431.055503367793, 1439.9280035998158, 26004.73995260099, 26849.731502684725, 27072.72927270702, 27535.44929101447, 27588.89644414281, 43617.78191109024, 43846.0, 44226.33660495031, 44227.33658995053, 44483.0, 563059.0, 566479.0, 569420.0, 569899.0, 570755.438668412, 23711.288661340186, 24442.0, 24466.755332446446, 24732.010719571736, 25013.12454064099, 39624.0, 39690.801545992086, 39877.60428384993, 40092.3986140214, 40744.79627601843, 35025.64974350224, 35026.82486587653, 35299.29401412009, 35483.46774798328, 36009.819950901125, 34583.481247781805, 34769.0, 35557.466638000966, 35889.48773585824, 35933.46099808453, 34100.80647177337, 34103.147421314665, 34319.4852077214, 34595.4810677835, 34843.0, 29985.250368740082, 31255.84372078125, 31347.84326078355, 31445.842770786, 31547.684523154476, 20508.38474845757, 24052.759472405753, 24188.879055604608, 24230.030798767853, 24803.88382522827], "value": 19676.388035831606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.45407521319726, 384.5808069204565, 400.9298372784762, 402.35422147453335, 418.02391416043537, 16537.91731041343, 20135.798642013575, 20449.386518404433, 21300.147994080216, 21420.464488387777, 20908.909109089083, 21377.14491420345, 21416.892915535438, 21452.678209826867, 21578.89210553945, 5385.030694474993, 5985.102234664802, 6155.476784473302, 6204.348543402945, 6210.099535567362, 5117.130087885069, 5707.5148612368, 6125.448709616134, 6131.3562075982045, 6161.599496032766, 6000.579959402844, 6114.571979961404, 6115.205023346959, 6133.478654314388, 6134.17188679527, 5736.799212027579, 6043.697815109271, 6059.66671833049, 6084.817455476343, 6118.204633397652, 5045.798168073273, 5938.287405511366, 6035.094735789623, 6037.335893051761, 6046.2139921809885, 13045.608631741063, 13611.319434028259, 13656.93171534146, 13693.0, 13696.315184240748, 2604.0885690008536, 2633.793209891917, 2664.1607893513483, 2686.3955609987793, 2686.7984901132495, 9997.05028022344, 10178.745531361701, 10213.846792298122, 10597.894021059845, 10611.257211995164, 1801.117452448304, 1838.5955089880206, 1868.392772348992, 1882.2094720217542, 1882.7646544181978, 140412.0, 142764.0, 148459.25770371186, 148672.2566387172, 149938.0, 12139.210951288138, 12238.449269782881, 12283.324417156964, 12393.0, 12400.317982510867, 14206.573802785933, 14932.0, 16280.918595407184, 16319.755203671955, 16338.346466141222, 8679.088695686989, 8865.778355541162, 9038.86441703375, 9058.818823623491, 9077.13767192129, 7111.288871112847, 8769.780755481037, 8770.605322760492, 8906.510141942126, 8916.0, 4762.14281429346, 4810.470848206694, 4828.734419606885, 4907.460179380265, 5006.674566153189, 2036.8981550922394, 2065.7624373196973, 2089.0, 2090.2893016374505, 2098.412444515538, 1015.0052948110892, 1045.8483519889573, 1046.5081411736624, 1057.349729916096, 1082.1721383141903, 22383.0, 22901.885490572775, 22960.4259893504, 22967.6554851674, 22974.966126524345, 1005.5274021210166, 1007.7956841574248, 1070.9946450267855, 1093.2347356850237, 1099.912007039435, 11140.220184587162, 11680.415979201007, 11814.350210738492, 11823.113266504875, 11838.585649502225, 722280.0, 730495.0, 735831.0, 736724.0, 746260.0, 130609.0, 159642.0, 160347.3965260356, 162506.1874690619, 168540.31459685246, 876.2420506262038, 1009.0817356205818, 1030.721705139608, 1037.113268155716, 1068.6847380022912, 20453.079611417223, 21275.787242127382, 21503.892480537495, 21510.0, 21693.240736574146, 49848.75075624599, 60980.780384391175, 61399.386006139364, 64117.0, 64866.351336486034, 94079.52960235429, 96594.0, 97196.0, 97930.51034745066, 98191.0, 7136.571805691675, 8314.168583141638, 8681.91318086811, 8771.824563508566, 8862.734117976472, 10472.947635261775, 13426.865731342952, 13480.79778803299, 13557.389917453935, 13963.092398994033, 11424.600138995096, 11631.0, 11807.409629518317, 11840.467178976794, 11867.821982670093, 10779.245452818228, 11074.446277686244, 11401.486933087865, 11681.240719353214, 11809.173357865211, 10710.518026688662, 11232.831507527557, 11548.364839933884, 11818.468168932246, 12183.451744671334, 7222.313880181375, 7649.235076492307, 7689.154193038649, 8556.229939305493, 8772.254358379354, 8243.505389676639, 10811.837822432828, 11830.0, 11893.226940248856, 11933.820992684943], "value": 10039.43882936832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.9522616708414, 1427.7287315410072, 1429.7640889253273, 1440.9625069949632, 1458.0158393084664, 7328.193898671148, 7477.775666730006, 7730.88403673944, 8475.364347673925, 8556.57217139142, 7918.683252669886, 8123.675052997873, 8242.175782421755, 8304.584770761452, 8387.370947178955, 2126.479012641897, 2300.3559003479013, 2340.555294494049, 2343.5430091132234, 2347.5187586544716, 2088.2273558783195, 2175.1625624134745, 2309.3533810533077, 2344.824138189634, 2360.3745007573007, 2263.366257447909, 2339.742628310893, 2341.7424083350816, 2343.2618725101624, 2345.2495201535517, 2308.792208701225, 2309.930702078932, 2335.054303007285, 2335.626299792042, 2336.719593648756, 2269.807066399358, 2272.8409011369213, 2323.9419014524774, 2331.988340058289, 2369.312899259213, 8343.99872015362, 8447.57762111898, 8498.957505212435, 8505.149485051445, 8509.574521273911, 5091.159958606825, 5207.140821764404, 5244.344456942882, 5247.134222853225, 5247.370315562119, 3332.266901281714, 3464.532288141094, 3493.825308734553, 3519.7712148710507, 3563.233904710474, 1151.1366475143643, 1169.5146514196683, 1210.3222195570509, 1231.0828432817518, 1239.330761388858, 8811.691590794291, 10746.570137194425, 10838.891611083867, 10846.728831779268, 10923.180761442924, 4612.3542704021465, 4692.014676917817, 4718.646101542398, 4764.523547645208, 4776.904461910812, 9400.012998635182, 9569.186619137452, 9576.090271424204, 9586.66446674363, 9597.040295970348, 5171.974140129275, 5219.504147106095, 5270.578353731769, 5278.841634750886, 5286.444923283077, 4591.47198072226, 4785.258284965836, 4788.569028787426, 4804.711717296974, 4825.662203645781, 2889.508783506809, 3841.750286231386, 3921.627445392683, 3953.802309884494, 4018.9799051004557, 1188.5899364719157, 1223.7368965672424, 1247.9500819967282, 1280.6734282758023, 1290.4773566705499, 915.0803442540205, 916.6104405627715, 918.9172974432199, 926.0693003531408, 947.8672985781996, 4853.4418541867435, 5424.321959755031, 5761.337446193741, 5770.740316685759, 5961.0, 795.1531618825932, 949.3307218411004, 976.5215044628081, 980.6861804222581, 1027.4246422003703, 3237.7858303136322, 3844.231153769257, 3877.6122387760997, 3888.319544079775, 3897.590752970897, 9777.364471309342, 10112.797744045078, 10142.797144057078, 10162.949185254027, 10178.287519873538, 8419.326453883674, 9893.45585992763, 9983.201343892471, 10067.194624430174, 10136.391816491034, 7513.2862378074, 7804.492707973964, 7845.686172553148, 7926.24700653437, 7929.920700793034, 4549.79525921341, 4888.828890988798, 5657.179708942098, 5804.216430781959, 5901.967155747641, 9026.503542304974, 9168.220701240203, 9176.311776616914, 9246.537673116452, 9439.575219115293, 9028.00691923901, 9249.86125208109, 9275.953620231856, 9368.859467107863, 9403.764905877408, 7014.649267536501, 7079.893801592877, 7102.254263302279, 7203.459740519377, 7231.132264128339, 7763.61181940912, 7838.0, 7843.725469608765, 7851.254130857559, 7870.0, 6954.930450695429, 7077.646117693992, 7109.182444019004, 7143.82140446493, 7200.063991681048, 6963.895541566779, 7001.36987671112, 7001.894971575329, 7084.574925504487, 7086.149662040588, 5945.821625351246, 6341.334159913327, 6426.0, 6430.067640192239, 6430.324815894263, 4670.0, 4988.8752781180765, 5008.649394542347, 5055.443901170795, 5061.645684802029, 4865.197242455097, 4880.389951256093, 4880.90238195227, 4971.403431588233, 4998.4251811042195], "value": 4413.651607823381, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.73566250493366, 348.9755717099803, 366.6113919245599, 368.85983326335986, 372.5250305860028, 4876.512348765126, 5344.3586769587655, 5537.338798360491, 5648.0, 5658.0295344943925, 5505.476979686923, 5510.6969116698565, 5568.832935011946, 5585.218069470267, 5595.49640532352, 3730.7761534307892, 4148.232576973254, 4349.628623233653, 4360.542143074979, 4365.978170109161, 3488.127968007998, 3507.964920350802, 3987.703996201232, 4168.040691109316, 4216.725912815673, 3873.8572121224165, 4119.814608342616, 4120.0, 4168.458100446937, 4200.243956087903, 3471.94792078119, 4011.97994010031, 4043.939340909889, 4204.129162522679, 4217.746935183884, 2785.4846853332197, 3320.335932813447, 3419.6580341965605, 3535.699465545432, 3589.300086483137, 4235.49174099107, 4771.161159110358, 4785.712857228577, 5045.545900868903, 5325.961437519685, 4201.369794530814, 4761.333413322138, 4761.904761904742, 4788.904221915543, 4808.711477311373, 4898.43250159949, 5123.871903202413, 5277.577793776489, 5731.202604002878, 5835.270591176103, 3687.8284675179325, 3724.48036605408, 3917.4319723639915, 3943.704222183319, 3973.3642617181113, 5210.713410762407, 5634.464490979076, 5875.764969401262, 6135.355787642323, 6203.534734894901, 3938.6849052075763, 4180.602842729997, 4218.6835987300465, 4222.2611043067955, 4306.590873866978, 3747.662710356081, 4355.586219309161, 4362.099204029285, 4404.933925991113, 4405.383246345515, 4418.293073108352, 4773.37946067016, 4973.676711013773, 5018.372703412074, 5034.697918124924, 5811.878664287505, 5855.355910849803, 5884.117382392676, 5914.940850591525, 5985.610935289279, 1210.9939450302693, 1241.4847838147248, 1352.5671785028894, 1429.7426463236718, 1430.978535321971, 906.5150144672568, 926.8784770427858, 995.8107959487675, 1000.4297550396204, 1019.8929112443235, 4400.537943515948, 4418.779061046935, 4942.7130957913305, 5216.765245563959, 5256.608243249312, 5484.972575137179, 5759.8560035999435, 5786.971065144648, 5826.533877289891, 5854.970725146347, 3948.3879998600264, 4509.774511274423, 4828.058528586892, 5207.323048003811, 5310.955282216374, 2599.6750406199226, 2902.753265972374, 2940.867660955262, 3036.711512406318, 3076.9538456923165, 4759.143933865795, 5222.242774797709, 5292.168689750964, 5963.4334738199805, 6061.605995610271, 4938.728369939688, 5348.786048558014, 5717.714114294268, 5876.589618491616, 6226.968865155645, 6055.0, 6262.655553944577, 6282.403171698773, 6284.277308109626, 6303.6532990685955, 5589.413111623221, 5925.822225333247, 5959.523238141025, 6083.239595050619, 6123.663198524124, 5462.317210348707, 5898.793542226002, 5964.7912323068485, 6004.819855404345, 6014.969925150346, 4785.6171506278715, 5112.870535848019, 5339.958708051892, 5443.1695518331035, 6294.370562943669, 3912.237113762789, 3954.822033008579, 3970.940435893406, 3986.900327491836, 3990.4812374391145, 4898.681585696918, 5988.8802223956145, 6009.188759517584, 6047.274327080779, 6067.5123199488, 4503.301988191835, 4682.906341873076, 4754.0016596515115, 4942.505749425029, 5221.190715439113, 5077.492250774893, 5836.212111365081, 5856.472917437451, 5859.94140058611, 5872.324682661549, 5274.496768420981, 5388.865278367915, 5741.1534272037, 5759.51044161251, 5770.2498675171955, 5255.37379208345, 5449.482299181572, 5819.534437244925, 5914.7338369774325, 5928.762849486145, 5768.711564421678, 5772.336181339199, 5782.479576838105, 5800.24596802413, 5812.273465816773], "value": 4199.803498551231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.5860642221088, 704.54556810857, 763.0423818108277, 829.9585020748963, 839.0728245288956, 15464.458743943964, 15991.200439977983, 16005.27976241069, 16033.83966160338, 16069.437569685064, 14603.561893143196, 14621.122732636022, 14717.264136793172, 14737.557873263819, 14801.333939972701, 586.61576667283, 667.9565828221174, 752.4732687119011, 852.6930305090193, 860.7632900952241, 855.6064210463195, 871.2594294849391, 875.4090988582712, 876.6493402638931, 879.4283715584857, 895.8297923394567, 930.2000279759435, 941.7739742461794, 942.5146049784366, 945.8344789661816, 835.7743409279522, 932.9720108396725, 935.6818681648205, 939.0890835889245, 939.426949560764, 681.9590824550493, 685.3831551603547, 701.719312275094, 703.1843062048, 703.3529153179087, 14915.850841491554, 15407.3837046518, 15433.768493472608, 15948.441804536902, 15988.920055399765, 14373.712525749532, 14686.926565367105, 14851.0, 14918.40326386954, 14983.250837458192, 11602.477888495037, 11775.587854425054, 11911.04711623068, 12013.399330033551, 12049.93975030128, 3741.38267185914, 3788.5074940257864, 3833.09922168293, 3933.8623148189963, 3951.881443556698, 106138.40792388043, 107400.46299768529, 108358.37462437991, 108770.45614771875, 109417.0, 11261.94369028166, 11272.492737826817, 11343.886561134448, 11442.771144577062, 11561.397467950137, 23742.16902408442, 23828.64257036146, 24124.39689007789, 24195.274141775775, 24260.757392426203, 7660.578668173304, 7975.601219939096, 7979.561124138112, 7980.680772769024, 8066.072401673882, 4259.297215959394, 4294.0, 4337.869863904088, 4349.847755328612, 4396.934045989313, 2818.9428964138447, 3099.5815564898903, 3610.7291953103622, 3622.5290712207243, 3655.94516082259, 547.6850810783815, 594.910763385487, 646.7219095788856, 648.1671052697261, 651.1502489251573, 562.8817948230835, 565.527784300114, 576.420697199312, 588.7644942023139, 602.4999250621973, 9861.506924653882, 10009.0, 10666.73333166677, 11180.273282236629, 11518.712032199095, 498.9725565093882, 553.3249435688454, 588.9175515427844, 597.7698586044318, 700.9018737376771, 5002.074616196015, 5123.462036486189, 5140.717260550706, 5143.27994080829, 5159.58723302135, 90383.0, 93437.0, 94966.05033949572, 95051.0, 95694.0430595699, 64360.678196608715, 74939.0048796095, 76071.0, 86101.13898861056, 87553.56223218971, 72377.63811180911, 73250.63374683092, 73602.2639773606, 74373.25626743663, 74914.5017099655, 10542.0, 11011.339319640847, 11073.667789965992, 11075.280106793032, 11174.27367221128, 45313.773431132635, 47416.05167896554, 47570.52429475661, 47752.7612361936, 52724.20913686221, 67364.98952515618, 67487.0, 67855.0, 69837.65081174765, 70688.6465567669, 12694.746105078031, 12842.614721558368, 13111.279183169598, 13206.735865282833, 13272.800907986195, 14150.504732334319, 14182.645433864236, 14684.265786710836, 14974.775378369115, 15503.302351393988, 11361.204715669825, 13464.730705386033, 13629.931850340685, 13641.658958525719, 13998.510052148125, 12811.167274127152, 13707.588772336845, 13716.931415342859, 13907.58277251684, 14007.579772606838, 12754.61736147917, 13444.058915876187, 13642.795358069437, 13728.176309421468, 13756.0, 9857.915629280917, 10862.456877156268, 11247.887521124683, 11373.431328433711, 11529.654110376701, 9484.668036618685, 9784.33666276749, 10421.270511064457, 10527.842082368617, 10603.204759642904], "value": 7480.257615713724, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 121.0, 119.0, 119.0, 135.0, 176.0, 157.0, 120.0, 115.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 97.0, 144.0, 174.0, 149.0, 115.0, 114.0, 113.0, 127.0, 117.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 202.0, 195.0, 196.0, 198.0, 194.0, 194.0, 194.0, 203.0, 190.0], "value": 196.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 57.0, 83.0, 59.0, 85.0, 82.0, 79.0, 71.0, 58.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 86.0, 61.0, 84.0, 102.0, 111.0, 106.0, 84.0, 81.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 86.0, 90.0, 96.0, 112.0, 87.0, 88.0, 88.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 115.0, 154.0, 117.0, 96.0, 94.0, 95.0, 96.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 92.0, 91.0, 94.0, 92.0, 92.0, 93.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 62.0, 63.0, 62.0, 63.0, 65.0, 76.0, 90.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 70.0, 70.0, 76.0, 75.0, 71.0, 70.0, 73.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 85.0, 85.0, 81.0, 85.0, 87.0, 85.0, 87.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 151.0, 155.0, 175.0, 152.0, 155.0, 148.0, 142.0, 184.0, 150.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 173.0, 169.0, 172.0, 171.0, 174.0, 177.0, 173.0, 173.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 66.0, 83.0, 88.0, 90.0, 82.0, 65.0, 67.0], "value": 74.4, "unit": "score"}]}]} 02:38:41 INFO - Return code: 0 02:38:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:38:41 ERROR - # TBPL SUCCESS # 02:38:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:41 INFO - 2017-02-02 02:38:41.646 screenresolution[1658:10699] starting screenresolution argv=screenresolution get 02:38:41 INFO - 2017-02-02 02:38:41.649 screenresolution[1658:10699] Display 0: 1600x1200x32@60 02:38:41 INFO - 2017-02-02 02:38:41.655 screenresolution[1659:10702] starting screenresolution argv=screenresolution list 02:38:41 INFO - Available Modes on Display 0 02:38:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:41 INFO - Intel Iris: 02:38:41 INFO - Chipset Model: Intel Iris 02:38:41 INFO - Type: GPU 02:38:41 INFO - Bus: Built-In 02:38:41 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:41 INFO - Vendor: Intel (0x8086) 02:38:41 INFO - Device ID: 0x0a2e 02:38:41 INFO - Revision ID: 0x0009 02:38:41 INFO - Displays: 02:38:41 INFO - Display: 02:38:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:41 INFO - Display Serial Number: 1600x1200 60 02:38:41 INFO - Main Display: Yes 02:38:41 INFO - Mirror: Off 02:38:41 INFO - Online: Yes 02:38:41 INFO - Rotation: Supported 02:38:41 INFO - Return code: 0 02:38:41 INFO - Running post-action listener: _resource_record_post_action 02:38:41 INFO - [mozharness: 2017-02-02 10:38:41.903754Z] Finished run-tests step (success) 02:38:41 INFO - Running post-run listener: _resource_record_post_run 02:38:41 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39020544; Write bytes: 509127680; Read time: 240; Write time: 1614 02:38:41 INFO - TinderboxPrint: CPU usage
54.3% 02:38:41 INFO - TinderboxPrint: I/O read bytes / time
39,020,544 / 240 02:38:41 INFO - TinderboxPrint: I/O write bytes / time
509,127,680 / 1,614 02:38:41 INFO - TinderboxPrint: CPU idle
2,191.0 (45.7%) 02:38:41 INFO - TinderboxPrint: CPU system
966.0 (20.2%) 02:38:41 INFO - TinderboxPrint: CPU user
1,632.8 (34.1%) 02:38:41 INFO - TinderboxPrint: Swap in / out
246,583,296 / 0 02:38:41 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 205373952; Write bytes: 202266624; Read time: 16384; Write time: 437 02:38:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 32747520; Write bytes: 297047040; Read time: 187; Write time: 1148 02:38:42 INFO - Running post-run listener: _upload_blobber_files 02:38:42 INFO - Blob upload gear active. 02:38:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:38:42 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 02:38:42 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:38:42 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:38:42 INFO - (blobuploader) - INFO - Open directory for files ... 02:38:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:38:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:38:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:43 INFO - (blobuploader) - INFO - Done attempting. 02:38:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:38:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:38:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:44 INFO - (blobuploader) - INFO - Done attempting. 02:38:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:38:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:38:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:45 INFO - (blobuploader) - INFO - Done attempting. 02:38:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:38:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:38:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:47 INFO - (blobuploader) - INFO - Done attempting. 02:38:47 INFO - (blobuploader) - INFO - Iteration through files over. 02:38:47 INFO - Return code: 0 02:38:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:38:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:38:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/967b04b5b8e70af1d5fb2912aef2a8f503885f192e22d57310938887fbc79d0039c9b8f9269761c7f2cbc2c2926765f7d7d55cff8975643a9127910ffec950d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cb1e7117c0b86ebc3996bcb008f347cd27d1b3e1da62304c785c38244b9ae5d779da8205f350cb51da75e04b46848ce25c948de800514026459ad8602254fa4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dbe07a7a944d8fd81a1abdff334ad1f2d0f03f56a1a92e0dad8478b036808fdc625ccf05b235b12bcc9965ef09dc1bfe637c5f95c108563c543b513f1b219b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22ca53a2af8f381949cc70915b296c884571348ea8ff0c5b9d6ad4ae966ae9b77d2e930cd9a2afac1439f64f8087cf5cda6257cc1d96c48dbaea3323dd65e940"} 02:38:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:38:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:38:47 INFO - Contents: 02:38:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/967b04b5b8e70af1d5fb2912aef2a8f503885f192e22d57310938887fbc79d0039c9b8f9269761c7f2cbc2c2926765f7d7d55cff8975643a9127910ffec950d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cb1e7117c0b86ebc3996bcb008f347cd27d1b3e1da62304c785c38244b9ae5d779da8205f350cb51da75e04b46848ce25c948de800514026459ad8602254fa4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dbe07a7a944d8fd81a1abdff334ad1f2d0f03f56a1a92e0dad8478b036808fdc625ccf05b235b12bcc9965ef09dc1bfe637c5f95c108563c543b513f1b219b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22ca53a2af8f381949cc70915b296c884571348ea8ff0c5b9d6ad4ae966ae9b77d2e930cd9a2afac1439f64f8087cf5cda6257cc1d96c48dbaea3323dd65e940"} 02:38:47 INFO - Running post-run listener: copy_logs_to_upload_dir 02:38:47 INFO - Copying logs to upload dir... 02:38:47 INFO - mkdir: /builds/slave/test/build/upload/logs 02:38:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.043563 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-02-02 02:38:47.717975) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 02:38:47.722633) ========= 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.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/Mozilla-Aurora/sha512/967b04b5b8e70af1d5fb2912aef2a8f503885f192e22d57310938887fbc79d0039c9b8f9269761c7f2cbc2c2926765f7d7d55cff8975643a9127910ffec950d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cb1e7117c0b86ebc3996bcb008f347cd27d1b3e1da62304c785c38244b9ae5d779da8205f350cb51da75e04b46848ce25c948de800514026459ad8602254fa4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dbe07a7a944d8fd81a1abdff334ad1f2d0f03f56a1a92e0dad8478b036808fdc625ccf05b235b12bcc9965ef09dc1bfe637c5f95c108563c543b513f1b219b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22ca53a2af8f381949cc70915b296c884571348ea8ff0c5b9d6ad4ae966ae9b77d2e930cd9a2afac1439f64f8087cf5cda6257cc1d96c48dbaea3323dd65e940"} build_url:https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011426 build_url: 'https://queue.taskcluster.net/v1/task/dH6OrbBjRaOeKvMBX_AP1A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/967b04b5b8e70af1d5fb2912aef2a8f503885f192e22d57310938887fbc79d0039c9b8f9269761c7f2cbc2c2926765f7d7d55cff8975643a9127910ffec950d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cb1e7117c0b86ebc3996bcb008f347cd27d1b3e1da62304c785c38244b9ae5d779da8205f350cb51da75e04b46848ce25c948de800514026459ad8602254fa4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9dbe07a7a944d8fd81a1abdff334ad1f2d0f03f56a1a92e0dad8478b036808fdc625ccf05b235b12bcc9965ef09dc1bfe637c5f95c108563c543b513f1b219b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22ca53a2af8f381949cc70915b296c884571348ea8ff0c5b9d6ad4ae966ae9b77d2e930cd9a2afac1439f64f8087cf5cda6257cc1d96c48dbaea3323dd65e940"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 02:38:47.757936) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:38:47.758269) ========= 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.VaT4iok1Vq/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.6D3n52Yims/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005220 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 02:38:47.806731) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 02:38:47.807025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 02:38:47.810339) ========= ========= Total master_lag: 0.27 =========